2025-12-05 16:17:44,219 - INFO - ya.test.22630  - pytest_runtest_setup: ####################################################################################################
2025-12-05 16:17:44,224 - INFO - ya.test.22630  - pytest_runtest_setup: test_all_supported_compression[zstd_8_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 8]
2025-12-05 16:17:44,225 - INFO - ya.test.22630  - pytest_runtest_setup: ####################################################################################################
2025-12-05 16:17:44,226 - INFO - ya.test.22630  - pytest_runtest_setup: Test setup
2025-12-05 16:17:44,245 - DEBUG - ya.test.22630  - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-05 16:17:44,251 - DEBUG - ya.test.22630  - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-05 16:17:44,255 - DEBUG - ya.test.22630  - execute: Executing '['/home/runner/.ya/build/build_root/nihu/0016e8/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-05 16:17:44,290 - DEBUG - ya.test.22630  - execute: Command pid: 23604
2025-12-05 16:17:53,899 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) rc: 0
2025-12-05 16:17:53,900 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) elapsed time (sec): 9.588818311691284
2025-12-05 16:17:53,900 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) idrss: 0
2025-12-05 16:17:53,901 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) inblock: 0
2025-12-05 16:17:53,901 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) isrss: 0
2025-12-05 16:17:53,901 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) ixrss: 0
2025-12-05 16:17:53,901 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) majflt: 0
2025-12-05 16:17:53,902 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) maxrss: 485992
2025-12-05 16:17:53,902 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) minflt: 46924
2025-12-05 16:17:53,902 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) msgrcv: 0
2025-12-05 16:17:53,902 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) msgsnd: 0
2025-12-05 16:17:53,903 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) nivcsw: 926
2025-12-05 16:17:53,903 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) nsignals: 0
2025-12-05 16:17:53,903 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) nswap: 0
2025-12-05 16:17:53,903 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) nvcsw: 14
2025-12-05 16:17:53,904 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) oublock: 8
2025-12-05 16:17:53,904 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) stime: 4.4575569999999995
2025-12-05 16:17:53,904 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) utime: 0.547836
2025-12-05 16:17:53,904 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) wtime: 9.643
2025-12-05 16:17:53,905 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) output:
Git info:
    Commit: 7e4a069eed48cd0542e43cfcd8d71021826caed7
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge a01fb38f4f5fc409f3a42967229b198aa52519b3 into 3022ce6da3be33dce544958fe431efb7eb8af83a
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-a66hhqwkt4    Host information: 
        Linux ghrun-a66hhqwkt4 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/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-05 16:17:53,905 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 23604) errors:

2025-12-05 16:17:53,906 - INFO - __tests__.base.22630  - _setup_ydb: Git info:
    Commit: 7e4a069eed48cd0542e43cfcd8d71021826caed7
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge a01fb38f4f5fc409f3a42967229b198aa52519b3 into 3022ce6da3be33dce544958fe431efb7eb8af83a
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-a66hhqwkt4    Host information: 
        Linux ghrun-a66hhqwkt4 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/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-05 16:17:54,014 - DEBUG - ya.test.22630  - get_binary: Binary was found by /home/runner/.ya/build/build_root/nihu/0016e8/ydb/apps/ydbd/ydbd
2025-12-05 16:17:54,016 - DEBUG - library.python.filelock.22630  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20570
2025-12-05 16:17:54,018 - DEBUG - ydb.tests.library.harness.kikimr_runner.22630  - start: Working directory: /home/runner/.ya/build/build_root/nihu/0016e8/r3tmp/kikimr_cluster_do0pxgyp
2025-12-05 16:17:54,060 - DEBUG - ya.test.22630  - get_binary: Binary was found by /home/runner/.ya/build/build_root/nihu/0016e8/ydb/apps/ydbd/ydbd
2025-12-05 16:17:54,060 - DEBUG - library.python.filelock.22630  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17371
2025-12-05 16:17:54,061 - DEBUG - library.python.filelock.22630  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5150
2025-12-05 16:17:54,062 - DEBUG - library.python.filelock.22630  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29566
2025-12-05 16:17:54,062 - DEBUG - library.python.filelock.22630  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16252
2025-12-05 16:17:54,063 - INFO - ydb.tests.library.harness.kikimr_runner.22630  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/nihu/0016e8/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/kikimr_configs"
2025-12-05 16:17:54,064 - INFO - ydb.tests.library.harness.kikimr_runner.22630  - __make_run_command: Final command: /home/runner/.ya/build/build_root/nihu/0016e8/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/nihu/0016e8/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/node_1/logfile_qwjfvqd1.log --grpc-port=17371 --mon-port=5150 --ic-port=20570
2025-12-05 16:17:54,064 - DEBUG - ydb.tests.library.harness.kikimr_runner.22630  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:17371/1, disk_size 68719476736
2025-12-05 16:17:54,065 - INFO - ydb.tests.library.harness.kikimr_runner.22630  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/nihu/0016e8/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/kikimr_configs"
2025-12-05 16:17:54,065 - INFO - ydb.tests.library.harness.kikimr_runner.22630  - __make_run_command: Final command: /home/runner/.ya/build/build_root/nihu/0016e8/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/nihu/0016e8/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/node_1/logfile_qwjfvqd1.log --grpc-port=17371 --mon-port=5150 --ic-port=20570
2025-12-05 16:17:54,066 - DEBUG - ya.test.22630  - execute: Executing '('/home/runner/.ya/build/build_root/nihu/0016e8/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/nihu/0016e8/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/nihu/0016e8/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/node_1/logfile_qwjfvqd1.log', '--grpc-port=17371', '--mon-port=5150', '--ic-port=20570')' in '/home/runner/.ya/build/build_root/nihu/0016e8/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/node_1' (no wait)
2025-12-05 16:17:54,089 - DEBUG - ya.test.22630  - execute: Command pid: 24128
2025-12-05 16:17:54,090 - INFO - ydb.tests.library.harness.kikimr_runner.22630  - start: Started node localhost:17371/1
2025-12-05 16:17:54,092 - DEBUG - ya.test.22630  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-05 16:17:54,093 - DEBUG - ya.test.22630  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-05 16:17:54,093 - DEBUG - ya.test.22630  - execute: Executing '['/home/runner/.ya/build/build_root/nihu/0016e8/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-05 16:17:54,109 - DEBUG - ya.test.22630  - execute: Command pid: 24130
2025-12-05 16:18:01,558 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) rc: 0
2025-12-05 16:18:01,559 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) elapsed time (sec): 7.447595596313477
2025-12-05 16:18:01,559 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) idrss: 0
2025-12-05 16:18:01,560 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) inblock: 0
2025-12-05 16:18:01,560 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) isrss: 0
2025-12-05 16:18:01,560 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) ixrss: 0
2025-12-05 16:18:01,560 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) majflt: 0
2025-12-05 16:18:01,561 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) maxrss: 486032
2025-12-05 16:18:01,561 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) minflt: 49121
2025-12-05 16:18:01,561 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) msgrcv: 0
2025-12-05 16:18:01,561 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) msgsnd: 0
2025-12-05 16:18:01,564 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) nivcsw: 636
2025-12-05 16:18:01,567 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) nsignals: 0
2025-12-05 16:18:01,569 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) nswap: 0
2025-12-05 16:18:01,572 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) nvcsw: 13
2025-12-05 16:18:01,574 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) oublock: 8
2025-12-05 16:18:01,577 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) stime: 4.3043949999999995
2025-12-05 16:18:01,580 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) utime: 0.519203
2025-12-05 16:18:01,584 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) wtime: 7.464
2025-12-05 16:18:01,587 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) output:
Git info:
    Commit: 7e4a069eed48cd0542e43cfcd8d71021826caed7
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge a01fb38f4f5fc409f3a42967229b198aa52519b3 into 3022ce6da3be33dce544958fe431efb7eb8af83a
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-a66hhqwkt4    Host information: 
        Linux ghrun-a66hhqwkt4 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/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-05 16:18:01,610 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 24130) errors:

2025-12-05 16:18:01,613 - INFO - ydb.tests.library.harness.kikimr_runner.22630  - start: Node 1 version:
Git info:
    Commit: 7e4a069eed48cd0542e43cfcd8d71021826caed7
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge a01fb38f4f5fc409f3a42967229b198aa52519b3 into 3022ce6da3be33dce544958fe431efb7eb8af83a
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-a66hhqwkt4    Host information: 
        Linux ghrun-a66hhqwkt4 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-05 16:18:27,948 - DEBUG - ya.test.22630  - get_binary: Binary was found by /home/runner/.ya/build/build_root/nihu/0016e8/ydb/apps/ydbd/ydbd
2025-12-05 16:18:27,950 - DEBUG - ydb.tests.library.harness.kikimr_runner.22630  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/nihu/0016e8/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:17371', '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: 20570\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-05 16:18:27,951 - DEBUG - ya.test.22630  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-05 16:18:27,952 - DEBUG - ya.test.22630  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-05 16:18:27,952 - DEBUG - ya.test.22630  - execute: Executing '['/home/runner/.ya/build/build_root/nihu/0016e8/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:17371', '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: 20570\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-05 16:18:27,961 - DEBUG - ya.test.22630  - execute: Command pid: 29364
2025-12-05 16:18:29,917 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) rc: 0
2025-12-05 16:18:29,918 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) elapsed time (sec): 1.9262912273406982
2025-12-05 16:18:29,918 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) idrss: 0
2025-12-05 16:18:29,918 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) inblock: 0
2025-12-05 16:18:29,918 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) isrss: 0
2025-12-05 16:18:29,919 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) ixrss: 0
2025-12-05 16:18:29,919 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) majflt: 0
2025-12-05 16:18:29,919 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) maxrss: 640276
2025-12-05 16:18:29,919 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) minflt: 52176
2025-12-05 16:18:29,920 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) msgrcv: 0
2025-12-05 16:18:29,920 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) msgsnd: 0
2025-12-05 16:18:29,920 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) nivcsw: 292
2025-12-05 16:18:29,920 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) nsignals: 0
2025-12-05 16:18:29,921 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) nswap: 0
2025-12-05 16:18:29,954 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) nvcsw: 580
2025-12-05 16:18:29,954 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) oublock: 8
2025-12-05 16:18:29,955 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) stime: 0.32769499999999996
2025-12-05 16:18:29,955 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) utime: 0.5982759999999999
2025-12-05 16:18:29,955 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) wtime: 1.936
2025-12-05 16:18:29,955 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-05 16:18:29,956 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 29364) errors:

2025-12-05 16:18:29,958 - DEBUG - ya.test.22630  - get_binary: Binary was found by /home/runner/.ya/build/build_root/nihu/0016e8/ydb/apps/ydbd/ydbd
2025-12-05 16:18:29,958 - DEBUG - ydb.tests.library.harness.kikimr_runner.22630  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/nihu/0016e8/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:17371', '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-05 16:18:29,960 - DEBUG - ya.test.22630  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-05 16:18:29,960 - DEBUG - ya.test.22630  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-05 16:18:29,971 - DEBUG - ya.test.22630  - execute: Executing '['/home/runner/.ya/build/build_root/nihu/0016e8/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:17371', '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-05 16:18:29,997 - DEBUG - ya.test.22630  - execute: Command pid: 30623
2025-12-05 16:18:31,781 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) rc: 0
2025-12-05 16:18:31,782 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) elapsed time (sec): 1.7684674263000488
2025-12-05 16:18:31,782 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) idrss: 0
2025-12-05 16:18:31,783 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) inblock: 0
2025-12-05 16:18:31,783 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) isrss: 0
2025-12-05 16:18:31,783 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) ixrss: 0
2025-12-05 16:18:31,784 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) majflt: 0
2025-12-05 16:18:31,784 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) maxrss: 640276
2025-12-05 16:18:31,784 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) minflt: 52294
2025-12-05 16:18:31,784 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) msgrcv: 0
2025-12-05 16:18:31,785 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) msgsnd: 0
2025-12-05 16:18:31,793 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) nivcsw: 248
2025-12-05 16:18:31,794 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) nsignals: 0
2025-12-05 16:18:31,794 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) nswap: 0
2025-12-05 16:18:31,794 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) nvcsw: 724
2025-12-05 16:18:31,794 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) oublock: 8
2025-12-05 16:18:31,795 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) stime: 0.379779
2025-12-05 16:18:31,795 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) utime: 0.5522429999999999
2025-12-05 16:18:31,795 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) wtime: 1.809
2025-12-05 16:18:31,796 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-05 16:18:31,796 - DEBUG - ya.test.22630  - _save_outputs: Command (pid 30623) errors:

2025-12-05 16:18:31,796 - INFO - ydb.tests.library.harness.kikimr_runner.22630  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-05 16:18:31,986 - INFO - ydb.tests.library.harness.kikimr_runner.22630  - __run: Cluster started and initialized
2025-12-05 16:18:32,114 - DEBUG - ydb.connection.22630  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-05 16:18:32,133 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22630  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-05 16:18:32,134 - DEBUG - ydb.connection.22630  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-05 16:18:32,216 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22630  - context_resolve: Resolving endpoints for database /Root
2025-12-05 16:18:32,216 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ListEndpoints, 3aa945ec-9c7d-4a6d-bbbf-eda2b790d26d, localhost:17371): creating call state
2025-12-05 16:18:32,217 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ListEndpoints, 3aa945ec-9c7d-4a6d-bbbf-eda2b790d26d, localhost:17371): request = { database: "/Root" }
2025-12-05 16:18:32,227 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ListEndpoints, 3aa945ec-9c7d-4a6d-bbbf-eda2b790d26d, localhost:17371): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-a66hhqwkt4.auto.internal" port: 17371 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-05 16:18:32,235 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22630  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-a66hhqwkt4.auto.internal:17371, location 1, ssl: False>]>
2025-12-05 16:18:32,236 - DEBUG - ydb.connection.22630  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-05 16:18:32,274 - DEBUG - ydb.connection.22630  - close: Closing channel for endpoint localhost:17371
2025-12-05 16:18:32,276 - DEBUG - ydb.query.pool.22630  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-05 16:18:32,278 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(CreateSession, 482d06d4-ee9d-4bb4-b2b7-6d7caaafa0a2, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:32,279 - DEBUG - ydb.connection.22630  - _log_request: RpcState(CreateSession, 482d06d4-ee9d-4bb4-b2b7-6d7caaafa0a2, ghrun-a66hhqwkt4.auto.internal:17371): request = {  }
2025-12-05 16:18:32,290 - DEBUG - ydb.connection.22630  - _log_response: RpcState(CreateSession, 482d06d4-ee9d-4bb4-b2b7-6d7caaafa0a2, ghrun-a66hhqwkt4.auto.internal:17371): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" node_id: 1 }
2025-12-05 16:18:32,291 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(AttachSession, 58e28edc-2bc6-479d-8e60-3324f0baf6fc, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:32,292 - DEBUG - ydb.connection.22630  - _log_request: RpcState(AttachSession, 58e28edc-2bc6-479d-8e60-3324f0baf6fc, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" }
2025-12-05 16:18:32,305 - DEBUG - ydb.connection.22630  - _log_response: RpcState(AttachSession, 58e28edc-2bc6-479d-8e60-3324f0baf6fc, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:32,346 - DEBUG - ydb.query.pool.22630  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:32,347 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, fac983fe-5d36-4130-8d46-e69b2026de59, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:32,348 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, fac983fe-5d36-4130-8d46-e69b2026de59, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:32,357 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, fac983fe-5d36-4130-8d46-e69b2026de59, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:37,053 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:37,094 - INFO - __tests__.alter_compression.22630  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-12-05 16:18:37,094 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:37,095 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 18339c6e-e7e5-454f-9701-a21485fe09a0, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:37,132 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 18339c6e-e7e5-454f-9701-a21485fe09a0, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:37,157 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 18339c6e-e7e5-454f-9701-a21485fe09a0, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:39,044 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:39,132 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:39,214 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 26649a8f-9038-4f12-a6a8-58f8154ea3e4, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:39,254 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 26649a8f-9038-4f12-a6a8-58f8154ea3e4, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:39,295 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 26649a8f-9038-4f12-a6a8-58f8154ea3e4, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:41,237 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:41,238 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:41,238 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 95f5b534-643f-48c0-9674-5e102fded7a3, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:41,239 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 95f5b534-643f-48c0-9674-5e102fded7a3, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:41,241 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 95f5b534-643f-48c0-9674-5e102fded7a3, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:42,689 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:42,709 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:42,709 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 908d85d6-1101-425e-95b7-97880fd4f798, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:42,725 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 908d85d6-1101-425e-95b7-97880fd4f798, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:42,728 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 908d85d6-1101-425e-95b7-97880fd4f798, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:44,597 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:44,621 - INFO - __tests__.alter_compression.22630  - 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-05 16:18:44,621 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:44,622 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 20e79827-d6d5-4ba2-9349-ea23fd622b4c, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:44,623 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 20e79827-d6d5-4ba2-9349-ea23fd622b4c, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:44,624 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 20e79827-d6d5-4ba2-9349-ea23fd622b4c, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:45,659 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:45,677 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:45,678 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 7f460b65-a4ca-4b61-a9fd-b29dd213562d, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:45,679 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 7f460b65-a4ca-4b61-a9fd-b29dd213562d, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:45,680 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 7f460b65-a4ca-4b61-a9fd-b29dd213562d, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:45,954 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:45,954 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:45,955 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, e6fb499e-78b8-4c18-98d8-ed85150ce9e0, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:45,956 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, e6fb499e-78b8-4c18-98d8-ed85150ce9e0, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:45,957 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, e6fb499e-78b8-4c18-98d8-ed85150ce9e0, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:46,294 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:46,294 - INFO - __tests__.alter_compression.22630  - 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-05 16:18:46,295 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:46,296 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 7d32658f-57a4-4bcb-9b56-d8e2976043b1, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:46,297 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 7d32658f-57a4-4bcb-9b56-d8e2976043b1, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:46,300 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 7d32658f-57a4-4bcb-9b56-d8e2976043b1, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:47,320 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:47,406 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:47,407 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, f7f84b28-15d8-4377-b2a0-b47a0de969c7, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:47,408 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, f7f84b28-15d8-4377-b2a0-b47a0de969c7, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:47,412 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, f7f84b28-15d8-4377-b2a0-b47a0de969c7, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:47,758 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:47,759 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:47,759 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 23ee4a04-8f9e-4aca-9028-bb72e475f5d1, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:47,763 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 23ee4a04-8f9e-4aca-9028-bb72e475f5d1, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:47,765 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 23ee4a04-8f9e-4aca-9028-bb72e475f5d1, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:48,218 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:48,218 - INFO - __tests__.alter_compression.22630  - 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-05 16:18:48,482 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:48,483 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 72545793-acae-4785-98eb-21ad6a90ed66, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:48,489 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 72545793-acae-4785-98eb-21ad6a90ed66, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:48,502 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 72545793-acae-4785-98eb-21ad6a90ed66, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:49,431 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:49,519 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:49,520 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, fc8d2f60-fff4-4b46-af8a-b93262fa33aa, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:49,521 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, fc8d2f60-fff4-4b46-af8a-b93262fa33aa, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:49,524 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, fc8d2f60-fff4-4b46-af8a-b93262fa33aa, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:49,868 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:49,869 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:49,869 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, f624edb4-ac34-4f60-a299-daa5db819a2e, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:49,870 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, f624edb4-ac34-4f60-a299-daa5db819a2e, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:49,872 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, f624edb4-ac34-4f60-a299-daa5db819a2e, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:50,583 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:50,654 - INFO - __tests__.alter_compression.22630  - 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-05 16:18:50,655 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:50,655 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 7abaf044-079d-40b4-9684-14844a532753, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:50,656 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 7abaf044-079d-40b4-9684-14844a532753, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:50,658 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 7abaf044-079d-40b4-9684-14844a532753, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:51,684 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:51,685 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:51,686 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 6a41d7e3-cced-4834-98e9-d965da3d9fda, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:51,703 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 6a41d7e3-cced-4834-98e9-d965da3d9fda, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:51,706 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 6a41d7e3-cced-4834-98e9-d965da3d9fda, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:52,076 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:52,078 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:52,078 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 082b0ca4-58e9-4c64-999d-67799274f518, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:52,079 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 082b0ca4-58e9-4c64-999d-67799274f518, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:52,081 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 082b0ca4-58e9-4c64-999d-67799274f518, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:52,747 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:52,748 - INFO - __tests__.alter_compression.22630  - 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-05 16:18:52,748 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:52,749 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 3ea7d7dc-e6b3-41dc-8957-b3abb2a21fce, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:52,750 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 3ea7d7dc-e6b3-41dc-8957-b3abb2a21fce, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:52,764 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 3ea7d7dc-e6b3-41dc-8957-b3abb2a21fce, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:54,493 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:54,494 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:54,495 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 9a19ce6b-b84f-4404-af9d-fd094215c620, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:54,533 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 9a19ce6b-b84f-4404-af9d-fd094215c620, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:54,535 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 9a19ce6b-b84f-4404-af9d-fd094215c620, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:54,894 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:54,908 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:54,912 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 23f617d3-3ecd-4f8b-8302-80528b6291b1, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:54,913 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 23f617d3-3ecd-4f8b-8302-80528b6291b1, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:54,915 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 23f617d3-3ecd-4f8b-8302-80528b6291b1, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:55,563 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:55,564 - INFO - __tests__.alter_compression.22630  - 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-05 16:18:55,564 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:55,565 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 4a9f09f2-eb5d-45cf-a541-941dd58835d3, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:55,566 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 4a9f09f2-eb5d-45cf-a541-941dd58835d3, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:55,578 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 4a9f09f2-eb5d-45cf-a541-941dd58835d3, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:56,783 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:56,784 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:56,784 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, f295c8f0-5bb8-4ac9-9c7d-2f3963bee560, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:56,785 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, f295c8f0-5bb8-4ac9-9c7d-2f3963bee560, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:56,879 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, f295c8f0-5bb8-4ac9-9c7d-2f3963bee560, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:57,333 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:57,401 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:57,414 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 1db3a8cb-30f4-45df-b45a-f3d7a454b800, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:57,415 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 1db3a8cb-30f4-45df-b45a-f3d7a454b800, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:57,417 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 1db3a8cb-30f4-45df-b45a-f3d7a454b800, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:58,091 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:58,092 - INFO - __tests__.alter_compression.22630  - 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-05 16:18:58,092 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:58,093 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, f5806444-9cad-4516-b308-3934b6ac42cf, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:58,094 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, f5806444-9cad-4516-b308-3934b6ac42cf, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:58,096 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, f5806444-9cad-4516-b308-3934b6ac42cf, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:59,112 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:59,124 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:59,125 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, d624cfd6-940c-4de3-be5e-c58a6b5d3855, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:59,126 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, d624cfd6-940c-4de3-be5e-c58a6b5d3855, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:59,128 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, d624cfd6-940c-4de3-be5e-c58a6b5d3855, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:59,374 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:59,402 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:18:59,403 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 41e4d3a9-1a39-4e95-b5dd-571f1d104dbb, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:18:59,406 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 41e4d3a9-1a39-4e95-b5dd-571f1d104dbb, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:18:59,413 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 41e4d3a9-1a39-4e95-b5dd-571f1d104dbb, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:00,031 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:00,032 - INFO - __tests__.alter_compression.22630  - 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-05 16:19:00,032 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:00,033 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 7aa9b8ca-e581-4015-974b-77b65534e9d1, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:19:00,034 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 7aa9b8ca-e581-4015-974b-77b65534e9d1, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:19:00,035 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 7aa9b8ca-e581-4015-974b-77b65534e9d1, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:01,085 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:01,114 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:01,126 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, f018be21-54d8-4f36-9c37-619aa9c05f8f, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:19:01,143 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, f018be21-54d8-4f36-9c37-619aa9c05f8f, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:19:01,157 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, f018be21-54d8-4f36-9c37-619aa9c05f8f, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:01,556 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:01,557 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:01,557 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 38390c5c-f8d4-4907-9735-f7065be78929, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:19:01,558 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 38390c5c-f8d4-4907-9735-f7065be78929, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:19:01,560 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 38390c5c-f8d4-4907-9735-f7065be78929, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:02,351 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:02,383 - INFO - __tests__.alter_compression.22630  - 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-05 16:19:02,384 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:02,385 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 837c4194-f650-46f7-b9c6-9f094e06c678, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:19:02,386 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 837c4194-f650-46f7-b9c6-9f094e06c678, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:19:02,387 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 837c4194-f650-46f7-b9c6-9f094e06c678, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:04,053 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:04,054 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:04,055 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, d08682fb-c23b-4ee5-85ef-8cad02dc86ff, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:19:04,056 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, d08682fb-c23b-4ee5-85ef-8cad02dc86ff, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:19:04,057 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, d08682fb-c23b-4ee5-85ef-8cad02dc86ff, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:04,481 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:04,483 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:04,484 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, b20865c9-3730-4d0d-aca6-8c52b604eea0, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:19:04,485 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, b20865c9-3730-4d0d-aca6-8c52b604eea0, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:19:04,486 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, b20865c9-3730-4d0d-aca6-8c52b604eea0, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:05,262 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:05,264 - INFO - __tests__.alter_compression.22630  - 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-05 16:19:05,264 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:05,265 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 0aa31935-422f-485d-81ef-2fd6b03b35f2, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:19:05,266 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 0aa31935-422f-485d-81ef-2fd6b03b35f2, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:19:05,335 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 0aa31935-422f-485d-81ef-2fd6b03b35f2, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:06,741 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:06,742 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:06,742 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, c896c29e-0f48-49fe-bbda-c2521efd6112, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:19:06,743 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, c896c29e-0f48-49fe-bbda-c2521efd6112, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:19:06,744 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, c896c29e-0f48-49fe-bbda-c2521efd6112, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:07,112 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:07,112 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:07,113 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 80b9d990-e71a-420b-b710-01b42a237ae5, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:19:07,114 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 80b9d990-e71a-420b-b710-01b42a237ae5, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:19:07,116 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 80b9d990-e71a-420b-b710-01b42a237ae5, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:07,538 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:07,539 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:07,539 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 837ae5f9-0655-4718-9e9e-9aeb6f8df301, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:19:07,540 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 837ae5f9-0655-4718-9e9e-9aeb6f8df301, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:19:07,542 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 837ae5f9-0655-4718-9e9e-9aeb6f8df301, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:12,984 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:23,028 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:23,029 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 14c4f065-8653-41c4-9f56-4987d4ecd715, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:19:23,030 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 14c4f065-8653-41c4-9f56-4987d4ecd715, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:19:23,031 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 14c4f065-8653-41c4-9f56-4987d4ecd715, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:26,257 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:26,333 - INFO - root.22630  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-12-05 16:19:26,347 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:26,348 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 44320d4d-ffb4-4fa1-80b5-1873dc788db1, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:19:26,349 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 44320d4d-ffb4-4fa1-80b5-1873dc788db1, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:19:26,350 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 44320d4d-ffb4-4fa1-80b5-1873dc788db1, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:29,638 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:39,700 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:39,701 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 3f5923a1-1715-4d42-b0f5-d038140737ab, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:19:39,702 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 3f5923a1-1715-4d42-b0f5-d038140737ab, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:19:39,703 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 3f5923a1-1715-4d42-b0f5-d038140737ab, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:41,372 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:51,392 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:51,394 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, e4dd4f78-b6c4-4055-85f7-8220acf2e269, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:19:51,395 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, e4dd4f78-b6c4-4055-85f7-8220acf2e269, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:19:51,396 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, e4dd4f78-b6c4-4055-85f7-8220acf2e269, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:52,032 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:19:56,166 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22630  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-05 16:19:56,167 - DEBUG - ydb.connection.22630  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-05 16:19:56,175 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22630  - context_resolve: Resolving endpoints for database /Root
2025-12-05 16:19:56,176 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ListEndpoints, 9fcd8391-4ffa-4890-9590-1e928e5033fe, localhost:17371): creating call state
2025-12-05 16:19:56,177 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ListEndpoints, 9fcd8391-4ffa-4890-9590-1e928e5033fe, localhost:17371): request = { database: "/Root" }
2025-12-05 16:19:56,181 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ListEndpoints, 9fcd8391-4ffa-4890-9590-1e928e5033fe, localhost:17371): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-a66hhqwkt4.auto.internal" port: 17371 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-05 16:19:56,181 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22630  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-a66hhqwkt4.auto.internal:17371, location 1, ssl: False>]>
2025-12-05 16:19:56,182 - DEBUG - ydb.connection.22630  - close: Closing channel for endpoint localhost:17371
2025-12-05 16:20:02,045 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:02,047 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 5fbf63d5-1b16-4d00-9cfa-a31829f3dc26, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:02,048 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 5fbf63d5-1b16-4d00-9cfa-a31829f3dc26, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:20:02,049 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 5fbf63d5-1b16-4d00-9cfa-a31829f3dc26, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:02,847 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:02,851 - INFO - root.22630  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-12-05 16:20:02,852 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:02,852 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 747cbab6-ad99-467c-9da7-9593a4d0dd78, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:02,854 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 747cbab6-ad99-467c-9da7-9593a4d0dd78, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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-05 16:20:02,860 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 747cbab6-ad99-467c-9da7-9593a4d0dd78, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:03,193 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:03,211 - INFO - ya.test.22630  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_8_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 8])
2025-12-05 16:20:03,212 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:03,213 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 08eeec19-bec0-4dac-86bc-7307eec9f8d6, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:03,216 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 08eeec19-bec0-4dac-86bc-7307eec9f8d6, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_8_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 8)\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-05 16:20:03,218 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 08eeec19-bec0-4dac-86bc-7307eec9f8d6, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:05,730 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:05,745 - INFO - __tests__.alter_compression.22630  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_8_compression created
2025-12-05 16:20:05,747 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:05,748 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 0ca78191-fdb9-4a22-8a3c-dd3cdb46bc45, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:05,773 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 0ca78191-fdb9-4a22-8a3c-dd3cdb46bc45, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_8_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:05,778 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 0ca78191-fdb9-4a22-8a3c-dd3cdb46bc45, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:06,563 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:06,564 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:06,564 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 4d26e212-a640-45af-870c-00ffa2928377, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:06,565 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 4d26e212-a640-45af-870c-00ffa2928377, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:06,567 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 4d26e212-a640-45af-870c-00ffa2928377, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:08,310 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:08,311 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:08,311 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 4cf67875-4d5c-4dc2-8b8a-a1a18bc3f243, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:08,312 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 4cf67875-4d5c-4dc2-8b8a-a1a18bc3f243, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:08,313 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 4cf67875-4d5c-4dc2-8b8a-a1a18bc3f243, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:09,115 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:09,116 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:09,117 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 12b8314d-4f6c-4e6f-884a-6fbcab311e3f, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:09,118 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 12b8314d-4f6c-4e6f-884a-6fbcab311e3f, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:09,119 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 12b8314d-4f6c-4e6f-884a-6fbcab311e3f, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:10,673 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:10,673 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318016, 'BlobCount': 128}}
2025-12-05 16:20:10,674 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:10,674 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 55cd3a04-204f-4186-941d-b816642a5f00, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:10,675 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 55cd3a04-204f-4186-941d-b816642a5f00, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:10,676 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 55cd3a04-204f-4186-941d-b816642a5f00, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:12,225 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:12,226 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:12,227 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 1da5af96-1d25-4a56-b085-05e2da8f8130, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:12,228 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 1da5af96-1d25-4a56-b085-05e2da8f8130, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:12,229 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 1da5af96-1d25-4a56-b085-05e2da8f8130, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:12,545 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:12,554 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:12,555 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 427fba12-6af0-403d-aa97-8e5c176a711a, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:12,556 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 427fba12-6af0-403d-aa97-8e5c176a711a, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:12,557 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 427fba12-6af0-403d-aa97-8e5c176a711a, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:12,858 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:12,859 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637312, 'BlobCount': 256}}
2025-12-05 16:20:12,859 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:12,860 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 862cc069-8ddd-427c-b8ea-f9c3dcdf02c2, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:12,861 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 862cc069-8ddd-427c-b8ea-f9c3dcdf02c2, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:12,863 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 862cc069-8ddd-427c-b8ea-f9c3dcdf02c2, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:14,661 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:14,662 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:14,663 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 9b181a34-0ab3-4220-83f7-7e0f0e84ccfa, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:14,663 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 9b181a34-0ab3-4220-83f7-7e0f0e84ccfa, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:14,665 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 9b181a34-0ab3-4220-83f7-7e0f0e84ccfa, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:14,891 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:14,892 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:14,893 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 91d7b038-d123-4db3-9576-ede9d178e9e5, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:14,894 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 91d7b038-d123-4db3-9576-ede9d178e9e5, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:14,939 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 91d7b038-d123-4db3-9576-ede9d178e9e5, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:15,423 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:15,424 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956352, 'BlobCount': 384}}
2025-12-05 16:20:15,424 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:15,425 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 82f0ff6e-9d75-47c3-8aa4-b9cc8dbb91c8, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:15,426 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 82f0ff6e-9d75-47c3-8aa4-b9cc8dbb91c8, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:15,428 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 82f0ff6e-9d75-47c3-8aa4-b9cc8dbb91c8, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:17,077 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:17,086 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:17,087 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 93eb38c5-0a3b-44fd-89ea-550615680f53, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:17,098 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 93eb38c5-0a3b-44fd-89ea-550615680f53, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:17,114 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 93eb38c5-0a3b-44fd-89ea-550615680f53, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:17,406 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:17,421 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:17,462 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 9622d931-326d-42e4-ac8b-f3a86ad8614f, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:17,463 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 9622d931-326d-42e4-ac8b-f3a86ad8614f, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:17,464 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 9622d931-326d-42e4-ac8b-f3a86ad8614f, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:17,852 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:17,862 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275712, 'BlobCount': 512}}
2025-12-05 16:20:17,862 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:17,863 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, bb58330d-9286-4cd0-9d6d-ccb7e12aa4ec, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:17,864 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, bb58330d-9286-4cd0-9d6d-ccb7e12aa4ec, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:17,866 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, bb58330d-9286-4cd0-9d6d-ccb7e12aa4ec, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:19,606 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:19,662 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:19,663 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 0ac0f754-8ec3-4268-b1c0-2752126a9b52, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:19,689 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 0ac0f754-8ec3-4268-b1c0-2752126a9b52, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:19,694 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 0ac0f754-8ec3-4268-b1c0-2752126a9b52, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:19,923 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:19,938 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:19,938 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, c266e438-07be-4769-b082-3946cdf85d92, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:19,939 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, c266e438-07be-4769-b082-3946cdf85d92, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:19,941 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, c266e438-07be-4769-b082-3946cdf85d92, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:20,404 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:20,430 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594672, 'BlobCount': 640}}
2025-12-05 16:20:20,445 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:20,461 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 60dd2251-10a0-44ed-9128-63e752ae0808, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:20,491 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 60dd2251-10a0-44ed-9128-63e752ae0808, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:20,493 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 60dd2251-10a0-44ed-9128-63e752ae0808, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:22,314 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:22,314 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:22,315 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, cb919e17-61d8-4f72-9e1a-b9f7f2c0a81e, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:22,328 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, cb919e17-61d8-4f72-9e1a-b9f7f2c0a81e, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:22,339 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, cb919e17-61d8-4f72-9e1a-b9f7f2c0a81e, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:22,559 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:22,564 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:22,565 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 9cc354fb-8eb3-4e26-a649-8aee5af24479, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:22,566 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 9cc354fb-8eb3-4e26-a649-8aee5af24479, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:22,567 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 9cc354fb-8eb3-4e26-a649-8aee5af24479, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:23,114 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:23,122 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913856, 'BlobCount': 768}}
2025-12-05 16:20:23,132 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:23,148 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, b484997a-a966-41cf-a1b2-14dcab329d78, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:23,150 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, b484997a-a966-41cf-a1b2-14dcab329d78, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:23,166 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, b484997a-a966-41cf-a1b2-14dcab329d78, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:24,849 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:24,866 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:24,866 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 59ee8e0b-3f17-48d3-9566-e808432dc006, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:24,882 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 59ee8e0b-3f17-48d3-9566-e808432dc006, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:24,895 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 59ee8e0b-3f17-48d3-9566-e808432dc006, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:25,204 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:25,205 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:25,206 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 95245876-00cf-409f-9785-87603fc777c1, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:25,207 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 95245876-00cf-409f-9785-87603fc777c1, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:25,208 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 95245876-00cf-409f-9785-87603fc777c1, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:25,718 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:25,729 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2232992, 'BlobCount': 896}}
2025-12-05 16:20:25,739 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:25,752 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, bf7884f8-0902-42e1-9fcd-6f7026d4e004, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:25,764 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, bf7884f8-0902-42e1-9fcd-6f7026d4e004, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:25,782 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, bf7884f8-0902-42e1-9fcd-6f7026d4e004, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:27,617 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:27,622 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:27,623 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, e7539e59-90cc-4937-8ca1-38fcc4fbf00d, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:27,633 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, e7539e59-90cc-4937-8ca1-38fcc4fbf00d, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:27,645 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, e7539e59-90cc-4937-8ca1-38fcc4fbf00d, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:27,898 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:27,913 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:27,914 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 107b773a-a55d-4cb4-9975-4d9628feb402, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:27,915 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 107b773a-a55d-4cb4-9975-4d9628feb402, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:27,917 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 107b773a-a55d-4cb4-9975-4d9628feb402, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:28,644 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:28,644 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552480, 'BlobCount': 1024}}
2025-12-05 16:20:28,645 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:28,646 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 24ab6f08-27d3-42cc-9c10-9dd1ee2f6880, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:28,647 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 24ab6f08-27d3-42cc-9c10-9dd1ee2f6880, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:28,648 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 24ab6f08-27d3-42cc-9c10-9dd1ee2f6880, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:31,014 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:31,015 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:31,016 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 96e0951c-72ea-4fe2-afe8-62ec8d8f4ebd, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:31,034 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 96e0951c-72ea-4fe2-afe8-62ec8d8f4ebd, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:31,055 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 96e0951c-72ea-4fe2-afe8-62ec8d8f4ebd, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:31,345 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:31,346 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:31,347 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, df17eb49-deab-479c-8b67-fbb36b6f690c, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:31,348 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, df17eb49-deab-479c-8b67-fbb36b6f690c, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:31,350 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, df17eb49-deab-479c-8b67-fbb36b6f690c, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:32,004 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:32,005 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871488, 'BlobCount': 1152}}
2025-12-05 16:20:32,005 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:32,006 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 3bc55186-fab1-4fa2-8093-7a9e16a59ec2, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:32,006 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 3bc55186-fab1-4fa2-8093-7a9e16a59ec2, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:32,007 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 3bc55186-fab1-4fa2-8093-7a9e16a59ec2, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:34,093 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:34,093 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:34,094 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 8a67af99-8bf1-467c-9dc6-72023326606c, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:34,095 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 8a67af99-8bf1-467c-9dc6-72023326606c, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:34,096 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 8a67af99-8bf1-467c-9dc6-72023326606c, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:34,410 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:34,430 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:34,431 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 7f03cf11-a122-40c9-bec9-e89b79648302, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:34,432 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 7f03cf11-a122-40c9-bec9-e89b79648302, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:34,434 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 7f03cf11-a122-40c9-bec9-e89b79648302, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:35,195 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:35,195 - INFO - __tests__.alter_compression.22630  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3190992, 'BlobCount': 1280}}
2025-12-05 16:20:35,198 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:35,206 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, d89ea5c3-8f13-418d-8b1d-614eda0e629e, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:35,213 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, d89ea5c3-8f13-418d-8b1d-614eda0e629e, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_8_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:35,228 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, d89ea5c3-8f13-418d-8b1d-614eda0e629e, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:36,574 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:36,575 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:36,576 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, f7c7c757-404b-429a-ad21-2e12f7c9b036, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:36,577 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, f7c7c757-404b-429a-ad21-2e12f7c9b036, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:36,578 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, f7c7c757-404b-429a-ad21-2e12f7c9b036, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:37,359 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:37,365 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:37,366 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 3809d4c5-56c4-4b37-8d3f-bbd0c161cb47, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:37,390 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 3809d4c5-56c4-4b37-8d3f-bbd0c161cb47, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:37,418 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 3809d4c5-56c4-4b37-8d3f-bbd0c161cb47, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:38,173 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:38,175 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:38,176 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 04a4cdd3-7461-446a-ae28-ffa4dd7a4a0b, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:38,177 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 04a4cdd3-7461-446a-ae28-ffa4dd7a4a0b, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_8_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-05 16:20:38,194 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 04a4cdd3-7461-446a-ae28-ffa4dd7a4a0b, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:45,113 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:55,157 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:55,158 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, b23f3a5a-4320-42ed-818d-b98739b9b6f7, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:55,159 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, b23f3a5a-4320-42ed-818d-b98739b9b6f7, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_8_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-05 16:20:55,160 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, b23f3a5a-4320-42ed-818d-b98739b9b6f7, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:58,700 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:58,748 - INFO - root.22630  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_8_compression`, volumes `value` (8000000, 1595496)
2025-12-05 16:20:58,749 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:58,790 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 07e0cf43-6801-4013-b741-5bdfb1ea3561, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:58,791 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 07e0cf43-6801-4013-b741-5bdfb1ea3561, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" 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_8_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-05 16:20:58,792 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 07e0cf43-6801-4013-b741-5bdfb1ea3561, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:59,143 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:59,144 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:20:59,145 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, df5b4dd0-4d8c-416a-9973-ad081d03623c, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:20:59,164 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, df5b4dd0-4d8c-416a-9973-ad081d03623c, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_8_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-05 16:20:59,172 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, df5b4dd0-4d8c-416a-9973-ad081d03623c, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:00,669 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22630  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-05 16:21:00,670 - DEBUG - ydb.connection.22630  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-05 16:21:00,701 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22630  - context_resolve: Resolving endpoints for database /Root
2025-12-05 16:21:00,705 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ListEndpoints, f2ca0c86-47ea-462b-939f-2cf9992c7886, localhost:17371): creating call state
2025-12-05 16:21:00,706 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ListEndpoints, f2ca0c86-47ea-462b-939f-2cf9992c7886, localhost:17371): request = { database: "/Root" }
2025-12-05 16:21:00,759 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ListEndpoints, f2ca0c86-47ea-462b-939f-2cf9992c7886, localhost:17371): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-a66hhqwkt4.auto.internal" port: 17371 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-05 16:21:00,763 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.22630  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-a66hhqwkt4.auto.internal:17371, location 1, ssl: False>]>
2025-12-05 16:21:00,764 - DEBUG - ydb.connection.22630  - close: Closing channel for endpoint localhost:17371
2025-12-05 16:21:03,116 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:13,155 - DEBUG - ydb.query.pool.22630  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:13,160 - DEBUG - ydb.connection.22630  - _prepare_call: RpcState(ExecuteQuery, 6e323ac0-5d6e-4d14-9cb3-5d423343cde3, ghrun-a66hhqwkt4.auto.internal:17371): creating call state
2025-12-05 16:21:13,169 - DEBUG - ydb.connection.22630  - _log_request: RpcState(ExecuteQuery, 6e323ac0-5d6e-4d14-9cb3-5d423343cde3, ghrun-a66hhqwkt4.auto.internal:17371): request = { session_id: "ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_8_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-05 16:21:13,176 - DEBUG - ydb.connection.22630  - _log_response: RpcState(ExecuteQuery, 6e323ac0-5d6e-4d14-9cb3-5d423343cde3, ghrun-a66hhqwkt4.auto.internal:17371): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:13,770 - DEBUG - ydb.query.pool.22630  - release: Session returned to queue: ydb://session/3?node_id=1&id=OWQxNzZjNWMtY2RlYmM3OWItZjgwMWY4ZjgtNzEzNjUyNmI=
2025-12-05 16:21:13,784 - INFO - root.22630  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_8_compression`, volumes `value` (8000000, 1465240)
2025-12-05 16:21:13,789 - INFO - root.22630  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_8_compression` 8092160 / 1465240: 5.522753951571073
2025-12-05 16:21:13,826 - INFO - ya.test.22630  - pytest_runtest_teardown: Test teardown
