2025-12-23 17:49:26,383 - INFO - ya.test.2335951  - pytest_runtest_setup: ####################################################################################################
2025-12-23 17:49:26,384 - INFO - ya.test.2335951  - pytest_runtest_setup: test_all_supported_compression[zstd_4_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 4]
2025-12-23 17:49:26,384 - INFO - ya.test.2335951  - pytest_runtest_setup: ####################################################################################################
2025-12-23 17:49:26,384 - INFO - ya.test.2335951  - pytest_runtest_setup: Test setup
2025-12-23 17:49:26,387 - DEBUG - ya.test.2335951  - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-23 17:49:26,388 - DEBUG - ya.test.2335951  - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-23 17:49:26,388 - DEBUG - ya.test.2335951  - execute: Executing '['/home/runner/.ya/build/build_root/w2fb/001d7e/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-23 17:49:26,390 - DEBUG - ya.test.2335951  - execute: Command pid: 2336888
2025-12-23 17:49:27,130 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) rc: 0
2025-12-23 17:49:27,131 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) elapsed time (sec): 0.7386963367462158
2025-12-23 17:49:27,131 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) idrss: 0
2025-12-23 17:49:27,131 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) inblock: 0
2025-12-23 17:49:27,131 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) isrss: 0
2025-12-23 17:49:27,132 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) ixrss: 0
2025-12-23 17:49:27,132 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) majflt: 0
2025-12-23 17:49:27,132 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) maxrss: 504324
2025-12-23 17:49:27,132 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) minflt: 45870
2025-12-23 17:49:27,133 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) msgrcv: 0
2025-12-23 17:49:27,133 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) msgsnd: 0
2025-12-23 17:49:27,133 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) nivcsw: 91
2025-12-23 17:49:27,134 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) nsignals: 0
2025-12-23 17:49:27,134 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) nswap: 0
2025-12-23 17:49:27,134 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) nvcsw: 14
2025-12-23 17:49:27,135 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) oublock: 8
2025-12-23 17:49:27,135 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) stime: 0.196038
2025-12-23 17:49:27,135 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) utime: 0.476309
2025-12-23 17:49:27,135 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) wtime: 0.741
2025-12-23 17:49:27,135 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) output:
Git info:
    Commit: 36060cc90e9313d79d78f7e01e8251dfc0b9a307
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 11f4dd79bc2e09e94b7f6457af93c1ee041776e2 into 7a46c6293674d1de550a9922546b7e0022176cfa
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-yh5r7pxf7y    Host information: 
        Linux ghrun-yh5r7pxf7y 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-12-23 17:49:27,136 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336888) errors:

2025-12-23 17:49:27,136 - INFO - __tests__.base.2335951  - _setup_ydb: Git info:
    Commit: 36060cc90e9313d79d78f7e01e8251dfc0b9a307
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 11f4dd79bc2e09e94b7f6457af93c1ee041776e2 into 7a46c6293674d1de550a9922546b7e0022176cfa
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-yh5r7pxf7y    Host information: 
        Linux ghrun-yh5r7pxf7y 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-12-23 17:49:27,241 - DEBUG - ya.test.2335951  - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2fb/001d7e/ydb/apps/ydbd/ydbd
2025-12-23 17:49:27,243 - DEBUG - library.python.filelock.2335951  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4972
2025-12-23 17:49:27,245 - DEBUG - ydb.tests.library.harness.kikimr_runner.2335951  - start: Working directory: /home/runner/.ya/build/build_root/w2fb/001d7e/r3tmp/kikimr_cluster_m_038uy4
2025-12-23 17:49:27,301 - DEBUG - ya.test.2335951  - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2fb/001d7e/ydb/apps/ydbd/ydbd
2025-12-23 17:49:27,301 - DEBUG - library.python.filelock.2335951  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/64039
2025-12-23 17:49:27,302 - DEBUG - library.python.filelock.2335951  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27442
2025-12-23 17:49:27,303 - DEBUG - library.python.filelock.2335951  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/64339
2025-12-23 17:49:27,303 - DEBUG - library.python.filelock.2335951  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17443
2025-12-23 17:49:27,304 - INFO - ydb.tests.library.harness.kikimr_runner.2335951  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2fb/001d7e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/kikimr_configs"
2025-12-23 17:49:27,305 - INFO - ydb.tests.library.harness.kikimr_runner.2335951  - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2fb/001d7e/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/w2fb/001d7e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/node_1/logfile_k7fys_2c.log --grpc-port=64039 --mon-port=27442 --ic-port=4972
2025-12-23 17:49:27,305 - DEBUG - ydb.tests.library.harness.kikimr_runner.2335951  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:64039/1, disk_size 68719476736
2025-12-23 17:49:27,306 - INFO - ydb.tests.library.harness.kikimr_runner.2335951  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2fb/001d7e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/kikimr_configs"
2025-12-23 17:49:27,306 - INFO - ydb.tests.library.harness.kikimr_runner.2335951  - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2fb/001d7e/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/w2fb/001d7e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/node_1/logfile_k7fys_2c.log --grpc-port=64039 --mon-port=27442 --ic-port=4972
2025-12-23 17:49:27,308 - DEBUG - ya.test.2335951  - execute: Executing '('/home/runner/.ya/build/build_root/w2fb/001d7e/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/w2fb/001d7e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/w2fb/001d7e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/node_1/logfile_k7fys_2c.log', '--grpc-port=64039', '--mon-port=27442', '--ic-port=4972')' in '/home/runner/.ya/build/build_root/w2fb/001d7e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/node_1' (no wait)
2025-12-23 17:49:27,311 - DEBUG - ya.test.2335951  - execute: Command pid: 2336982
2025-12-23 17:49:27,312 - INFO - ydb.tests.library.harness.kikimr_runner.2335951  - start: Started node localhost:64039/1
2025-12-23 17:49:27,314 - DEBUG - ya.test.2335951  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-23 17:49:27,315 - DEBUG - ya.test.2335951  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-23 17:49:27,315 - DEBUG - ya.test.2335951  - execute: Executing '['/home/runner/.ya/build/build_root/w2fb/001d7e/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-23 17:49:27,317 - DEBUG - ya.test.2335951  - execute: Command pid: 2336984
2025-12-23 17:49:28,112 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) rc: 0
2025-12-23 17:49:28,113 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) elapsed time (sec): 0.7885797023773193
2025-12-23 17:49:28,113 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) idrss: 0
2025-12-23 17:49:28,114 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) inblock: 0
2025-12-23 17:49:28,114 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) isrss: 0
2025-12-23 17:49:28,114 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) ixrss: 0
2025-12-23 17:49:28,115 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) majflt: 0
2025-12-23 17:49:28,115 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) maxrss: 513232
2025-12-23 17:49:28,115 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) minflt: 45849
2025-12-23 17:49:28,116 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) msgrcv: 0
2025-12-23 17:49:28,116 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) msgsnd: 0
2025-12-23 17:49:28,123 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) nivcsw: 171
2025-12-23 17:49:28,123 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) nsignals: 0
2025-12-23 17:49:28,124 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) nswap: 0
2025-12-23 17:49:28,124 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) nvcsw: 14
2025-12-23 17:49:28,124 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) oublock: 8
2025-12-23 17:49:28,125 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) stime: 0.18301399999999998
2025-12-23 17:49:28,125 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) utime: 0.493689
2025-12-23 17:49:28,125 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) wtime: 0.79
2025-12-23 17:49:28,126 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) output:
Git info:
    Commit: 36060cc90e9313d79d78f7e01e8251dfc0b9a307
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 11f4dd79bc2e09e94b7f6457af93c1ee041776e2 into 7a46c6293674d1de550a9922546b7e0022176cfa
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-yh5r7pxf7y    Host information: 
        Linux ghrun-yh5r7pxf7y 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-12-23 17:49:28,126 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2336984) errors:

2025-12-23 17:49:28,127 - INFO - ydb.tests.library.harness.kikimr_runner.2335951  - start: Node 1 version:
Git info:
    Commit: 36060cc90e9313d79d78f7e01e8251dfc0b9a307
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 11f4dd79bc2e09e94b7f6457af93c1ee041776e2 into 7a46c6293674d1de550a9922546b7e0022176cfa
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-yh5r7pxf7y    Host information: 
        Linux ghrun-yh5r7pxf7y 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-23 17:49:33,888 - DEBUG - ya.test.2335951  - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2fb/001d7e/ydb/apps/ydbd/ydbd
2025-12-23 17:49:33,889 - DEBUG - ydb.tests.library.harness.kikimr_runner.2335951  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2fb/001d7e/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:64039', '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: 4972\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-23 17:49:33,890 - DEBUG - ya.test.2335951  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-23 17:49:33,891 - DEBUG - ya.test.2335951  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-23 17:49:33,891 - DEBUG - ya.test.2335951  - execute: Executing '['/home/runner/.ya/build/build_root/w2fb/001d7e/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:64039', '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: 4972\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-23 17:49:33,897 - DEBUG - ya.test.2335951  - execute: Command pid: 2339433
2025-12-23 17:49:35,224 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) rc: 0
2025-12-23 17:49:35,225 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) elapsed time (sec): 1.3222496509552002
2025-12-23 17:49:35,226 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) idrss: 0
2025-12-23 17:49:35,226 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) inblock: 0
2025-12-23 17:49:35,226 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) isrss: 0
2025-12-23 17:49:35,227 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) ixrss: 0
2025-12-23 17:49:35,227 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) majflt: 0
2025-12-23 17:49:35,227 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) maxrss: 594408
2025-12-23 17:49:35,228 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) minflt: 52285
2025-12-23 17:49:35,228 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) msgrcv: 0
2025-12-23 17:49:35,228 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) msgsnd: 0
2025-12-23 17:49:35,229 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) nivcsw: 224
2025-12-23 17:49:35,229 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) nsignals: 0
2025-12-23 17:49:35,229 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) nswap: 0
2025-12-23 17:49:35,230 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) nvcsw: 434
2025-12-23 17:49:35,230 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) oublock: 8
2025-12-23 17:49:35,230 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) stime: 0.276138
2025-12-23 17:49:35,231 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) utime: 0.633431
2025-12-23 17:49:35,231 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) wtime: 1.329
2025-12-23 17:49:35,231 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-23 17:49:35,232 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2339433) errors:

2025-12-23 17:49:35,252 - DEBUG - ya.test.2335951  - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2fb/001d7e/ydb/apps/ydbd/ydbd
2025-12-23 17:49:35,252 - DEBUG - ydb.tests.library.harness.kikimr_runner.2335951  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2fb/001d7e/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:64039', '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-12-23 17:49:35,254 - DEBUG - ya.test.2335951  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-23 17:49:35,255 - DEBUG - ya.test.2335951  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-23 17:49:35,255 - DEBUG - ya.test.2335951  - execute: Executing '['/home/runner/.ya/build/build_root/w2fb/001d7e/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:64039', '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-12-23 17:49:35,280 - DEBUG - ya.test.2335951  - execute: Command pid: 2340161
2025-12-23 17:49:36,596 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) rc: 0
2025-12-23 17:49:36,597 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) elapsed time (sec): 1.31083345413208
2025-12-23 17:49:36,597 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) idrss: 0
2025-12-23 17:49:36,597 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) inblock: 0
2025-12-23 17:49:36,598 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) isrss: 0
2025-12-23 17:49:36,598 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) ixrss: 0
2025-12-23 17:49:36,598 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) majflt: 0
2025-12-23 17:49:36,598 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) maxrss: 594644
2025-12-23 17:49:36,599 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) minflt: 52284
2025-12-23 17:49:36,599 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) msgrcv: 0
2025-12-23 17:49:36,599 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) msgsnd: 0
2025-12-23 17:49:36,600 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) nivcsw: 237
2025-12-23 17:49:36,600 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) nsignals: 0
2025-12-23 17:49:36,611 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) nswap: 0
2025-12-23 17:49:36,611 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) nvcsw: 562
2025-12-23 17:49:36,611 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) oublock: 8
2025-12-23 17:49:36,612 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) stime: 0.28817699999999996
2025-12-23 17:49:36,612 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) utime: 0.629934
2025-12-23 17:49:36,612 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) wtime: 1.336
2025-12-23 17:49:36,613 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-23 17:49:36,613 - DEBUG - ya.test.2335951  - _save_outputs: Command (pid 2340161) errors:

2025-12-23 17:49:36,614 - INFO - ydb.tests.library.harness.kikimr_runner.2335951  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-23 17:49:36,854 - INFO - ydb.tests.library.harness.kikimr_runner.2335951  - __run: Cluster started and initialized
2025-12-23 17:49:36,910 - DEBUG - ydb.connection.2335951  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 17:49:36,923 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2335951  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 17:49:36,924 - DEBUG - ydb.connection.2335951  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 17:49:37,000 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2335951  - context_resolve: Resolving endpoints for database /Root
2025-12-23 17:49:37,001 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ListEndpoints, 6d4778a2-f382-4e74-a05d-02c4bde6147e, localhost:64039): creating call state
2025-12-23 17:49:37,002 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ListEndpoints, 6d4778a2-f382-4e74-a05d-02c4bde6147e, localhost:64039): request = { database: "/Root" }
2025-12-23 17:49:37,014 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ListEndpoints, 6d4778a2-f382-4e74-a05d-02c4bde6147e, localhost:64039): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-yh5r7pxf7y.auto.internal" port: 64039 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-12-23 17:49:37,016 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2335951  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-yh5r7pxf7y.auto.internal:64039, location 1, ssl: False>]>
2025-12-23 17:49:37,016 - DEBUG - ydb.connection.2335951  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 17:49:37,061 - DEBUG - ydb.connection.2335951  - close: Closing channel for endpoint localhost:64039
2025-12-23 17:49:37,062 - DEBUG - ydb.query.pool.2335951  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-23 17:49:37,063 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(CreateSession, e8fd4c69-fb08-454a-8399-85aba270293b, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:37,064 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(CreateSession, e8fd4c69-fb08-454a-8399-85aba270293b, ghrun-yh5r7pxf7y.auto.internal:64039): request = {  }
2025-12-23 17:49:37,077 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(CreateSession, e8fd4c69-fb08-454a-8399-85aba270293b, ghrun-yh5r7pxf7y.auto.internal:64039): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" node_id: 1 }
2025-12-23 17:49:37,078 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(AttachSession, eb90b19c-1b7b-4a4e-a39a-a81fabeb2008, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:37,079 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(AttachSession, eb90b19c-1b7b-4a4e-a39a-a81fabeb2008, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" }
2025-12-23 17:49:37,083 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(AttachSession, eb90b19c-1b7b-4a4e-a39a-a81fabeb2008, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:37,103 - DEBUG - ydb.query.pool.2335951  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:37,105 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 45fcc70e-2c9f-4d4c-86be-d0d37bc483ed, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:37,106 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 45fcc70e-2c9f-4d4c-86be-d0d37bc483ed, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:37,107 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 45fcc70e-2c9f-4d4c-86be-d0d37bc483ed, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:40,797 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:40,797 - INFO - __tests__.alter_compression.2335951  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-12-23 17:49:40,798 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:40,799 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 381f2103-8acc-4407-a39d-200cadc026d1, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:40,800 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 381f2103-8acc-4407-a39d-200cadc026d1, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:40,801 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 381f2103-8acc-4407-a39d-200cadc026d1, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:42,367 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:42,369 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:42,369 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, f46f6088-1be8-406a-8e5e-bce1c74eb019, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:42,370 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, f46f6088-1be8-406a-8e5e-bce1c74eb019, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:42,384 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, f46f6088-1be8-406a-8e5e-bce1c74eb019, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:43,586 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:43,587 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:43,588 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 9f93e966-5cd8-4da3-8a99-69e37e92b36d, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:43,589 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 9f93e966-5cd8-4da3-8a99-69e37e92b36d, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:43,590 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 9f93e966-5cd8-4da3-8a99-69e37e92b36d, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:44,783 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:44,784 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:44,785 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, b8feddeb-e908-49d4-a106-56625acb5a1c, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:44,786 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, b8feddeb-e908-49d4-a106-56625acb5a1c, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:44,788 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, b8feddeb-e908-49d4-a106-56625acb5a1c, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:46,993 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:46,994 - INFO - __tests__.alter_compression.2335951  - 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-12-23 17:49:46,995 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:46,996 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, f82833e6-642f-4ffd-a927-289a50d19098, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:47,000 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, f82833e6-642f-4ffd-a927-289a50d19098, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:47,001 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, f82833e6-642f-4ffd-a927-289a50d19098, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:48,326 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:48,327 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:48,328 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, e56cc9f3-548d-43bd-b42a-c24979dbdf90, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:48,329 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, e56cc9f3-548d-43bd-b42a-c24979dbdf90, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:48,330 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, e56cc9f3-548d-43bd-b42a-c24979dbdf90, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:48,656 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:48,657 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:48,658 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 8a6f27fe-d2ef-4f69-8357-a62bd40a6970, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:48,659 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 8a6f27fe-d2ef-4f69-8357-a62bd40a6970, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:48,662 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 8a6f27fe-d2ef-4f69-8357-a62bd40a6970, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:49,207 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:49,208 - INFO - __tests__.alter_compression.2335951  - 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-12-23 17:49:49,209 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:49,210 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, ab5eda4b-046b-4557-83b6-b074ef1fa62a, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:49,211 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, ab5eda4b-046b-4557-83b6-b074ef1fa62a, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:49,219 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, ab5eda4b-046b-4557-83b6-b074ef1fa62a, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:50,775 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:50,776 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:50,777 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 66407780-2e64-43e8-982c-b4ecdaa4659b, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:50,778 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 66407780-2e64-43e8-982c-b4ecdaa4659b, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:50,779 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 66407780-2e64-43e8-982c-b4ecdaa4659b, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:51,174 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:51,175 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:51,175 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 632d8cca-b087-4e08-8402-d558792da3f7, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:51,176 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 632d8cca-b087-4e08-8402-d558792da3f7, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:51,188 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 632d8cca-b087-4e08-8402-d558792da3f7, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:51,993 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:51,994 - INFO - __tests__.alter_compression.2335951  - 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-12-23 17:49:51,995 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:51,995 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, b50e54d4-1473-4072-badf-38d1b9bc08ca, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:52,001 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, b50e54d4-1473-4072-badf-38d1b9bc08ca, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:52,003 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, b50e54d4-1473-4072-badf-38d1b9bc08ca, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:53,434 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:53,435 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:53,436 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, d6a5e1dd-7c8f-462e-bf40-07d6d64f5a2a, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:53,437 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, d6a5e1dd-7c8f-462e-bf40-07d6d64f5a2a, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:53,438 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, d6a5e1dd-7c8f-462e-bf40-07d6d64f5a2a, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:53,874 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:53,875 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:53,876 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 610cee14-28a6-49bf-93f7-0143f77b02d2, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:53,877 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 610cee14-28a6-49bf-93f7-0143f77b02d2, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:53,879 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 610cee14-28a6-49bf-93f7-0143f77b02d2, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:54,734 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:54,735 - INFO - __tests__.alter_compression.2335951  - 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-12-23 17:49:54,735 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:54,736 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 13b4fe65-c365-4aed-980d-c4de4c541f31, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:54,737 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 13b4fe65-c365-4aed-980d-c4de4c541f31, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:54,757 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 13b4fe65-c365-4aed-980d-c4de4c541f31, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:56,073 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:56,074 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:56,075 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 94b4a9d6-7af7-4745-8b53-9d5e7b820330, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:56,076 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 94b4a9d6-7af7-4745-8b53-9d5e7b820330, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:56,077 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 94b4a9d6-7af7-4745-8b53-9d5e7b820330, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:56,782 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:56,783 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:56,784 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 9b6dc2e1-8fb7-4ee3-9c7b-970c392c3d72, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:56,785 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 9b6dc2e1-8fb7-4ee3-9c7b-970c392c3d72, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:56,786 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 9b6dc2e1-8fb7-4ee3-9c7b-970c392c3d72, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:57,709 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:57,710 - INFO - __tests__.alter_compression.2335951  - 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-12-23 17:49:57,711 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:57,711 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, f6a9fbca-2419-4198-a9fb-28d0150ab4f7, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:57,713 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, f6a9fbca-2419-4198-a9fb-28d0150ab4f7, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:57,715 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, f6a9fbca-2419-4198-a9fb-28d0150ab4f7, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:59,238 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:59,239 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:59,240 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 6981cf58-09bc-4274-b56b-6cebf7ffb4b9, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:59,241 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 6981cf58-09bc-4274-b56b-6cebf7ffb4b9, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:59,242 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 6981cf58-09bc-4274-b56b-6cebf7ffb4b9, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:49:59,613 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:59,614 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:49:59,615 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 7652a400-d9a7-4499-acc7-f5bd278b49ca, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:49:59,616 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 7652a400-d9a7-4499-acc7-f5bd278b49ca, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:49:59,617 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 7652a400-d9a7-4499-acc7-f5bd278b49ca, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:00,209 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:00,225 - INFO - __tests__.alter_compression.2335951  - 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-12-23 17:50:00,226 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:00,226 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 6ddf4e53-ccc8-4b20-a762-eab86d629da5, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:00,227 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 6ddf4e53-ccc8-4b20-a762-eab86d629da5, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:00,232 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 6ddf4e53-ccc8-4b20-a762-eab86d629da5, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:01,677 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:01,678 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:01,679 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 08435e9a-0a15-4ecd-81a2-15f684e88ff1, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:01,680 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 08435e9a-0a15-4ecd-81a2-15f684e88ff1, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:01,682 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 08435e9a-0a15-4ecd-81a2-15f684e88ff1, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:01,966 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:01,967 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:01,967 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 9ca5d95d-ff17-407d-b058-8c621818d976, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:01,968 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 9ca5d95d-ff17-407d-b058-8c621818d976, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:01,969 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 9ca5d95d-ff17-407d-b058-8c621818d976, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:02,556 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:02,556 - INFO - __tests__.alter_compression.2335951  - 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-12-23 17:50:02,557 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:02,557 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 7b82ced0-621f-4900-9bc8-f87cfe8646d0, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:02,558 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 7b82ced0-621f-4900-9bc8-f87cfe8646d0, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:02,561 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 7b82ced0-621f-4900-9bc8-f87cfe8646d0, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:03,929 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:03,930 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:03,931 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 09749759-cfbd-480e-9bef-bd737c65645e, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:03,932 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 09749759-cfbd-480e-9bef-bd737c65645e, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:03,937 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 09749759-cfbd-480e-9bef-bd737c65645e, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:04,545 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:04,546 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:04,547 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 156e220a-0126-4d64-bbf6-5b0e05a96a3c, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:04,548 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 156e220a-0126-4d64-bbf6-5b0e05a96a3c, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:04,551 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 156e220a-0126-4d64-bbf6-5b0e05a96a3c, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:05,348 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:05,349 - INFO - __tests__.alter_compression.2335951  - 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-12-23 17:50:05,349 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:05,350 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, ffd7000f-69c7-497a-a01e-3189bcbc68fb, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:05,351 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, ffd7000f-69c7-497a-a01e-3189bcbc68fb, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:05,353 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, ffd7000f-69c7-497a-a01e-3189bcbc68fb, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:07,149 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:07,150 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:07,151 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, dbe58b9f-74b4-4f59-a0f7-0ef594c3a162, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:07,152 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, dbe58b9f-74b4-4f59-a0f7-0ef594c3a162, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:07,154 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, dbe58b9f-74b4-4f59-a0f7-0ef594c3a162, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:07,608 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:07,609 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:07,610 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 5c7c3a20-32a3-4df8-9c2b-bc40d2cbe05a, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:07,611 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 5c7c3a20-32a3-4df8-9c2b-bc40d2cbe05a, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:07,617 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 5c7c3a20-32a3-4df8-9c2b-bc40d2cbe05a, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:09,321 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:09,322 - INFO - __tests__.alter_compression.2335951  - 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-12-23 17:50:09,322 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:09,323 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 3d4bc8ce-9c7b-4ff8-9b8b-1b4a3507c17a, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:09,325 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 3d4bc8ce-9c7b-4ff8-9b8b-1b4a3507c17a, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:09,326 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 3d4bc8ce-9c7b-4ff8-9b8b-1b4a3507c17a, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:11,228 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:11,229 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:11,230 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, ecc0a813-9f89-4b01-8d61-a359291f7c3a, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:11,231 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, ecc0a813-9f89-4b01-8d61-a359291f7c3a, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:11,237 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, ecc0a813-9f89-4b01-8d61-a359291f7c3a, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:11,813 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:11,813 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:11,814 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 2b657b66-9cf0-40ff-8c91-5c528ce8b330, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:11,815 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 2b657b66-9cf0-40ff-8c91-5c528ce8b330, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:11,818 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 2b657b66-9cf0-40ff-8c91-5c528ce8b330, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:14,576 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:14,577 - INFO - __tests__.alter_compression.2335951  - 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-12-23 17:50:14,578 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:14,579 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 2d96194d-0a03-421a-affa-c0b81333c22e, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:14,580 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 2d96194d-0a03-421a-affa-c0b81333c22e, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:14,583 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 2d96194d-0a03-421a-affa-c0b81333c22e, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:16,162 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:16,163 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:16,164 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, ad553f03-3e09-4e8f-bda1-d0abfa852cc3, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:16,165 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, ad553f03-3e09-4e8f-bda1-d0abfa852cc3, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:16,169 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, ad553f03-3e09-4e8f-bda1-d0abfa852cc3, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:16,527 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:16,528 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:16,529 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, f8c6682c-c597-4c21-98ad-9bdfd82cdec5, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:16,529 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, f8c6682c-c597-4c21-98ad-9bdfd82cdec5, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:16,531 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, f8c6682c-c597-4c21-98ad-9bdfd82cdec5, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:17,030 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:17,031 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:17,032 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, deb79670-8182-48ab-b09f-4396bc7d3250, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:17,033 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, deb79670-8182-48ab-b09f-4396bc7d3250, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:17,049 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, deb79670-8182-48ab-b09f-4396bc7d3250, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:21,729 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:31,806 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:31,807 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 74eab9bd-1e80-4c7e-9138-fdf3668442fd, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:31,807 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 74eab9bd-1e80-4c7e-9138-fdf3668442fd, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:31,809 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 74eab9bd-1e80-4c7e-9138-fdf3668442fd, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:36,357 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:36,396 - INFO - root.2335951  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-12-23 17:50:36,396 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:36,397 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 8a4c85cc-bada-4b5f-ac5b-113ebd4163dd, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:36,398 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 8a4c85cc-bada-4b5f-ac5b-113ebd4163dd, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:36,399 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 8a4c85cc-bada-4b5f-ac5b-113ebd4163dd, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:40,091 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2335951  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 17:50:40,092 - DEBUG - ydb.connection.2335951  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 17:50:40,110 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:40,158 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2335951  - context_resolve: Resolving endpoints for database /Root
2025-12-23 17:50:40,159 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ListEndpoints, 398f4a09-80cd-423c-bbd2-d3099aaa8521, localhost:64039): creating call state
2025-12-23 17:50:40,160 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ListEndpoints, 398f4a09-80cd-423c-bbd2-d3099aaa8521, localhost:64039): request = { database: "/Root" }
2025-12-23 17:50:40,169 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ListEndpoints, 398f4a09-80cd-423c-bbd2-d3099aaa8521, localhost:64039): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-yh5r7pxf7y.auto.internal" port: 64039 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-12-23 17:50:40,170 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.2335951  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-yh5r7pxf7y.auto.internal:64039, location 1, ssl: False>]>
2025-12-23 17:50:40,170 - DEBUG - ydb.connection.2335951  - close: Closing channel for endpoint localhost:64039
2025-12-23 17:50:50,154 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:50,155 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, fb5fd45b-4879-4dfd-9a07-a84d9f1b4cc4, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:50,156 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, fb5fd45b-4879-4dfd-9a07-a84d9f1b4cc4, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:50,158 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, fb5fd45b-4879-4dfd-9a07-a84d9f1b4cc4, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:50,910 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:50,915 - INFO - root.2335951  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-12-23 17:50:50,916 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:50,917 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 9be8e21b-9466-4a03-8a6b-a77c0f8afdd7, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:50,918 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 9be8e21b-9466-4a03-8a6b-a77c0f8afdd7, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:50,922 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 9be8e21b-9466-4a03-8a6b-a77c0f8afdd7, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:51,224 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:51,228 - INFO - ya.test.2335951  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_4_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 4])
2025-12-23 17:50:51,229 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:51,230 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 39935acc-f872-446b-a988-9a459bb2efb8, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:51,231 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 39935acc-f872-446b-a988-9a459bb2efb8, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_4_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 4)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:51,233 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 39935acc-f872-446b-a988-9a459bb2efb8, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:54,245 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:54,246 - INFO - __tests__.alter_compression.2335951  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_4_compression created
2025-12-23 17:50:54,246 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:54,247 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, e73cd805-7ff6-447a-9530-21ba97549371, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:54,248 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, e73cd805-7ff6-447a-9530-21ba97549371, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_4_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:54,250 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, e73cd805-7ff6-447a-9530-21ba97549371, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:55,111 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:55,112 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:55,113 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, efd71e76-bca3-479c-bf11-736f02820fe7, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:55,114 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, efd71e76-bca3-479c-bf11-736f02820fe7, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:55,115 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, efd71e76-bca3-479c-bf11-736f02820fe7, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:56,452 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:56,453 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:56,454 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, a5c72278-2392-4a5e-a24d-d0ec6003250e, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:56,455 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, a5c72278-2392-4a5e-a24d-d0ec6003250e, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:56,456 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, a5c72278-2392-4a5e-a24d-d0ec6003250e, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:57,352 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:57,352 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:57,353 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, eb2ce438-07a8-4d6a-8df3-ba4928b1ccfb, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:57,354 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, eb2ce438-07a8-4d6a-8df3-ba4928b1ccfb, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:57,356 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, eb2ce438-07a8-4d6a-8df3-ba4928b1ccfb, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:50:59,196 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:59,197 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318960, 'BlobCount': 128}}
2025-12-23 17:50:59,198 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:50:59,198 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 8045f6b7-1041-4385-b7a1-4fdedb357e9c, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:50:59,199 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 8045f6b7-1041-4385-b7a1-4fdedb357e9c, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:50:59,200 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 8045f6b7-1041-4385-b7a1-4fdedb357e9c, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:00,222 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:00,223 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:00,224 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 7b46c42f-d2b0-4754-b0cc-eed223570041, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:00,225 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 7b46c42f-d2b0-4754-b0cc-eed223570041, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:00,226 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 7b46c42f-d2b0-4754-b0cc-eed223570041, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:00,491 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:00,492 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:00,493 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 19745484-0e1c-405e-a854-5efa96adbbe2, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:00,494 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 19745484-0e1c-405e-a854-5efa96adbbe2, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:00,495 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 19745484-0e1c-405e-a854-5efa96adbbe2, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:00,816 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:00,817 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638256, 'BlobCount': 256}}
2025-12-23 17:51:00,818 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:00,818 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 753f6d98-7a35-453c-80f9-7363f39abd32, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:00,819 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 753f6d98-7a35-453c-80f9-7363f39abd32, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:00,821 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 753f6d98-7a35-453c-80f9-7363f39abd32, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:01,965 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:01,966 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:01,966 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, ae6d02d0-8244-40a7-9fa3-7636d2eb813f, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:01,967 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, ae6d02d0-8244-40a7-9fa3-7636d2eb813f, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:01,969 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, ae6d02d0-8244-40a7-9fa3-7636d2eb813f, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:02,220 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:02,221 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:02,222 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 9fbc9c71-6220-4d52-87dc-78f34c5b801b, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:02,223 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 9fbc9c71-6220-4d52-87dc-78f34c5b801b, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:02,226 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 9fbc9c71-6220-4d52-87dc-78f34c5b801b, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:02,624 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:02,625 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957296, 'BlobCount': 384}}
2025-12-23 17:51:02,626 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:02,627 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, deea8302-03fd-4526-b3dc-ecbbffa3ea5a, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:02,628 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, deea8302-03fd-4526-b3dc-ecbbffa3ea5a, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:02,629 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, deea8302-03fd-4526-b3dc-ecbbffa3ea5a, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:03,792 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:03,793 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:03,794 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 87bb2c1a-2747-47f7-9806-527cc32e2bb2, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:03,795 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 87bb2c1a-2747-47f7-9806-527cc32e2bb2, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:03,799 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 87bb2c1a-2747-47f7-9806-527cc32e2bb2, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:04,047 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:04,048 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:04,049 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 97e44a25-1a2b-4503-90ed-33dc43a3fd64, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:04,050 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 97e44a25-1a2b-4503-90ed-33dc43a3fd64, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:04,051 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 97e44a25-1a2b-4503-90ed-33dc43a3fd64, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:04,497 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:04,498 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276656, 'BlobCount': 512}}
2025-12-23 17:51:04,499 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:04,500 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, f457d310-3d19-47c1-bcda-49af3942981d, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:04,501 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, f457d310-3d19-47c1-bcda-49af3942981d, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:04,502 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, f457d310-3d19-47c1-bcda-49af3942981d, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:05,650 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:05,650 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:05,651 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, adaa372e-a11d-49d0-88a8-85721603e01c, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:05,652 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, adaa372e-a11d-49d0-88a8-85721603e01c, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:05,653 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, adaa372e-a11d-49d0-88a8-85721603e01c, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:05,878 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:05,879 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:05,880 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, cb9eb268-7e44-46e4-ad15-f6c09c864b7a, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:05,881 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, cb9eb268-7e44-46e4-ad15-f6c09c864b7a, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:05,883 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, cb9eb268-7e44-46e4-ad15-f6c09c864b7a, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:06,331 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:06,331 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595616, 'BlobCount': 640}}
2025-12-23 17:51:06,331 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:06,332 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 70cb0e2d-5efd-4f43-a95a-6799a83b4145, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:06,333 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 70cb0e2d-5efd-4f43-a95a-6799a83b4145, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:06,334 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 70cb0e2d-5efd-4f43-a95a-6799a83b4145, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:07,561 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:07,562 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:07,562 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, e856ca27-b54f-477b-98e6-da4175becb28, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:07,563 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, e856ca27-b54f-477b-98e6-da4175becb28, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:07,565 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, e856ca27-b54f-477b-98e6-da4175becb28, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:07,824 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:07,825 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:07,825 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 27de9738-a211-4e78-9ff5-25a41a7acbca, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:07,827 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 27de9738-a211-4e78-9ff5-25a41a7acbca, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:07,828 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 27de9738-a211-4e78-9ff5-25a41a7acbca, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:08,485 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:08,486 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914800, 'BlobCount': 768}}
2025-12-23 17:51:08,487 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:08,487 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 626c38e4-697f-4f87-a545-67bfe493275c, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:08,489 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 626c38e4-697f-4f87-a545-67bfe493275c, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:08,490 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 626c38e4-697f-4f87-a545-67bfe493275c, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:09,830 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:09,831 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:09,832 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, ab9ca002-44f8-4b84-b1f9-14f25bbb46fe, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:09,832 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, ab9ca002-44f8-4b84-b1f9-14f25bbb46fe, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:09,834 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, ab9ca002-44f8-4b84-b1f9-14f25bbb46fe, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:10,210 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:10,239 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:10,242 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 24187eda-6a4a-4552-91a3-17209ea3acc4, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:10,243 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 24187eda-6a4a-4552-91a3-17209ea3acc4, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:10,246 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 24187eda-6a4a-4552-91a3-17209ea3acc4, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:11,062 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:11,063 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233936, 'BlobCount': 896}}
2025-12-23 17:51:11,063 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:11,064 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, fdc59a64-18a8-4684-b378-a85f80883f87, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:11,083 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, fdc59a64-18a8-4684-b378-a85f80883f87, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:11,084 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, fdc59a64-18a8-4684-b378-a85f80883f87, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:12,680 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:12,681 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:12,681 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, f77753c4-dcb3-4ccc-95c4-e203d7267bce, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:12,682 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, f77753c4-dcb3-4ccc-95c4-e203d7267bce, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:12,684 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, f77753c4-dcb3-4ccc-95c4-e203d7267bce, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:13,143 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:13,143 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:13,145 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 396f3223-c375-46dc-b641-600b5d80128f, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:13,146 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 396f3223-c375-46dc-b641-600b5d80128f, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:13,147 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 396f3223-c375-46dc-b641-600b5d80128f, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:13,765 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:13,765 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553424, 'BlobCount': 1024}}
2025-12-23 17:51:13,766 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:13,767 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 11e79389-64ec-4c3f-9581-cdc979917c9e, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:13,767 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 11e79389-64ec-4c3f-9581-cdc979917c9e, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:13,769 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 11e79389-64ec-4c3f-9581-cdc979917c9e, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:15,004 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:15,005 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:15,006 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, d0a0aa96-8435-4b35-9839-f51ff5cf0059, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:15,006 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, d0a0aa96-8435-4b35-9839-f51ff5cf0059, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:15,009 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, d0a0aa96-8435-4b35-9839-f51ff5cf0059, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:15,321 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:15,322 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:15,323 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, be009f8c-4abf-4079-a052-e0f8d5fd3b0c, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:15,324 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, be009f8c-4abf-4079-a052-e0f8d5fd3b0c, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:15,326 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, be009f8c-4abf-4079-a052-e0f8d5fd3b0c, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:16,238 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:16,238 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872432, 'BlobCount': 1152}}
2025-12-23 17:51:16,239 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:16,240 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, aeae38c5-0e86-40a0-80ca-5989cbf5f7df, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:16,241 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, aeae38c5-0e86-40a0-80ca-5989cbf5f7df, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:16,242 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, aeae38c5-0e86-40a0-80ca-5989cbf5f7df, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:17,686 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:17,707 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:17,708 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 14d4ceb9-d62d-41c6-a177-af33735137ba, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:17,709 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 14d4ceb9-d62d-41c6-a177-af33735137ba, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:17,710 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 14d4ceb9-d62d-41c6-a177-af33735137ba, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:18,426 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:18,426 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:18,427 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, e69da17e-5659-4e39-bf42-3a0ea170d129, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:18,428 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, e69da17e-5659-4e39-bf42-3a0ea170d129, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:18,429 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, e69da17e-5659-4e39-bf42-3a0ea170d129, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:19,326 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:19,328 - INFO - __tests__.alter_compression.2335951  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191936, 'BlobCount': 1280}}
2025-12-23 17:51:19,328 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:19,329 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, c265bf58-db51-407b-839e-f85628637f21, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:19,330 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, c265bf58-db51-407b-839e-f85628637f21, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_4_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:19,333 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, c265bf58-db51-407b-839e-f85628637f21, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:20,660 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:20,661 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:20,662 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 1bbe3a83-8b14-4a02-a2d9-4b0a8100b6c5, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:20,662 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 1bbe3a83-8b14-4a02-a2d9-4b0a8100b6c5, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:20,670 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 1bbe3a83-8b14-4a02-a2d9-4b0a8100b6c5, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:21,085 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:21,133 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:21,134 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, f93b9930-d690-4e7d-8203-9cb769054692, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:21,135 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, f93b9930-d690-4e7d-8203-9cb769054692, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:21,137 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, f93b9930-d690-4e7d-8203-9cb769054692, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:21,566 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:21,589 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:21,590 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 379d15a4-405c-4ad7-81ef-c05cd87afd0f, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:21,591 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 379d15a4-405c-4ad7-81ef-c05cd87afd0f, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:21,593 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 379d15a4-405c-4ad7-81ef-c05cd87afd0f, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:27,271 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:37,319 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:37,320 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, ab5c5a90-c25d-42a0-a294-3acd1dd0d375, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:37,321 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, ab5c5a90-c25d-42a0-a294-3acd1dd0d375, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:37,322 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, ab5c5a90-c25d-42a0-a294-3acd1dd0d375, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:43,257 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:43,338 - INFO - root.2335951  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_4_compression`, volumes `value` (8000000, 1595968)
2025-12-23 17:51:43,339 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:43,339 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, fb17edc4-4658-4e0e-b4fe-8a48bd5d38e4, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:43,340 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, fb17edc4-4658-4e0e-b4fe-8a48bd5d38e4, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" 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/zstd_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:43,342 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, fb17edc4-4658-4e0e-b4fe-8a48bd5d38e4, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:43,733 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:43,734 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:43,735 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 136b86eb-d3bb-4d01-8b62-c046bd1caacc, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:43,736 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 136b86eb-d3bb-4d01-8b62-c046bd1caacc, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:43,737 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 136b86eb-d3bb-4d01-8b62-c046bd1caacc, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:51:47,253 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:57,298 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:51:57,330 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, b08de71f-1563-4f0c-809b-d9b2cffce620, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:51:57,331 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, b08de71f-1563-4f0c-809b-d9b2cffce620, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:51:57,333 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, b08de71f-1563-4f0c-809b-d9b2cffce620, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:00,344 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:10,371 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:10,372 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 83c118c3-64fc-4232-a2aa-44d3aea8556c, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:10,373 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 83c118c3-64fc-4232-a2aa-44d3aea8556c, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:10,375 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 83c118c3-64fc-4232-a2aa-44d3aea8556c, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:11,394 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:21,400 - DEBUG - ydb.query.pool.2335951  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:21,401 - DEBUG - ydb.connection.2335951  - _prepare_call: RpcState(ExecuteQuery, 2b65f1de-add6-42bf-8f2b-c6d8f284fe26, ghrun-yh5r7pxf7y.auto.internal:64039): creating call state
2025-12-23 17:52:21,402 - DEBUG - ydb.connection.2335951  - _log_request: RpcState(ExecuteQuery, 2b65f1de-add6-42bf-8f2b-c6d8f284fe26, ghrun-yh5r7pxf7y.auto.internal:64039): request = { session_id: "ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 17:52:21,404 - DEBUG - ydb.connection.2335951  - _log_response: RpcState(ExecuteQuery, 2b65f1de-add6-42bf-8f2b-c6d8f284fe26, ghrun-yh5r7pxf7y.auto.internal:64039): response = { <_MultiThreadedRendezvous object> }
2025-12-23 17:52:22,299 - DEBUG - ydb.query.pool.2335951  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNmNzM2NmUtODRkZWQ3NTQtYTFjMjY1YzItMjVkYjZmMzQ=
2025-12-23 17:52:22,303 - INFO - root.2335951  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_4_compression`, volumes `value` (8000000, 1466456)
2025-12-23 17:52:22,303 - INFO - root.2335951  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_4_compression` 8092160 / 1466456: 5.518174428690666
2025-12-23 17:52:22,316 - INFO - ya.test.2335951  - pytest_runtest_teardown: Test teardown
