2025-11-01 16:19:04,720 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:19:04,720 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[lz4_compression-COMPRESSION = "lz4"]
2025-11-01 16:19:04,720 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:19:04,720 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 16:19:04,722 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-01 16:19:04,722 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-01 16:19:04,723 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/002516/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:19:04,724 - DEBUG - ya.test - execute: Command pid: 516317
2025-11-01 16:19:05,416 - DEBUG - ya.test - _save_outputs: Command (pid 516317) rc: 0
2025-11-01 16:19:05,416 - DEBUG - ya.test - _save_outputs: Command (pid 516317) elapsed time (sec): 0.6910514831542969
2025-11-01 16:19:05,417 - DEBUG - ya.test - _save_outputs: Command (pid 516317) idrss: 0
2025-11-01 16:19:05,417 - DEBUG - ya.test - _save_outputs: Command (pid 516317) inblock: 0
2025-11-01 16:19:05,417 - DEBUG - ya.test - _save_outputs: Command (pid 516317) isrss: 0
2025-11-01 16:19:05,417 - DEBUG - ya.test - _save_outputs: Command (pid 516317) ixrss: 0
2025-11-01 16:19:05,418 - DEBUG - ya.test - _save_outputs: Command (pid 516317) majflt: 0
2025-11-01 16:19:05,418 - DEBUG - ya.test - _save_outputs: Command (pid 516317) maxrss: 492316
2025-11-01 16:19:05,418 - DEBUG - ya.test - _save_outputs: Command (pid 516317) minflt: 45271
2025-11-01 16:19:05,419 - DEBUG - ya.test - _save_outputs: Command (pid 516317) msgrcv: 0
2025-11-01 16:19:05,419 - DEBUG - ya.test - _save_outputs: Command (pid 516317) msgsnd: 0
2025-11-01 16:19:05,419 - DEBUG - ya.test - _save_outputs: Command (pid 516317) nivcsw: 13
2025-11-01 16:19:05,420 - DEBUG - ya.test - _save_outputs: Command (pid 516317) nsignals: 0
2025-11-01 16:19:05,420 - DEBUG - ya.test - _save_outputs: Command (pid 516317) nswap: 0
2025-11-01 16:19:05,420 - DEBUG - ya.test - _save_outputs: Command (pid 516317) nvcsw: 14
2025-11-01 16:19:05,420 - DEBUG - ya.test - _save_outputs: Command (pid 516317) oublock: 8
2025-11-01 16:19:05,420 - DEBUG - ya.test - _save_outputs: Command (pid 516317) stime: 0.204513
2025-11-01 16:19:05,421 - DEBUG - ya.test - _save_outputs: Command (pid 516317) utime: 0.439803
2025-11-01 16:19:05,421 - DEBUG - ya.test - _save_outputs: Command (pid 516317) wtime: 0.693
2025-11-01 16:19:05,421 - DEBUG - ya.test - _save_outputs: Command (pid 516317) output:
Git info:
    Commit: b759454d1e40b275ba8b2f5964202fc9f08a1c3c
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge b3001557f326a9ac3df9852d3eea5c83a6651a93 into 88f362bbf1115d2653ecf70400035099c5165741

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-cjocww3of4
    Host information: 
        Linux ghrun-cjocww3of4 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-01 16:19:05,421 - DEBUG - ya.test - _save_outputs: Command (pid 516317) errors:

2025-11-01 16:19:05,422 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: b759454d1e40b275ba8b2f5964202fc9f08a1c3c
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge b3001557f326a9ac3df9852d3eea5c83a6651a93 into 88f362bbf1115d2653ecf70400035099c5165741

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-cjocww3of4
    Host information: 
        Linux ghrun-cjocww3of4 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-01 16:19:05,515 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/y72z/002516/ydb/apps/ydbd/ydbd
2025-11-01 16:19:05,516 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27429
2025-11-01 16:19:05,517 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/y72z/002516/r3tmp/kikimr_cluster_tn_rvpc2
2025-11-01 16:19:05,552 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/y72z/002516/ydb/apps/ydbd/ydbd
2025-11-01 16:19:05,553 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25698
2025-11-01 16:19:05,554 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5250
2025-11-01 16:19:05,554 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5251
2025-11-01 16:19:05,555 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30784
2025-11-01 16:19:05,555 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20011
2025-11-01 16:19:05,556 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/y72z/002516/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/kikimr_configs"
2025-11-01 16:19:05,556 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/y72z/002516/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/y72z/002516/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1/logfile_n0qrq4jq.log --grpc-port=25698 --mon-port=5251 --ic-port=27429
2025-11-01 16:19:05,557 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:25698/1, disk_size 68719476736
2025-11-01 16:19:05,557 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/y72z/002516/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/kikimr_configs"
2025-11-01 16:19:05,557 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/y72z/002516/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/y72z/002516/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1/logfile_n0qrq4jq.log --grpc-port=25698 --mon-port=5251 --ic-port=27429
2025-11-01 16:19:05,559 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/y72z/002516/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/y72z/002516/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/y72z/002516/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1/logfile_n0qrq4jq.log', '--grpc-port=25698', '--mon-port=5251', '--ic-port=27429')' in '/home/runner/.ya/build/build_root/y72z/002516/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1' (no wait)
2025-11-01 16:19:05,561 - DEBUG - ya.test - execute: Command pid: 516440
2025-11-01 16:19:05,561 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:25698/1
2025-11-01 16:19:05,563 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-01 16:19:05,563 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-01 16:19:05,564 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/002516/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:19:05,569 - DEBUG - ya.test - execute: Command pid: 516441
2025-11-01 16:19:06,261 - DEBUG - ya.test - _save_outputs: Command (pid 516441) rc: 0
2025-11-01 16:19:06,261 - DEBUG - ya.test - _save_outputs: Command (pid 516441) elapsed time (sec): 0.6905839443206787
2025-11-01 16:19:06,262 - DEBUG - ya.test - _save_outputs: Command (pid 516441) idrss: 0
2025-11-01 16:19:06,262 - DEBUG - ya.test - _save_outputs: Command (pid 516441) inblock: 0
2025-11-01 16:19:06,262 - DEBUG - ya.test - _save_outputs: Command (pid 516441) isrss: 0
2025-11-01 16:19:06,262 - DEBUG - ya.test - _save_outputs: Command (pid 516441) ixrss: 0
2025-11-01 16:19:06,263 - DEBUG - ya.test - _save_outputs: Command (pid 516441) majflt: 0
2025-11-01 16:19:06,263 - DEBUG - ya.test - _save_outputs: Command (pid 516441) maxrss: 501568
2025-11-01 16:19:06,263 - DEBUG - ya.test - _save_outputs: Command (pid 516441) minflt: 45382
2025-11-01 16:19:06,263 - DEBUG - ya.test - _save_outputs: Command (pid 516441) msgrcv: 0
2025-11-01 16:19:06,263 - DEBUG - ya.test - _save_outputs: Command (pid 516441) msgsnd: 0
2025-11-01 16:19:06,264 - DEBUG - ya.test - _save_outputs: Command (pid 516441) nivcsw: 231
2025-11-01 16:19:06,264 - DEBUG - ya.test - _save_outputs: Command (pid 516441) nsignals: 0
2025-11-01 16:19:06,264 - DEBUG - ya.test - _save_outputs: Command (pid 516441) nswap: 0
2025-11-01 16:19:06,264 - DEBUG - ya.test - _save_outputs: Command (pid 516441) nvcsw: 14
2025-11-01 16:19:06,264 - DEBUG - ya.test - _save_outputs: Command (pid 516441) oublock: 8
2025-11-01 16:19:06,264 - DEBUG - ya.test - _save_outputs: Command (pid 516441) stime: 0.17709
2025-11-01 16:19:06,265 - DEBUG - ya.test - _save_outputs: Command (pid 516441) utime: 0.44546399999999997
2025-11-01 16:19:06,265 - DEBUG - ya.test - _save_outputs: Command (pid 516441) wtime: 0.696
2025-11-01 16:19:06,265 - DEBUG - ya.test - _save_outputs: Command (pid 516441) output:
Git info:
    Commit: b759454d1e40b275ba8b2f5964202fc9f08a1c3c
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge b3001557f326a9ac3df9852d3eea5c83a6651a93 into 88f362bbf1115d2653ecf70400035099c5165741

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-cjocww3of4
    Host information: 
        Linux ghrun-cjocww3of4 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-01 16:19:06,266 - DEBUG - ya.test - _save_outputs: Command (pid 516441) errors:

2025-11-01 16:19:06,266 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: b759454d1e40b275ba8b2f5964202fc9f08a1c3c
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge b3001557f326a9ac3df9852d3eea5c83a6651a93 into 88f362bbf1115d2653ecf70400035099c5165741
2025-11-01 16:19:11,127 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/y72z/002516/ydb/apps/ydbd/ydbd
2025-11-01 16:19:11,127 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/y72z/002516/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:25698', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineHostConfig {\n    HostConfigId: 1\n    Drive {\n      Path: "SectorMap:1:64"\n    }\n  }\n}\nCommand {\n  DefineBox {\n    BoxId: 1\n    Host {\n      Key {\n        Fqdn: "localhost"\n        IcPort: 27429\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 16:19:11,128 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-01 16:19:11,129 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-01 16:19:11,129 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/002516/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:25698', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineHostConfig {\n    HostConfigId: 1\n    Drive {\n      Path: "SectorMap:1:64"\n    }\n  }\n}\nCommand {\n  DefineBox {\n    BoxId: 1\n    Host {\n      Key {\n        Fqdn: "localhost"\n        IcPort: 27429\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 16:19:11,142 - DEBUG - ya.test - execute: Command pid: 519404
2025-11-01 16:19:12,134 - DEBUG - ya.test - _save_outputs: Command (pid 519404) rc: 0
2025-11-01 16:19:12,135 - DEBUG - ya.test - _save_outputs: Command (pid 519404) elapsed time (sec): 0.9916536808013916
2025-11-01 16:19:12,135 - DEBUG - ya.test - _save_outputs: Command (pid 519404) idrss: 0
2025-11-01 16:19:12,135 - DEBUG - ya.test - _save_outputs: Command (pid 519404) inblock: 0
2025-11-01 16:19:12,135 - DEBUG - ya.test - _save_outputs: Command (pid 519404) isrss: 0
2025-11-01 16:19:12,136 - DEBUG - ya.test - _save_outputs: Command (pid 519404) ixrss: 0
2025-11-01 16:19:12,136 - DEBUG - ya.test - _save_outputs: Command (pid 519404) majflt: 0
2025-11-01 16:19:12,136 - DEBUG - ya.test - _save_outputs: Command (pid 519404) maxrss: 593388
2025-11-01 16:19:12,136 - DEBUG - ya.test - _save_outputs: Command (pid 519404) minflt: 51581
2025-11-01 16:19:12,136 - DEBUG - ya.test - _save_outputs: Command (pid 519404) msgrcv: 0
2025-11-01 16:19:12,137 - DEBUG - ya.test - _save_outputs: Command (pid 519404) msgsnd: 0
2025-11-01 16:19:12,137 - DEBUG - ya.test - _save_outputs: Command (pid 519404) nivcsw: 171
2025-11-01 16:19:12,137 - DEBUG - ya.test - _save_outputs: Command (pid 519404) nsignals: 0
2025-11-01 16:19:12,137 - DEBUG - ya.test - _save_outputs: Command (pid 519404) nswap: 0
2025-11-01 16:19:12,137 - DEBUG - ya.test - _save_outputs: Command (pid 519404) nvcsw: 645
2025-11-01 16:19:12,138 - DEBUG - ya.test - _save_outputs: Command (pid 519404) oublock: 8
2025-11-01 16:19:12,138 - DEBUG - ya.test - _save_outputs: Command (pid 519404) stime: 0.229934
2025-11-01 16:19:12,138 - DEBUG - ya.test - _save_outputs: Command (pid 519404) utime: 0.515454
2025-11-01 16:19:12,138 - DEBUG - ya.test - _save_outputs: Command (pid 519404) wtime: 1.005
2025-11-01 16:19:12,138 - DEBUG - ya.test - _save_outputs: Command (pid 519404) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 16:19:12,139 - DEBUG - ya.test - _save_outputs: Command (pid 519404) errors:

2025-11-01 16:19:12,140 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/y72z/002516/ydb/apps/ydbd/ydbd
2025-11-01 16:19:12,140 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/y72z/002516/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:25698', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-11-01 16:19:12,141 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-01 16:19:12,142 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-01 16:19:12,142 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/002516/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:25698', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 16:19:12,143 - DEBUG - ya.test - execute: Command pid: 519766
2025-11-01 16:19:13,017 - DEBUG - ya.test - _save_outputs: Command (pid 519766) rc: 0
2025-11-01 16:19:13,018 - DEBUG - ya.test - _save_outputs: Command (pid 519766) elapsed time (sec): 0.8731493949890137
2025-11-01 16:19:13,018 - DEBUG - ya.test - _save_outputs: Command (pid 519766) idrss: 0
2025-11-01 16:19:13,018 - DEBUG - ya.test - _save_outputs: Command (pid 519766) inblock: 0
2025-11-01 16:19:13,019 - DEBUG - ya.test - _save_outputs: Command (pid 519766) isrss: 0
2025-11-01 16:19:13,019 - DEBUG - ya.test - _save_outputs: Command (pid 519766) ixrss: 0
2025-11-01 16:19:13,019 - DEBUG - ya.test - _save_outputs: Command (pid 519766) majflt: 0
2025-11-01 16:19:13,019 - DEBUG - ya.test - _save_outputs: Command (pid 519766) maxrss: 593388
2025-11-01 16:19:13,019 - DEBUG - ya.test - _save_outputs: Command (pid 519766) minflt: 51567
2025-11-01 16:19:13,019 - DEBUG - ya.test - _save_outputs: Command (pid 519766) msgrcv: 0
2025-11-01 16:19:13,020 - DEBUG - ya.test - _save_outputs: Command (pid 519766) msgsnd: 0
2025-11-01 16:19:13,020 - DEBUG - ya.test - _save_outputs: Command (pid 519766) nivcsw: 165
2025-11-01 16:19:13,020 - DEBUG - ya.test - _save_outputs: Command (pid 519766) nsignals: 0
2025-11-01 16:19:13,020 - DEBUG - ya.test - _save_outputs: Command (pid 519766) nswap: 0
2025-11-01 16:19:13,023 - DEBUG - ya.test - _save_outputs: Command (pid 519766) nvcsw: 659
2025-11-01 16:19:13,023 - DEBUG - ya.test - _save_outputs: Command (pid 519766) oublock: 8
2025-11-01 16:19:13,023 - DEBUG - ya.test - _save_outputs: Command (pid 519766) stime: 0.22344499999999998
2025-11-01 16:19:13,023 - DEBUG - ya.test - _save_outputs: Command (pid 519766) utime: 0.5227459999999999
2025-11-01 16:19:13,024 - DEBUG - ya.test - _save_outputs: Command (pid 519766) wtime: 0.874
2025-11-01 16:19:13,024 - DEBUG - ya.test - _save_outputs: Command (pid 519766) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-01 16:19:13,024 - DEBUG - ya.test - _save_outputs: Command (pid 519766) errors:

2025-11-01 16:19:13,025 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 16:19:13,221 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 16:19:13,259 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:19:13,262 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:19:13,262 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:19:13,301 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:19:13,302 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 726d0f3e-6cc6-42cd-a48c-f2266a582114, localhost:25698): creating call state
2025-11-01 16:19:13,303 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 726d0f3e-6cc6-42cd-a48c-f2266a582114, localhost:25698): request = { database: "/Root" }
2025-11-01 16:19:13,310 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 726d0f3e-6cc6-42cd-a48c-f2266a582114, localhost:25698): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-cjocww3of4.auto.internal" port: 25698 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-01 16:19:13,311 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-cjocww3of4.auto.internal:25698, location 1, ssl: False>]>
2025-11-01 16:19:13,312 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:19:13,328 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:25698
2025-11-01 16:19:13,329 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-01 16:19:13,331 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 23dd0edf-6afb-47ec-9f51-bcdbc340ee33, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:13,331 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 23dd0edf-6afb-47ec-9f51-bcdbc340ee33, ghrun-cjocww3of4.auto.internal:25698): request = {  }
2025-11-01 16:19:13,338 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 23dd0edf-6afb-47ec-9f51-bcdbc340ee33, ghrun-cjocww3of4.auto.internal:25698): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" node_id: 1 }
2025-11-01 16:19:13,339 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, a0cd93eb-8bb2-4ede-bfa1-c3c1abd6cbe1, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:13,340 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, a0cd93eb-8bb2-4ede-bfa1-c3c1abd6cbe1, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" }
2025-11-01 16:19:13,342 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, a0cd93eb-8bb2-4ede-bfa1-c3c1abd6cbe1, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:13,349 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:13,350 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 395201c9-5ae8-4105-b684-987bf457f5b8, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:13,351 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 395201c9-5ae8-4105-b684-987bf457f5b8, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:13,352 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 395201c9-5ae8-4105-b684-987bf457f5b8, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:16,106 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:16,106 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-01 16:19:16,107 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:16,107 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 49a3b57b-06f0-4157-adce-2c0c720594ba, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:16,108 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 49a3b57b-06f0-4157-adce-2c0c720594ba, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:16,110 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 49a3b57b-06f0-4157-adce-2c0c720594ba, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:17,007 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:17,007 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:17,008 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8463256e-a47f-4e8c-9faf-244b98bcfe93, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:17,009 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8463256e-a47f-4e8c-9faf-244b98bcfe93, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:17,010 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8463256e-a47f-4e8c-9faf-244b98bcfe93, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:17,997 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:17,998 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:17,998 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 83f890bd-2a73-49f1-bf8d-9f2ecd01a91f, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:17,999 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 83f890bd-2a73-49f1-bf8d-9f2ecd01a91f, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:18,000 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 83f890bd-2a73-49f1-bf8d-9f2ecd01a91f, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:18,830 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:18,831 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:18,831 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d52933e8-ab4f-487b-a6f3-a2311042f9f4, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:18,832 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d52933e8-ab4f-487b-a6f3-a2311042f9f4, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:18,833 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d52933e8-ab4f-487b-a6f3-a2311042f9f4, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:20,198 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:20,198 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 1618432, 'BlobCount': 128}}
2025-11-01 16:19:20,199 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:20,199 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 22094044-79bc-4cf4-85c4-57520f1cfc58, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:20,200 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 22094044-79bc-4cf4-85c4-57520f1cfc58, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:20,201 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 22094044-79bc-4cf4-85c4-57520f1cfc58, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:21,048 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:21,049 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:21,050 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 56a4caf5-5faf-4b65-8fd0-b1d0d6cbd5f4, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:21,050 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 56a4caf5-5faf-4b65-8fd0-b1d0d6cbd5f4, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:21,052 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 56a4caf5-5faf-4b65-8fd0-b1d0d6cbd5f4, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:21,281 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:21,282 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:21,283 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e0425fe2-db3a-40d3-84a3-ab8351fa8266, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:21,283 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e0425fe2-db3a-40d3-84a3-ab8351fa8266, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:21,284 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e0425fe2-db3a-40d3-84a3-ab8351fa8266, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:21,550 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:21,551 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 3236864, 'BlobCount': 256}}
2025-11-01 16:19:21,552 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:21,552 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d591fe4b-bd50-44e4-88af-5ffae0262b80, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:21,553 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d591fe4b-bd50-44e4-88af-5ffae0262b80, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:21,555 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d591fe4b-bd50-44e4-88af-5ffae0262b80, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:22,482 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:22,482 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:22,483 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 08a6aa13-122f-42ca-810f-728e95acf5eb, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:22,484 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 08a6aa13-122f-42ca-810f-728e95acf5eb, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:22,485 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 08a6aa13-122f-42ca-810f-728e95acf5eb, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:22,734 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:22,735 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:22,735 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 157d0ae5-cc99-4060-8922-a0614fb1bd57, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:22,736 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 157d0ae5-cc99-4060-8922-a0614fb1bd57, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:22,738 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 157d0ae5-cc99-4060-8922-a0614fb1bd57, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:23,103 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:23,104 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 4855296, 'BlobCount': 384}}
2025-11-01 16:19:23,105 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:23,106 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8b4c8ab3-cbe0-4694-b1cc-101a5fbd773d, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:23,106 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8b4c8ab3-cbe0-4694-b1cc-101a5fbd773d, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:23,108 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8b4c8ab3-cbe0-4694-b1cc-101a5fbd773d, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:23,986 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:23,987 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:23,987 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 17b6948d-914a-437b-8dbd-309f109e8fe2, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:23,988 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 17b6948d-914a-437b-8dbd-309f109e8fe2, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:23,989 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 17b6948d-914a-437b-8dbd-309f109e8fe2, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:24,253 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:24,253 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:24,254 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8d26f236-0d13-4f9c-946a-740997d9a949, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:24,255 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8d26f236-0d13-4f9c-946a-740997d9a949, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:24,256 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8d26f236-0d13-4f9c-946a-740997d9a949, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:24,596 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:24,597 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 6473728, 'BlobCount': 512}}
2025-11-01 16:19:24,597 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:24,598 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c8bbcc96-742a-4cce-ba79-e8db6a502d61, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:24,599 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c8bbcc96-742a-4cce-ba79-e8db6a502d61, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:24,600 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c8bbcc96-742a-4cce-ba79-e8db6a502d61, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:25,718 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:25,719 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:25,719 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bdafd8c6-ad9d-40cd-94f7-419494c4f20e, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:25,720 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bdafd8c6-ad9d-40cd-94f7-419494c4f20e, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:25,721 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bdafd8c6-ad9d-40cd-94f7-419494c4f20e, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:25,981 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:25,982 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:25,982 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 78adee8d-97b7-4b12-81e0-8e69a51c478f, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:25,983 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 78adee8d-97b7-4b12-81e0-8e69a51c478f, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:25,984 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 78adee8d-97b7-4b12-81e0-8e69a51c478f, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:26,477 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:26,477 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 8092160, 'BlobCount': 640}}
2025-11-01 16:19:26,478 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:26,478 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5166d0cb-c223-4592-8a13-5b08ad66739c, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:26,479 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5166d0cb-c223-4592-8a13-5b08ad66739c, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:26,480 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5166d0cb-c223-4592-8a13-5b08ad66739c, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:27,265 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:27,265 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:27,266 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f44c0b72-698f-4ba3-be48-168c217481f0, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:27,267 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f44c0b72-698f-4ba3-be48-168c217481f0, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:27,268 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f44c0b72-698f-4ba3-be48-168c217481f0, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:27,532 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:27,533 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:27,534 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2a59bc77-554d-4594-914a-1543e0932fc0, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:27,535 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2a59bc77-554d-4594-914a-1543e0932fc0, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:27,538 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2a59bc77-554d-4594-914a-1543e0932fc0, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:28,027 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:28,028 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 9710592, 'BlobCount': 768}}
2025-11-01 16:19:28,029 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:28,030 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f1128758-9db9-4e02-aaf7-c5f475038e94, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:28,031 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f1128758-9db9-4e02-aaf7-c5f475038e94, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:28,032 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f1128758-9db9-4e02-aaf7-c5f475038e94, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:29,029 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:29,030 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:29,031 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 54fe3a83-cfe4-4ae4-b311-ba77f9260b61, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:29,032 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 54fe3a83-cfe4-4ae4-b311-ba77f9260b61, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:29,033 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 54fe3a83-cfe4-4ae4-b311-ba77f9260b61, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:29,303 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:29,304 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:29,304 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1029e785-e4eb-4d06-99b9-ee0f37baebf1, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:29,305 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1029e785-e4eb-4d06-99b9-ee0f37baebf1, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:29,311 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1029e785-e4eb-4d06-99b9-ee0f37baebf1, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:29,908 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:29,909 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 11329024, 'BlobCount': 896}}
2025-11-01 16:19:29,910 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:29,910 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cee8baa5-1ee0-4f21-ab4d-06153140fb60, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:29,911 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cee8baa5-1ee0-4f21-ab4d-06153140fb60, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:29,913 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cee8baa5-1ee0-4f21-ab4d-06153140fb60, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:30,906 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:30,907 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:30,908 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b39f9144-ed58-48da-a63d-e4f169d1f82c, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:30,909 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b39f9144-ed58-48da-a63d-e4f169d1f82c, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:30,910 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b39f9144-ed58-48da-a63d-e4f169d1f82c, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:31,212 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:31,213 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:31,214 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 08024aab-be19-4bc8-bb8b-099cf66fdf18, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:31,214 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 08024aab-be19-4bc8-bb8b-099cf66fdf18, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:31,216 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 08024aab-be19-4bc8-bb8b-099cf66fdf18, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:31,757 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:31,758 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 12947456, 'BlobCount': 1024}}
2025-11-01 16:19:31,758 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:31,759 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2f41cb8e-5603-4e57-b22b-dd5dbf4ec1ec, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:31,760 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2f41cb8e-5603-4e57-b22b-dd5dbf4ec1ec, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:31,762 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2f41cb8e-5603-4e57-b22b-dd5dbf4ec1ec, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:32,693 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:32,694 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:32,695 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 089531ab-feca-4947-84ee-9c49ed00b44e, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:32,696 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 089531ab-feca-4947-84ee-9c49ed00b44e, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:32,703 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 089531ab-feca-4947-84ee-9c49ed00b44e, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:32,966 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:32,967 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:32,967 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0f836930-b0c7-4d51-a41f-dfb881c4c86f, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:32,968 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0f836930-b0c7-4d51-a41f-dfb881c4c86f, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:32,969 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0f836930-b0c7-4d51-a41f-dfb881c4c86f, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:33,532 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:33,533 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 14565888, 'BlobCount': 1152}}
2025-11-01 16:19:33,533 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:33,534 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e872b5ff-13d3-43f2-aac0-2ea19bdee8d5, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:33,535 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e872b5ff-13d3-43f2-aac0-2ea19bdee8d5, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:33,537 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e872b5ff-13d3-43f2-aac0-2ea19bdee8d5, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:34,444 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:34,445 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:34,446 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9909a837-35d3-4c0a-aac4-b1bf0297ee77, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:34,446 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9909a837-35d3-4c0a-aac4-b1bf0297ee77, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:34,448 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9909a837-35d3-4c0a-aac4-b1bf0297ee77, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:34,781 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:34,782 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:34,783 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5c3621a3-f6c8-4254-a8cc-6fc01019d626, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:34,784 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5c3621a3-f6c8-4254-a8cc-6fc01019d626, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:34,786 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5c3621a3-f6c8-4254-a8cc-6fc01019d626, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:35,429 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:35,429 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 16184320, 'BlobCount': 1280}}
2025-11-01 16:19:35,430 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:35,430 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, af079ce9-1804-4c54-9b07-4859bc2bf20e, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:35,431 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, af079ce9-1804-4c54-9b07-4859bc2bf20e, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:35,432 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, af079ce9-1804-4c54-9b07-4859bc2bf20e, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:36,403 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:36,404 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:36,405 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d06c8507-0a85-4a23-a65e-70e3ce31099e, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:36,405 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d06c8507-0a85-4a23-a65e-70e3ce31099e, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:36,407 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d06c8507-0a85-4a23-a65e-70e3ce31099e, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:36,658 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:36,659 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:36,659 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 01871c25-57b9-42d0-abc7-179590f27c87, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:36,660 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 01871c25-57b9-42d0-abc7-179590f27c87, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:36,662 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 01871c25-57b9-42d0-abc7-179590f27c87, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:36,916 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:36,916 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:36,917 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9f08cbef-5c35-4c91-8149-db9b89e1a645, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:36,918 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9f08cbef-5c35-4c91-8149-db9b89e1a645, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:36,919 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9f08cbef-5c35-4c91-8149-db9b89e1a645, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:40,641 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:50,677 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:50,678 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a8b5bae8-6748-4bd3-99f1-0a31b595a025, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:50,679 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a8b5bae8-6748-4bd3-99f1-0a31b595a025, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:50,681 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a8b5bae8-6748-4bd3-99f1-0a31b595a025, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:53,607 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:53,665 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-01 16:19:53,666 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:19:53,666 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3454fd7d-465d-4424-a2cf-60c057a5bdb8, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:19:53,667 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3454fd7d-465d-4424-a2cf-60c057a5bdb8, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:53,669 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3454fd7d-465d-4424-a2cf-60c057a5bdb8, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:57,282 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:07,325 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:07,326 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 16baaa5a-9108-4d4d-b1b6-b62994c405ec, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:07,327 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 16baaa5a-9108-4d4d-b1b6-b62994c405ec, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:07,328 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 16baaa5a-9108-4d4d-b1b6-b62994c405ec, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:10,465 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:10,501 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-01 16:20:10,502 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:10,503 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0337e690-3ef6-4154-b949-b8f9764896bb, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:10,504 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0337e690-3ef6-4154-b949-b8f9764896bb, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:10,505 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0337e690-3ef6-4154-b949-b8f9764896bb, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:10,974 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:10,977 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[lz4_compression-COMPRESSION = "lz4"])
2025-11-01 16:20:10,977 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:10,978 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3abc1ae1-2b11-4b01-bdee-c5e19ceb2821, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:10,979 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3abc1ae1-2b11-4b01-bdee-c5e19ceb2821, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/lz4_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"lz4\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:10,982 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3abc1ae1-2b11-4b01-bdee-c5e19ceb2821, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:14,465 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:14,465 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/lz4_compression created
2025-11-01 16:20:14,466 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:14,466 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6edb15c1-aff8-42c6-ac06-b58d7d039726, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:14,467 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6edb15c1-aff8-42c6-ac06-b58d7d039726, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/lz4_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:14,469 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6edb15c1-aff8-42c6-ac06-b58d7d039726, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:15,728 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:15,729 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:15,730 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1923e09e-6599-4933-9666-7e98dec132bf, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:15,731 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1923e09e-6599-4933-9666-7e98dec132bf, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:15,744 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1923e09e-6599-4933-9666-7e98dec132bf, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:17,524 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:17,525 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:17,525 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 31c2b5d8-f00a-4630-8e76-61192d83fbc0, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:17,526 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 31c2b5d8-f00a-4630-8e76-61192d83fbc0, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:17,527 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 31c2b5d8-f00a-4630-8e76-61192d83fbc0, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:19,026 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:19,027 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:19,027 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3d92d43a-f0bf-4aef-8c7f-e8a2067f5b53, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:19,028 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3d92d43a-f0bf-4aef-8c7f-e8a2067f5b53, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:19,029 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3d92d43a-f0bf-4aef-8c7f-e8a2067f5b53, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:21,437 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:21,438 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 872992, 'BlobCount': 128}}
2025-11-01 16:20:21,438 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:21,439 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a255f4c6-b6b8-4335-9394-ddb6a25d282e, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:21,440 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a255f4c6-b6b8-4335-9394-ddb6a25d282e, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:21,445 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a255f4c6-b6b8-4335-9394-ddb6a25d282e, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:22,625 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:22,625 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:22,626 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 86c9dc3b-1941-4303-b6c8-a6cd6d336c1e, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:22,627 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 86c9dc3b-1941-4303-b6c8-a6cd6d336c1e, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:22,628 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 86c9dc3b-1941-4303-b6c8-a6cd6d336c1e, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:23,086 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:23,087 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:23,088 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 57bb3ce9-fed7-4658-82c7-aad60c3f2d30, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:23,089 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 57bb3ce9-fed7-4658-82c7-aad60c3f2d30, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:23,090 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 57bb3ce9-fed7-4658-82c7-aad60c3f2d30, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:23,614 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:23,615 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 1747088, 'BlobCount': 256}}
2025-11-01 16:20:23,615 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:23,616 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f4aa2ed8-e650-4fa1-8d15-a6a776300de4, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:23,617 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f4aa2ed8-e650-4fa1-8d15-a6a776300de4, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:23,619 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f4aa2ed8-e650-4fa1-8d15-a6a776300de4, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:24,916 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:24,917 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:24,918 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3be9561b-ba9e-46a2-a6bf-1569623eac2f, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:24,919 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3be9561b-ba9e-46a2-a6bf-1569623eac2f, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:24,931 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3be9561b-ba9e-46a2-a6bf-1569623eac2f, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:25,264 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:25,264 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:25,265 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1207e1a3-f119-4d00-9070-0b073b97f5f6, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:25,266 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1207e1a3-f119-4d00-9070-0b073b97f5f6, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:25,267 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1207e1a3-f119-4d00-9070-0b073b97f5f6, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:25,629 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:25,630 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 2620992, 'BlobCount': 384}}
2025-11-01 16:20:25,631 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:25,631 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aea1a8d6-07d3-4b15-adc9-95851487ffe6, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:25,632 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aea1a8d6-07d3-4b15-adc9-95851487ffe6, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:25,634 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aea1a8d6-07d3-4b15-adc9-95851487ffe6, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:26,650 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:26,651 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:26,651 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, caa28888-cb91-42c5-b936-7086da6129d7, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:26,652 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, caa28888-cb91-42c5-b936-7086da6129d7, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:26,653 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, caa28888-cb91-42c5-b936-7086da6129d7, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:26,977 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:26,977 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:26,978 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 21f12459-29f1-4f6e-a8c9-b7f168e00263, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:26,979 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 21f12459-29f1-4f6e-a8c9-b7f168e00263, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:26,980 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 21f12459-29f1-4f6e-a8c9-b7f168e00263, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:27,509 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:27,509 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 3495088, 'BlobCount': 512}}
2025-11-01 16:20:27,510 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:27,510 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7bbaba22-f9bd-4107-91b6-d3d617a389a6, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:27,511 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7bbaba22-f9bd-4107-91b6-d3d617a389a6, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:27,513 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7bbaba22-f9bd-4107-91b6-d3d617a389a6, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:28,507 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:28,508 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:28,509 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 73a23613-fb2e-47b1-aa6d-37a7ecee4c92, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:28,510 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 73a23613-fb2e-47b1-aa6d-37a7ecee4c92, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:28,511 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 73a23613-fb2e-47b1-aa6d-37a7ecee4c92, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:28,756 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:28,757 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:28,758 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bc36af95-3ff1-469d-88c9-05c5aba66b2f, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:28,759 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bc36af95-3ff1-469d-88c9-05c5aba66b2f, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:28,762 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bc36af95-3ff1-469d-88c9-05c5aba66b2f, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:29,147 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:29,148 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 4368976, 'BlobCount': 640}}
2025-11-01 16:20:29,148 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:29,149 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 42204b20-4206-429c-b13d-152cc8365c25, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:29,150 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 42204b20-4206-429c-b13d-152cc8365c25, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:29,151 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 42204b20-4206-429c-b13d-152cc8365c25, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:30,130 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:30,131 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:30,131 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 17c04a89-6c39-42be-a85d-48085bde3838, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:30,132 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 17c04a89-6c39-42be-a85d-48085bde3838, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:30,134 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 17c04a89-6c39-42be-a85d-48085bde3838, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:30,408 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:30,409 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:30,410 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1789536d-ff95-4852-99f7-d887bb8ff22e, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:30,410 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1789536d-ff95-4852-99f7-d887bb8ff22e, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:30,412 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1789536d-ff95-4852-99f7-d887bb8ff22e, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:30,861 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:30,862 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 5242960, 'BlobCount': 768}}
2025-11-01 16:20:30,862 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:30,863 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, db8d45d9-5e69-43e1-b169-f31aea5ef25a, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:30,864 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, db8d45d9-5e69-43e1-b169-f31aea5ef25a, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:30,866 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, db8d45d9-5e69-43e1-b169-f31aea5ef25a, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:31,974 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:31,975 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:31,976 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2691f4c7-4c84-4682-ad13-80a01fc36eca, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:31,977 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2691f4c7-4c84-4682-ad13-80a01fc36eca, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:31,979 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2691f4c7-4c84-4682-ad13-80a01fc36eca, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:32,297 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:32,298 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:32,298 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4b41f2cc-bda8-4c29-86c1-07780727017b, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:32,299 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4b41f2cc-bda8-4c29-86c1-07780727017b, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:32,301 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4b41f2cc-bda8-4c29-86c1-07780727017b, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:32,873 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:32,874 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 6117040, 'BlobCount': 896}}
2025-11-01 16:20:32,874 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:32,875 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c24e6b84-539c-4031-aef8-63b4cd44cca5, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:32,876 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c24e6b84-539c-4031-aef8-63b4cd44cca5, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:32,879 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c24e6b84-539c-4031-aef8-63b4cd44cca5, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:33,912 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:33,913 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:33,913 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f72adb71-2b07-4216-ab9f-e479337e2755, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:33,914 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f72adb71-2b07-4216-ab9f-e479337e2755, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:33,915 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f72adb71-2b07-4216-ab9f-e479337e2755, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:34,170 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:34,171 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:34,171 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f95206cd-6e6f-4fd9-8c8a-c17668fc5d35, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:34,172 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f95206cd-6e6f-4fd9-8c8a-c17668fc5d35, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:34,174 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f95206cd-6e6f-4fd9-8c8a-c17668fc5d35, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:34,812 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:34,813 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 6991184, 'BlobCount': 1024}}
2025-11-01 16:20:34,813 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:34,814 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d1c629d4-90ba-4c2a-9a4f-4f22a4456d33, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:34,815 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d1c629d4-90ba-4c2a-9a4f-4f22a4456d33, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:34,817 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d1c629d4-90ba-4c2a-9a4f-4f22a4456d33, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:36,017 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:36,017 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:36,018 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8a918952-12cf-430e-90a8-3ab01690694c, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:36,019 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8a918952-12cf-430e-90a8-3ab01690694c, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:36,021 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8a918952-12cf-430e-90a8-3ab01690694c, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:36,325 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:36,326 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:36,327 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bb3cb17d-f7e5-4152-b7c7-388d9df0365a, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:36,328 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bb3cb17d-f7e5-4152-b7c7-388d9df0365a, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:36,329 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bb3cb17d-f7e5-4152-b7c7-388d9df0365a, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:36,977 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:36,978 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 7865088, 'BlobCount': 1152}}
2025-11-01 16:20:36,978 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:36,979 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aa9c8f52-5461-4492-a039-c663881bffe1, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:36,980 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aa9c8f52-5461-4492-a039-c663881bffe1, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:36,983 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aa9c8f52-5461-4492-a039-c663881bffe1, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:38,173 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:38,174 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:38,175 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6d60ec6f-b363-462c-ac56-4db1f4efd481, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:38,176 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6d60ec6f-b363-462c-ac56-4db1f4efd481, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:38,179 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6d60ec6f-b363-462c-ac56-4db1f4efd481, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:38,467 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:38,468 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:38,469 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 242f8a96-79d4-4d7d-9c69-09b4451b6b3e, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:38,469 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 242f8a96-79d4-4d7d-9c69-09b4451b6b3e, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:38,471 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 242f8a96-79d4-4d7d-9c69-09b4451b6b3e, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:39,209 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:39,210 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 8739264, 'BlobCount': 1280}}
2025-11-01 16:20:39,210 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:39,211 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f8c4b1fb-c75f-4fc7-996e-5c85ad213ead, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:39,211 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f8c4b1fb-c75f-4fc7-996e-5c85ad213ead, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/lz4_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:39,213 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f8c4b1fb-c75f-4fc7-996e-5c85ad213ead, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:40,978 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:40,979 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:40,980 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9e09b56a-b959-40e4-8253-56216600a9b0, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:40,981 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9e09b56a-b959-40e4-8253-56216600a9b0, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:40,982 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9e09b56a-b959-40e4-8253-56216600a9b0, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:41,326 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:41,327 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:41,328 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 53cc4e92-69b7-46c2-87d0-8eef1d5004f1, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:41,329 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 53cc4e92-69b7-46c2-87d0-8eef1d5004f1, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:41,337 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 53cc4e92-69b7-46c2-87d0-8eef1d5004f1, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:41,726 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:41,727 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:41,727 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 545e3884-8636-469c-a103-582f4afce8c0, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:41,729 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 545e3884-8636-469c-a103-582f4afce8c0, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:41,731 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 545e3884-8636-469c-a103-582f4afce8c0, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:46,142 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:56,185 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:20:56,186 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 424bc160-4dfd-40da-8544-9e5096d08f77, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:20:56,187 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 424bc160-4dfd-40da-8544-9e5096d08f77, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:56,188 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 424bc160-4dfd-40da-8544-9e5096d08f77, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:00,682 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:21:00,714 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4369632)
2025-11-01 16:21:00,715 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:21:00,716 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ab76a06e-ddcd-454a-a3fc-a0260b2d7862, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:21:00,716 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ab76a06e-ddcd-454a-a3fc-a0260b2d7862, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:00,718 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ab76a06e-ddcd-454a-a3fc-a0260b2d7862, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:01,067 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:21:01,068 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:21:01,069 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 177dbe77-06bf-49d4-946e-dd9b19d93e78, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:21:01,069 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 177dbe77-06bf-49d4-946e-dd9b19d93e78, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:01,070 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 177dbe77-06bf-49d4-946e-dd9b19d93e78, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:04,976 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:21:07,284 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:21:07,285 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:21:07,302 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:21:07,303 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 7d582bcb-0362-4ad2-9090-98e69ba93d5d, localhost:25698): creating call state
2025-11-01 16:21:07,304 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 7d582bcb-0362-4ad2-9090-98e69ba93d5d, localhost:25698): request = { database: "/Root" }
2025-11-01 16:21:07,316 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 7d582bcb-0362-4ad2-9090-98e69ba93d5d, localhost:25698): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-cjocww3of4.auto.internal" port: 25698 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-01 16:21:07,317 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-cjocww3of4.auto.internal:25698, location 1, ssl: False>]>
2025-11-01 16:21:07,317 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:25698
2025-11-01 16:21:15,009 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:21:15,010 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4b53fbab-6c3b-4f51-8b2e-d66353ee6388, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:21:15,012 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4b53fbab-6c3b-4f51-8b2e-d66353ee6388, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:15,013 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4b53fbab-6c3b-4f51-8b2e-d66353ee6388, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:18,229 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:21:28,253 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:21:28,253 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d55a537b-6317-458a-9ffa-066c2091d91b, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:21:28,254 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d55a537b-6317-458a-9ffa-066c2091d91b, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:28,255 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d55a537b-6317-458a-9ffa-066c2091d91b, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:28,938 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:21:38,945 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:21:38,946 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 83719b1d-bfc9-4bea-b829-484fc3d9e4ad, ghrun-cjocww3of4.auto.internal:25698): creating call state
2025-11-01 16:21:38,946 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 83719b1d-bfc9-4bea-b829-484fc3d9e4ad, ghrun-cjocww3of4.auto.internal:25698): request = { session_id: "ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:38,948 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 83719b1d-bfc9-4bea-b829-484fc3d9e4ad, ghrun-cjocww3of4.auto.internal:25698): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:39,565 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE1ZjBjOGEtYmE2YWMwYTAtZWMyNmE4ZjctMWQ1MTE5NTM=
2025-11-01 16:21:39,568 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4258592)
2025-11-01 16:21:39,568 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/lz4_compression` 8092160 / 4258592: 1.900196121159294
2025-11-01 16:21:39,570 - INFO - ya.test - pytest_runtest_teardown: Test teardown
