2025-12-23 15:36:52,234 - INFO - ya.test.670426  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:36:52,235 - INFO - ya.test.670426  - pytest_runtest_setup: test_all_supported_compression[zstd_18_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 18]
2025-12-23 15:36:52,235 - INFO - ya.test.670426  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:36:52,235 - INFO - ya.test.670426  - pytest_runtest_setup: Test setup
2025-12-23 15:36:52,237 - DEBUG - ya.test.670426  - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-23 15:36:52,238 - DEBUG - ya.test.670426  - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-23 15:36:52,239 - DEBUG - ya.test.670426  - execute: Executing '['/home/runner/.ya/build/build_root/wyw9/00219b/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-23 15:36:52,249 - DEBUG - ya.test.670426  - execute: Command pid: 673532
2025-12-23 15:36:53,148 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) rc: 0
2025-12-23 15:36:53,149 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) elapsed time (sec): 0.8980674743652344
2025-12-23 15:36:53,149 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) idrss: 0
2025-12-23 15:36:53,149 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) inblock: 0
2025-12-23 15:36:53,150 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) isrss: 0
2025-12-23 15:36:53,150 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) ixrss: 0
2025-12-23 15:36:53,150 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) majflt: 0
2025-12-23 15:36:53,151 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) maxrss: 498888
2025-12-23 15:36:53,151 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) minflt: 45774
2025-12-23 15:36:53,151 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) msgrcv: 0
2025-12-23 15:36:53,151 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) msgsnd: 0
2025-12-23 15:36:53,152 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) nivcsw: 173
2025-12-23 15:36:53,152 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) nsignals: 0
2025-12-23 15:36:53,152 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) nswap: 0
2025-12-23 15:36:53,152 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) nvcsw: 14
2025-12-23 15:36:53,153 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) oublock: 8
2025-12-23 15:36:53,153 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) stime: 0.24917599999999998
2025-12-23 15:36:53,153 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) utime: 0.48935199999999995
2025-12-23 15:36:53,154 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) wtime: 0.909
2025-12-23 15:36:53,154 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) output:
Git info:
    Commit: abc2d1f3c1b023db8ecb13b079f91fe24aeb8bee
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 8cd317bbcadd236db71e9829f8fc42e5bf5c2d0b into 54d3846fcfd20f009eeeabbfe05c19f647cd92d0
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-icmf7pzugu    Host information: 
        Linux ghrun-icmf7pzugu 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-23 15:36:53,154 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 673532) errors:

2025-12-23 15:36:53,155 - INFO - __tests__.base.670426  - _setup_ydb: Git info:
    Commit: abc2d1f3c1b023db8ecb13b079f91fe24aeb8bee
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 8cd317bbcadd236db71e9829f8fc42e5bf5c2d0b into 54d3846fcfd20f009eeeabbfe05c19f647cd92d0
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-icmf7pzugu    Host information: 
        Linux ghrun-icmf7pzugu 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-23 15:36:53,260 - DEBUG - ya.test.670426  - get_binary: Binary was found by /home/runner/.ya/build/build_root/wyw9/00219b/ydb/apps/ydbd/ydbd
2025-12-23 15:36:53,262 - DEBUG - library.python.filelock.670426  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/64843
2025-12-23 15:36:53,264 - DEBUG - ydb.tests.library.harness.kikimr_runner.670426  - start: Working directory: /home/runner/.ya/build/build_root/wyw9/00219b/r3tmp/kikimr_cluster_qsy2qys6
2025-12-23 15:36:53,303 - DEBUG - ya.test.670426  - get_binary: Binary was found by /home/runner/.ya/build/build_root/wyw9/00219b/ydb/apps/ydbd/ydbd
2025-12-23 15:36:53,304 - DEBUG - library.python.filelock.670426  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24463
2025-12-23 15:36:53,305 - DEBUG - library.python.filelock.670426  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9198
2025-12-23 15:36:53,305 - DEBUG - library.python.filelock.670426  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20497
2025-12-23 15:36:53,306 - DEBUG - library.python.filelock.670426  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9654
2025-12-23 15:36:53,307 - INFO - ydb.tests.library.harness.kikimr_runner.670426  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/wyw9/00219b/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs"
2025-12-23 15:36:53,307 - INFO - ydb.tests.library.harness.kikimr_runner.670426  - __make_run_command: Final command: /home/runner/.ya/build/build_root/wyw9/00219b/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/wyw9/00219b/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_id5zppky.log --grpc-port=24463 --mon-port=9198 --ic-port=64843
2025-12-23 15:36:53,308 - DEBUG - ydb.tests.library.harness.kikimr_runner.670426  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:24463/1, disk_size 68719476736
2025-12-23 15:36:53,308 - INFO - ydb.tests.library.harness.kikimr_runner.670426  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/wyw9/00219b/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs"
2025-12-23 15:36:53,309 - INFO - ydb.tests.library.harness.kikimr_runner.670426  - __make_run_command: Final command: /home/runner/.ya/build/build_root/wyw9/00219b/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/wyw9/00219b/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_id5zppky.log --grpc-port=24463 --mon-port=9198 --ic-port=64843
2025-12-23 15:36:53,310 - DEBUG - ya.test.670426  - execute: Executing '('/home/runner/.ya/build/build_root/wyw9/00219b/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/wyw9/00219b/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/wyw9/00219b/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_id5zppky.log', '--grpc-port=24463', '--mon-port=9198', '--ic-port=64843')' in '/home/runner/.ya/build/build_root/wyw9/00219b/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1' (no wait)
2025-12-23 15:36:53,312 - DEBUG - ya.test.670426  - execute: Command pid: 674253
2025-12-23 15:36:53,313 - INFO - ydb.tests.library.harness.kikimr_runner.670426  - start: Started node localhost:24463/1
2025-12-23 15:36:53,314 - DEBUG - ya.test.670426  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-23 15:36:53,315 - DEBUG - ya.test.670426  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-23 15:36:53,315 - DEBUG - ya.test.670426  - execute: Executing '['/home/runner/.ya/build/build_root/wyw9/00219b/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-23 15:36:53,329 - DEBUG - ya.test.670426  - execute: Command pid: 674259
2025-12-23 15:36:54,264 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) rc: 0
2025-12-23 15:36:54,265 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) elapsed time (sec): 0.9343883991241455
2025-12-23 15:36:54,265 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) idrss: 0
2025-12-23 15:36:54,265 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) inblock: 0
2025-12-23 15:36:54,266 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) isrss: 0
2025-12-23 15:36:54,266 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) ixrss: 0
2025-12-23 15:36:54,266 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) majflt: 0
2025-12-23 15:36:54,266 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) maxrss: 509632
2025-12-23 15:36:54,266 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) minflt: 45880
2025-12-23 15:36:54,267 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) msgrcv: 0
2025-12-23 15:36:54,267 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) msgsnd: 0
2025-12-23 15:36:54,267 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) nivcsw: 269
2025-12-23 15:36:54,267 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) nsignals: 0
2025-12-23 15:36:54,268 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) nswap: 0
2025-12-23 15:36:54,276 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) nvcsw: 14
2025-12-23 15:36:54,276 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) oublock: 8
2025-12-23 15:36:54,276 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) stime: 0.209784
2025-12-23 15:36:54,277 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) utime: 0.508164
2025-12-23 15:36:54,277 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) wtime: 0.948
2025-12-23 15:36:54,277 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) output:
Git info:
    Commit: abc2d1f3c1b023db8ecb13b079f91fe24aeb8bee
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 8cd317bbcadd236db71e9829f8fc42e5bf5c2d0b into 54d3846fcfd20f009eeeabbfe05c19f647cd92d0
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-icmf7pzugu    Host information: 
        Linux ghrun-icmf7pzugu 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-23 15:36:54,278 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 674259) errors:

2025-12-23 15:36:54,278 - INFO - ydb.tests.library.harness.kikimr_runner.670426  - start: Node 1 version:
Git info:
    Commit: abc2d1f3c1b023db8ecb13b079f91fe24aeb8bee
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 8cd317bbcadd236db71e9829f8fc42e5bf5c2d0b into 54d3846fcfd20f009eeeabbfe05c19f647cd92d0
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-icmf7pzugu    Host information: 
        Linux ghrun-icmf7pzugu 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-23 15:36:59,203 - DEBUG - ya.test.670426  - get_binary: Binary was found by /home/runner/.ya/build/build_root/wyw9/00219b/ydb/apps/ydbd/ydbd
2025-12-23 15:36:59,203 - DEBUG - ydb.tests.library.harness.kikimr_runner.670426  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/wyw9/00219b/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:24463', '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: 64843\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-23 15:36:59,205 - DEBUG - ya.test.670426  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-23 15:36:59,205 - DEBUG - ya.test.670426  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-23 15:36:59,206 - DEBUG - ya.test.670426  - execute: Executing '['/home/runner/.ya/build/build_root/wyw9/00219b/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:24463', '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: 64843\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-23 15:36:59,207 - DEBUG - ya.test.670426  - execute: Command pid: 678413
2025-12-23 15:37:00,170 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) rc: 0
2025-12-23 15:37:00,170 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) elapsed time (sec): 0.9616796970367432
2025-12-23 15:37:00,171 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) idrss: 0
2025-12-23 15:37:00,171 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) inblock: 0
2025-12-23 15:37:00,171 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) isrss: 0
2025-12-23 15:37:00,172 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) ixrss: 0
2025-12-23 15:37:00,172 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) majflt: 0
2025-12-23 15:37:00,172 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) maxrss: 599272
2025-12-23 15:37:00,173 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) minflt: 52259
2025-12-23 15:37:00,173 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) msgrcv: 0
2025-12-23 15:37:00,173 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) msgsnd: 0
2025-12-23 15:37:00,173 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) nivcsw: 132
2025-12-23 15:37:00,174 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) nsignals: 0
2025-12-23 15:37:00,174 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) nswap: 0
2025-12-23 15:37:00,174 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) nvcsw: 881
2025-12-23 15:37:00,175 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) oublock: 8
2025-12-23 15:37:00,175 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) stime: 0.367044
2025-12-23 15:37:00,175 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) utime: 0.513594
2025-12-23 15:37:00,176 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) wtime: 0.963
2025-12-23 15:37:00,176 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-23 15:37:00,176 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 678413) errors:

2025-12-23 15:37:00,178 - DEBUG - ya.test.670426  - get_binary: Binary was found by /home/runner/.ya/build/build_root/wyw9/00219b/ydb/apps/ydbd/ydbd
2025-12-23 15:37:00,178 - DEBUG - ydb.tests.library.harness.kikimr_runner.670426  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/wyw9/00219b/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:24463', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-12-23 15:37:00,179 - DEBUG - ya.test.670426  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-23 15:37:00,180 - DEBUG - ya.test.670426  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-23 15:37:00,180 - DEBUG - ya.test.670426  - execute: Executing '['/home/runner/.ya/build/build_root/wyw9/00219b/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:24463', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-23 15:37:00,184 - DEBUG - ya.test.670426  - execute: Command pid: 679183
2025-12-23 15:37:01,012 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) rc: 0
2025-12-23 15:37:01,013 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) elapsed time (sec): 0.8238868713378906
2025-12-23 15:37:01,013 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) idrss: 0
2025-12-23 15:37:01,014 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) inblock: 0
2025-12-23 15:37:01,014 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) isrss: 0
2025-12-23 15:37:01,014 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) ixrss: 0
2025-12-23 15:37:01,014 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) majflt: 0
2025-12-23 15:37:01,015 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) maxrss: 599272
2025-12-23 15:37:01,015 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) minflt: 52216
2025-12-23 15:37:01,015 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) msgrcv: 0
2025-12-23 15:37:01,015 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) msgsnd: 0
2025-12-23 15:37:01,016 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) nivcsw: 100
2025-12-23 15:37:01,016 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) nsignals: 0
2025-12-23 15:37:01,017 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) nswap: 0
2025-12-23 15:37:01,017 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) nvcsw: 574
2025-12-23 15:37:01,018 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) oublock: 8
2025-12-23 15:37:01,018 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) stime: 0.210424
2025-12-23 15:37:01,018 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) utime: 0.535586
2025-12-23 15:37:01,019 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) wtime: 0.827
2025-12-23 15:37:01,019 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-23 15:37:01,019 - DEBUG - ya.test.670426  - _save_outputs: Command (pid 679183) errors:

2025-12-23 15:37:01,020 - INFO - ydb.tests.library.harness.kikimr_runner.670426  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-23 15:37:01,134 - INFO - ydb.tests.library.harness.kikimr_runner.670426  - __run: Cluster started and initialized
2025-12-23 15:37:01,202 - DEBUG - ydb.connection.670426  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 15:37:01,220 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.670426  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 15:37:01,221 - DEBUG - ydb.connection.670426  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 15:37:01,249 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.670426  - context_resolve: Resolving endpoints for database /Root
2025-12-23 15:37:01,250 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ListEndpoints, 628413d1-7802-4547-b366-ecf59e1cbb2d, localhost:24463): creating call state
2025-12-23 15:37:01,251 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ListEndpoints, 628413d1-7802-4547-b366-ecf59e1cbb2d, localhost:24463): request = { database: "/Root" }
2025-12-23 15:37:01,262 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ListEndpoints, 628413d1-7802-4547-b366-ecf59e1cbb2d, localhost:24463): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-icmf7pzugu.auto.internal" port: 24463 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-12-23 15:37:01,264 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.670426  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-icmf7pzugu.auto.internal:24463, location 1, ssl: False>]>
2025-12-23 15:37:01,265 - DEBUG - ydb.connection.670426  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 15:37:01,310 - DEBUG - ydb.connection.670426  - close: Closing channel for endpoint localhost:24463
2025-12-23 15:37:01,311 - DEBUG - ydb.query.pool.670426  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-23 15:37:01,313 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(CreateSession, 42ffc2c7-f0c9-47ea-ac03-173dff12f5c7, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:01,314 - DEBUG - ydb.connection.670426  - _log_request: RpcState(CreateSession, 42ffc2c7-f0c9-47ea-ac03-173dff12f5c7, ghrun-icmf7pzugu.auto.internal:24463): request = {  }
2025-12-23 15:37:01,322 - DEBUG - ydb.connection.670426  - _log_response: RpcState(CreateSession, 42ffc2c7-f0c9-47ea-ac03-173dff12f5c7, ghrun-icmf7pzugu.auto.internal:24463): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" node_id: 1 }
2025-12-23 15:37:01,324 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(AttachSession, 7ad28dca-2926-4ecc-ae07-623dd998ca41, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:01,324 - DEBUG - ydb.connection.670426  - _log_request: RpcState(AttachSession, 7ad28dca-2926-4ecc-ae07-623dd998ca41, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" }
2025-12-23 15:37:01,337 - DEBUG - ydb.connection.670426  - _log_response: RpcState(AttachSession, 7ad28dca-2926-4ecc-ae07-623dd998ca41, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:01,354 - DEBUG - ydb.query.pool.670426  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:01,355 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 531c71cc-f8ec-4adf-945f-8a0950ad52d3, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:01,356 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 531c71cc-f8ec-4adf-945f-8a0950ad52d3, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:01,358 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 531c71cc-f8ec-4adf-945f-8a0950ad52d3, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:03,470 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:03,471 - INFO - __tests__.alter_compression.670426  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-12-23 15:37:03,471 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:03,472 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, f9adf82e-41ae-42b9-a842-03dba1911caf, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:03,473 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, f9adf82e-41ae-42b9-a842-03dba1911caf, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:03,475 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, f9adf82e-41ae-42b9-a842-03dba1911caf, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:04,318 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:04,319 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:04,320 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 8e23f81e-845e-4dde-8bfb-f673ade36c47, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:04,321 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 8e23f81e-845e-4dde-8bfb-f673ade36c47, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:04,322 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 8e23f81e-845e-4dde-8bfb-f673ade36c47, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:05,342 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:05,343 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:05,343 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 3463bd8e-f5d6-44ab-bbe5-7273b73ceaf8, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:05,344 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 3463bd8e-f5d6-44ab-bbe5-7273b73ceaf8, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:05,345 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 3463bd8e-f5d6-44ab-bbe5-7273b73ceaf8, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:06,099 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:06,099 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:06,100 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, d56b7f5a-839f-49f2-a7be-fb76de9afbfb, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:06,101 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, d56b7f5a-839f-49f2-a7be-fb76de9afbfb, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:06,102 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, d56b7f5a-839f-49f2-a7be-fb76de9afbfb, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:07,375 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:07,375 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 1618432, 'BlobCount': 128}}
2025-12-23 15:37:07,376 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:07,376 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 1b6a3f4f-553d-48a9-a2f1-da142a05ec3f, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:07,377 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 1b6a3f4f-553d-48a9-a2f1-da142a05ec3f, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:07,378 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 1b6a3f4f-553d-48a9-a2f1-da142a05ec3f, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:08,168 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:08,169 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:08,170 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 0447c116-e660-4999-805b-e71ba19c7e67, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:08,171 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 0447c116-e660-4999-805b-e71ba19c7e67, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:08,172 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 0447c116-e660-4999-805b-e71ba19c7e67, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:08,377 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:08,378 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:08,379 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, a63b5273-1d46-49fd-a3ad-e72a4d7fa190, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:08,380 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, a63b5273-1d46-49fd-a3ad-e72a4d7fa190, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:08,381 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, a63b5273-1d46-49fd-a3ad-e72a4d7fa190, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:08,601 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:08,601 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 3236864, 'BlobCount': 256}}
2025-12-23 15:37:08,602 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:08,603 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 3188fdab-1e50-4479-917f-b006a61039f1, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:08,603 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 3188fdab-1e50-4479-917f-b006a61039f1, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:08,605 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 3188fdab-1e50-4479-917f-b006a61039f1, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:09,374 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:09,375 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:09,376 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 86a204eb-f0d7-4ee5-bae4-1d602eda21dd, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:09,377 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 86a204eb-f0d7-4ee5-bae4-1d602eda21dd, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:09,378 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 86a204eb-f0d7-4ee5-bae4-1d602eda21dd, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:09,571 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:09,572 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:09,573 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, d4a5495f-57a5-4424-a0e8-dc1a52b93927, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:09,573 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, d4a5495f-57a5-4424-a0e8-dc1a52b93927, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:09,575 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, d4a5495f-57a5-4424-a0e8-dc1a52b93927, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:09,865 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:09,866 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 4855296, 'BlobCount': 384}}
2025-12-23 15:37:09,866 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:09,867 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, f19888ce-0cef-485e-8cf1-62e1f5800c37, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:09,868 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, f19888ce-0cef-485e-8cf1-62e1f5800c37, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:09,869 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, f19888ce-0cef-485e-8cf1-62e1f5800c37, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:10,712 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:10,713 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:10,713 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 48ecb259-173b-4317-9be6-75932f8513ac, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:10,714 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 48ecb259-173b-4317-9be6-75932f8513ac, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:10,716 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 48ecb259-173b-4317-9be6-75932f8513ac, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:10,890 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:10,891 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:10,892 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 8ae2b465-d9ad-4373-a221-68b3d8b74b56, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:10,893 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 8ae2b465-d9ad-4373-a221-68b3d8b74b56, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:10,894 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 8ae2b465-d9ad-4373-a221-68b3d8b74b56, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:11,190 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:11,190 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 6473728, 'BlobCount': 512}}
2025-12-23 15:37:11,191 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:11,192 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 3a34257b-2ad7-4838-9237-2d97dca0fb65, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:11,193 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 3a34257b-2ad7-4838-9237-2d97dca0fb65, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:11,194 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 3a34257b-2ad7-4838-9237-2d97dca0fb65, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:12,050 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:12,051 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:12,052 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 7bafff10-c353-4aae-9702-291ad276199e, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:12,053 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 7bafff10-c353-4aae-9702-291ad276199e, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:12,055 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 7bafff10-c353-4aae-9702-291ad276199e, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:12,228 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:12,228 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:12,229 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, e371f3b6-da8c-4c75-b6e5-2e59733d2689, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:12,230 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, e371f3b6-da8c-4c75-b6e5-2e59733d2689, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:12,230 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, e371f3b6-da8c-4c75-b6e5-2e59733d2689, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:12,570 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:12,570 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 8092160, 'BlobCount': 640}}
2025-12-23 15:37:12,571 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:12,571 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, a6f16b31-7ecc-4dd2-bcf7-7a60fbc18ccb, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:12,572 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, a6f16b31-7ecc-4dd2-bcf7-7a60fbc18ccb, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:12,573 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, a6f16b31-7ecc-4dd2-bcf7-7a60fbc18ccb, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:13,377 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:13,378 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:13,379 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 15ee6f61-d334-40c3-9c07-fa6c95e87193, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:13,379 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 15ee6f61-d334-40c3-9c07-fa6c95e87193, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:13,380 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 15ee6f61-d334-40c3-9c07-fa6c95e87193, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:13,766 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:13,767 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:13,768 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, deae0a3f-5031-48a2-bcb0-955a1a55537a, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:13,769 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, deae0a3f-5031-48a2-bcb0-955a1a55537a, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:13,770 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, deae0a3f-5031-48a2-bcb0-955a1a55537a, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:14,137 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:14,137 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 9710592, 'BlobCount': 768}}
2025-12-23 15:37:14,138 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:14,139 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, cf8a5bc3-35ef-415b-91bf-e0f4661bd3b8, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:14,140 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, cf8a5bc3-35ef-415b-91bf-e0f4661bd3b8, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:14,141 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, cf8a5bc3-35ef-415b-91bf-e0f4661bd3b8, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:14,985 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:14,986 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:14,987 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, dd89a890-cb30-4de5-a3f7-a18a36847f06, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:14,988 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, dd89a890-cb30-4de5-a3f7-a18a36847f06, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:14,991 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, dd89a890-cb30-4de5-a3f7-a18a36847f06, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:15,223 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:15,224 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:15,224 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 1d5aff53-acf0-4b10-abda-64c457d7a1ef, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:15,225 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 1d5aff53-acf0-4b10-abda-64c457d7a1ef, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:15,226 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 1d5aff53-acf0-4b10-abda-64c457d7a1ef, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:15,743 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:15,743 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 11329024, 'BlobCount': 896}}
2025-12-23 15:37:15,744 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:15,745 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 605b142a-c05d-4664-aaac-3a6b549e96b2, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:15,745 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 605b142a-c05d-4664-aaac-3a6b549e96b2, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:15,748 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 605b142a-c05d-4664-aaac-3a6b549e96b2, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:16,773 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:16,774 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:16,775 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 29c4fbf8-965d-4972-9214-5cc22b2ddb35, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:16,775 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 29c4fbf8-965d-4972-9214-5cc22b2ddb35, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:16,777 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 29c4fbf8-965d-4972-9214-5cc22b2ddb35, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:17,051 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:17,052 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:17,052 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 30b5ee78-cbe6-49dd-9ce1-bf374a59286e, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:17,053 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 30b5ee78-cbe6-49dd-9ce1-bf374a59286e, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:17,054 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 30b5ee78-cbe6-49dd-9ce1-bf374a59286e, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:17,660 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:17,660 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 12947456, 'BlobCount': 1024}}
2025-12-23 15:37:17,661 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:17,661 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 01ad3bb4-f9b4-4859-b3e8-502790b0e8f0, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:17,662 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 01ad3bb4-f9b4-4859-b3e8-502790b0e8f0, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:17,663 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 01ad3bb4-f9b4-4859-b3e8-502790b0e8f0, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:18,665 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:18,665 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:18,666 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, b3cf1898-7251-42d0-9c0c-695d9bccb1a4, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:18,667 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, b3cf1898-7251-42d0-9c0c-695d9bccb1a4, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:18,669 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, b3cf1898-7251-42d0-9c0c-695d9bccb1a4, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:19,005 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:19,005 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:19,006 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 28a0a78a-f62a-442e-9ed4-ac353594ff2f, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:19,007 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 28a0a78a-f62a-442e-9ed4-ac353594ff2f, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:19,012 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 28a0a78a-f62a-442e-9ed4-ac353594ff2f, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:19,552 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:19,553 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 14565888, 'BlobCount': 1152}}
2025-12-23 15:37:19,553 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:19,554 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, a7d26589-645b-4f9d-a974-dc668f71a8f2, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:19,555 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, a7d26589-645b-4f9d-a974-dc668f71a8f2, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:19,557 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, a7d26589-645b-4f9d-a974-dc668f71a8f2, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:20,552 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:20,553 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:20,554 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 38d2566e-3aa4-4b91-a8c5-0cf5b6fabdfd, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:20,555 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 38d2566e-3aa4-4b91-a8c5-0cf5b6fabdfd, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:20,557 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 38d2566e-3aa4-4b91-a8c5-0cf5b6fabdfd, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:20,860 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:20,861 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:20,862 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 2069319f-3ace-49d1-a5b0-eea02cf321c2, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:20,863 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 2069319f-3ace-49d1-a5b0-eea02cf321c2, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:20,865 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 2069319f-3ace-49d1-a5b0-eea02cf321c2, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:21,570 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:21,571 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 16184320, 'BlobCount': 1280}}
2025-12-23 15:37:21,572 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:21,572 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, f980e298-638a-4205-8353-25e07de302dc, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:21,573 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, f980e298-638a-4205-8353-25e07de302dc, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:21,575 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, f980e298-638a-4205-8353-25e07de302dc, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:22,490 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:22,491 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:22,492 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, a9e030e7-0b7b-4a8c-95b3-7aca83a5d51f, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:22,493 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, a9e030e7-0b7b-4a8c-95b3-7aca83a5d51f, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:22,494 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, a9e030e7-0b7b-4a8c-95b3-7aca83a5d51f, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:22,717 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:22,718 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:22,718 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, eceb423d-b88e-4fdb-8156-d10193ca2c8d, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:22,719 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, eceb423d-b88e-4fdb-8156-d10193ca2c8d, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:22,723 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, eceb423d-b88e-4fdb-8156-d10193ca2c8d, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:22,967 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:22,968 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:22,969 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, ffd98fb9-70e0-448a-86a8-0f911922cd5f, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:22,970 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, ffd98fb9-70e0-448a-86a8-0f911922cd5f, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:22,971 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, ffd98fb9-70e0-448a-86a8-0f911922cd5f, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:26,356 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:36,392 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:36,393 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 48f3bb84-bfce-4f40-aaab-3e2e5e07648d, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:36,394 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 48f3bb84-bfce-4f40-aaab-3e2e5e07648d, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:36,396 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 48f3bb84-bfce-4f40-aaab-3e2e5e07648d, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:39,312 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:39,348 - INFO - root.670426  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-12-23 15:37:39,349 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:39,349 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 382b7f31-0e85-4f53-9640-57becf804990, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:39,350 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 382b7f31-0e85-4f53-9640-57becf804990, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:39,351 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 382b7f31-0e85-4f53-9640-57becf804990, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:42,026 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:52,076 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:52,077 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, b02dd7a9-6fe7-42bd-a837-b8d398904e5b, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:52,078 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, b02dd7a9-6fe7-42bd-a837-b8d398904e5b, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:52,080 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, b02dd7a9-6fe7-42bd-a837-b8d398904e5b, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:55,203 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:55,234 - INFO - root.670426  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-12-23 15:37:55,234 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:55,235 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, ae2d8efd-22d4-4116-b2e8-289796fc2160, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:55,236 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, ae2d8efd-22d4-4116-b2e8-289796fc2160, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:55,237 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, ae2d8efd-22d4-4116-b2e8-289796fc2160, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:55,577 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:55,579 - INFO - ya.test.670426  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_18_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 18])
2025-12-23 15:37:55,580 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:55,581 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 1e1c7ceb-ab4b-4328-ac4b-67e6f5d1a379, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:55,582 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 1e1c7ceb-ab4b-4328-ac4b-67e6f5d1a379, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_18_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 18)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:55,583 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 1e1c7ceb-ab4b-4328-ac4b-67e6f5d1a379, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:58,088 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:58,088 - INFO - __tests__.alter_compression.670426  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_18_compression created
2025-12-23 15:37:58,089 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:58,089 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, c7f421f6-da1d-47e2-813d-094a8ce0456e, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:58,090 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, c7f421f6-da1d-47e2-813d-094a8ce0456e, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_18_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:58,091 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, c7f421f6-da1d-47e2-813d-094a8ce0456e, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:58,795 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:58,795 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:37:58,796 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, a3f3b3f6-99b1-4c9c-a936-6391cbdc033c, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:37:58,797 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, a3f3b3f6-99b1-4c9c-a936-6391cbdc033c, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:58,799 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, a3f3b3f6-99b1-4c9c-a936-6391cbdc033c, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:02,721 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:02,722 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:02,723 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 365e02cf-c5f6-4606-8331-2b8c2fbce049, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:02,724 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 365e02cf-c5f6-4606-8331-2b8c2fbce049, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:02,725 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 365e02cf-c5f6-4606-8331-2b8c2fbce049, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:03,848 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:03,848 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:03,849 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, af834ad1-6c8a-4c9a-b5cd-5e394983c4b8, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:03,850 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, af834ad1-6c8a-4c9a-b5cd-5e394983c4b8, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:03,851 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, af834ad1-6c8a-4c9a-b5cd-5e394983c4b8, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:05,358 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:05,359 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-12-23 15:38:05,359 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:05,360 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 75b0f2e3-ac8d-440e-8f14-67f3b3a9ba7a, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:05,361 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 75b0f2e3-ac8d-440e-8f14-67f3b3a9ba7a, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:05,362 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 75b0f2e3-ac8d-440e-8f14-67f3b3a9ba7a, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:09,148 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:09,149 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:09,150 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, e9b611d2-efe5-43a3-b6aa-af8d7184492d, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:09,151 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, e9b611d2-efe5-43a3-b6aa-af8d7184492d, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:09,153 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, e9b611d2-efe5-43a3-b6aa-af8d7184492d, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:09,585 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:09,585 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:09,625 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 34ca58a9-70bc-471d-9397-61b636b56506, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:09,626 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 34ca58a9-70bc-471d-9397-61b636b56506, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:09,627 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 34ca58a9-70bc-471d-9397-61b636b56506, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:10,154 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:10,154 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-12-23 15:38:10,155 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:10,156 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 653bc554-e0b4-4668-9e32-c508fd7fc33a, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:10,156 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 653bc554-e0b4-4668-9e32-c508fd7fc33a, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:10,157 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 653bc554-e0b4-4668-9e32-c508fd7fc33a, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:14,251 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:14,252 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:14,253 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 6424316b-cd2d-4b44-a46c-a85d8d7cc0be, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:14,253 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 6424316b-cd2d-4b44-a46c-a85d8d7cc0be, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:14,255 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 6424316b-cd2d-4b44-a46c-a85d8d7cc0be, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:14,477 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:14,477 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:14,478 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 62158ee1-7d95-474d-b984-0f504b8a8dde, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:14,478 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 62158ee1-7d95-474d-b984-0f504b8a8dde, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:14,479 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 62158ee1-7d95-474d-b984-0f504b8a8dde, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:14,841 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:14,842 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-12-23 15:38:14,842 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:14,843 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 39426eec-93a5-430b-bebf-9b2e49972278, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:14,844 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 39426eec-93a5-430b-bebf-9b2e49972278, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:14,845 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 39426eec-93a5-430b-bebf-9b2e49972278, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:18,876 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:18,877 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:18,877 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, aa3b24fa-db63-4761-8b71-33485339820f, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:18,878 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, aa3b24fa-db63-4761-8b71-33485339820f, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:18,879 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, aa3b24fa-db63-4761-8b71-33485339820f, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:19,105 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:19,105 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:19,106 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 53628143-ba74-4c1b-87bf-15e6a415bdbf, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:19,107 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 53628143-ba74-4c1b-87bf-15e6a415bdbf, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:19,110 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 53628143-ba74-4c1b-87bf-15e6a415bdbf, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:19,453 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:19,453 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-12-23 15:38:19,454 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:19,455 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, e9538106-fc55-4fe6-9a01-777170027471, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:19,456 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, e9538106-fc55-4fe6-9a01-777170027471, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:19,458 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, e9538106-fc55-4fe6-9a01-777170027471, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:23,430 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:23,431 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:23,432 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 7b4d4acd-4f08-4eb2-ad52-661047c72572, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:23,433 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 7b4d4acd-4f08-4eb2-ad52-661047c72572, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:23,434 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 7b4d4acd-4f08-4eb2-ad52-661047c72572, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:23,777 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:23,778 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:23,778 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, b3d5a63e-ed3b-4846-8f30-238da5abbaef, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:23,780 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, b3d5a63e-ed3b-4846-8f30-238da5abbaef, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:23,781 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, b3d5a63e-ed3b-4846-8f30-238da5abbaef, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:24,294 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:24,295 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-12-23 15:38:24,295 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:24,296 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, d6946820-799f-4213-8955-5d4a61b2357b, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:24,297 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, d6946820-799f-4213-8955-5d4a61b2357b, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:24,298 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, d6946820-799f-4213-8955-5d4a61b2357b, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:28,396 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:28,397 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:28,398 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, d0102e02-4e53-421e-85d3-37d9cb60c139, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:28,399 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, d0102e02-4e53-421e-85d3-37d9cb60c139, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:28,400 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, d0102e02-4e53-421e-85d3-37d9cb60c139, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:28,814 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:28,815 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:28,816 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 53d1af35-5633-4542-a70d-e46655c8c4a3, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:28,817 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 53d1af35-5633-4542-a70d-e46655c8c4a3, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:28,818 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 53d1af35-5633-4542-a70d-e46655c8c4a3, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:29,462 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:29,463 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-12-23 15:38:29,464 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:29,464 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, bae496be-7778-4ba1-b86f-774b206040e5, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:29,465 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, bae496be-7778-4ba1-b86f-774b206040e5, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:29,467 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, bae496be-7778-4ba1-b86f-774b206040e5, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:33,455 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:33,456 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:33,456 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, cc1476f4-a166-4947-9ee6-a22b04034d3c, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:33,457 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, cc1476f4-a166-4947-9ee6-a22b04034d3c, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:33,459 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, cc1476f4-a166-4947-9ee6-a22b04034d3c, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:33,797 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:33,798 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:33,799 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 3a72eb3e-51b8-4998-88c9-19757a4a39ea, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:33,800 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 3a72eb3e-51b8-4998-88c9-19757a4a39ea, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:33,801 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 3a72eb3e-51b8-4998-88c9-19757a4a39ea, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:34,437 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:34,438 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-12-23 15:38:34,438 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:34,439 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 227657dc-53ef-499b-ba69-20269c5f9ed6, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:34,440 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 227657dc-53ef-499b-ba69-20269c5f9ed6, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:34,441 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 227657dc-53ef-499b-ba69-20269c5f9ed6, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:37,921 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:37,922 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:37,923 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 5a2cc423-7344-4279-b159-6e5a715ac2b8, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:37,924 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 5a2cc423-7344-4279-b159-6e5a715ac2b8, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:37,926 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 5a2cc423-7344-4279-b159-6e5a715ac2b8, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:38,193 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:38,194 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:38,195 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, a85b79d1-6802-4826-bc0b-5903ea851def, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:38,196 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, a85b79d1-6802-4826-bc0b-5903ea851def, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:38,198 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, a85b79d1-6802-4826-bc0b-5903ea851def, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:38,713 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:38,714 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-12-23 15:38:38,714 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:38,715 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 35cba598-068b-4769-94fd-4c691bcb207e, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:38,716 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 35cba598-068b-4769-94fd-4c691bcb207e, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:38,718 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 35cba598-068b-4769-94fd-4c691bcb207e, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:42,013 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:42,014 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:42,014 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, bc65c210-f873-4b2d-bbf2-c1462d776741, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:42,015 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, bc65c210-f873-4b2d-bbf2-c1462d776741, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:42,017 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, bc65c210-f873-4b2d-bbf2-c1462d776741, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:42,268 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:42,269 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:42,270 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, b2c6af1a-e52e-4a2b-a4d0-a1035430da9b, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:42,271 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, b2c6af1a-e52e-4a2b-a4d0-a1035430da9b, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:42,273 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, b2c6af1a-e52e-4a2b-a4d0-a1035430da9b, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:42,827 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:42,828 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-12-23 15:38:42,829 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:42,829 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 9e77e31f-85f3-4f7d-92bb-fc4db4d9dec4, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:42,830 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 9e77e31f-85f3-4f7d-92bb-fc4db4d9dec4, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:42,831 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 9e77e31f-85f3-4f7d-92bb-fc4db4d9dec4, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:46,254 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:46,254 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:46,255 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 9f08302f-b33c-400f-8391-d84cd3a6a4d5, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:46,256 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 9f08302f-b33c-400f-8391-d84cd3a6a4d5, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:46,257 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 9f08302f-b33c-400f-8391-d84cd3a6a4d5, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:46,481 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:46,482 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:46,483 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, ca6be3a1-4765-4229-bd91-738fd0d9fc7c, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:46,484 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, ca6be3a1-4765-4229-bd91-738fd0d9fc7c, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:46,485 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, ca6be3a1-4765-4229-bd91-738fd0d9fc7c, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:46,995 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:46,995 - INFO - __tests__.alter_compression.670426  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-12-23 15:38:46,996 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:46,996 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, f38c6d44-d37a-4eaa-966a-a249ebd0f3ba, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:46,997 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, f38c6d44-d37a-4eaa-966a-a249ebd0f3ba, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_18_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:46,998 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, f38c6d44-d37a-4eaa-966a-a249ebd0f3ba, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:47,991 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:47,992 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:47,993 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, db2e314a-7a27-4795-b328-5ba73042f678, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:47,994 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, db2e314a-7a27-4795-b328-5ba73042f678, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:47,995 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, db2e314a-7a27-4795-b328-5ba73042f678, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:48,205 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:48,206 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:48,207 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 7aabf6ec-ba52-4459-90dd-79676cbb6b11, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:48,208 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 7aabf6ec-ba52-4459-90dd-79676cbb6b11, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:48,209 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 7aabf6ec-ba52-4459-90dd-79676cbb6b11, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:48,474 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:48,475 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:48,475 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 5c3e0f32-28a5-4704-9389-08d6799490cf, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:38:48,476 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 5c3e0f32-28a5-4704-9389-08d6799490cf, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:48,477 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 5c3e0f32-28a5-4704-9389-08d6799490cf, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:51,437 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:38:53,965 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.670426  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 15:38:53,966 - DEBUG - ydb.connection.670426  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 15:38:53,977 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.670426  - context_resolve: Resolving endpoints for database /Root
2025-12-23 15:38:53,978 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ListEndpoints, 10093c0d-3cfa-4f76-b692-d14885d57998, localhost:24463): creating call state
2025-12-23 15:38:53,978 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ListEndpoints, 10093c0d-3cfa-4f76-b692-d14885d57998, localhost:24463): request = { database: "/Root" }
2025-12-23 15:38:53,982 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ListEndpoints, 10093c0d-3cfa-4f76-b692-d14885d57998, localhost:24463): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-icmf7pzugu.auto.internal" port: 24463 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-12-23 15:38:53,983 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.670426  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-icmf7pzugu.auto.internal:24463, location 1, ssl: False>]>
2025-12-23 15:38:53,983 - DEBUG - ydb.connection.670426  - close: Closing channel for endpoint localhost:24463
2025-12-23 15:39:01,491 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:39:01,492 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 3e2cd9ff-ad31-4ab5-9a89-70737870c1f1, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:39:01,493 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 3e2cd9ff-ad31-4ab5-9a89-70737870c1f1, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:01,495 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 3e2cd9ff-ad31-4ab5-9a89-70737870c1f1, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:04,410 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:39:14,440 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:39:14,442 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 40c6b9e8-047b-401b-a339-4a6f2345b61a, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:39:14,443 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 40c6b9e8-047b-401b-a339-4a6f2345b61a, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:14,445 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 40c6b9e8-047b-401b-a339-4a6f2345b61a, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:15,797 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:39:25,806 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:39:25,807 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, c38ac2bb-d630-49d6-b5f9-a75a6a4aae0f, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:39:25,808 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, c38ac2bb-d630-49d6-b5f9-a75a6a4aae0f, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:25,809 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, c38ac2bb-d630-49d6-b5f9-a75a6a4aae0f, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:26,495 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:39:26,502 - INFO - root.670426  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_18_compression`, volumes `value` (8000000, 1497984)
2025-12-23 15:39:26,502 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:39:26,503 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 5ef65b03-b706-4bc4-b535-cf776d688da7, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:39:26,507 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 5ef65b03-b706-4bc4-b535-cf776d688da7, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:26,509 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 5ef65b03-b706-4bc4-b535-cf776d688da7, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:26,756 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:39:26,757 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:39:26,757 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 04b07ee5-deec-42b1-8d94-5fac4241713e, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:39:26,758 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 04b07ee5-deec-42b1-8d94-5fac4241713e, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:26,759 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 04b07ee5-deec-42b1-8d94-5fac4241713e, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:27,352 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:39:37,360 - DEBUG - ydb.query.pool.670426  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:39:37,362 - DEBUG - ydb.connection.670426  - _prepare_call: RpcState(ExecuteQuery, 60594ab6-fc18-4ca1-81a4-2a42132b4fa1, ghrun-icmf7pzugu.auto.internal:24463): creating call state
2025-12-23 15:39:37,362 - DEBUG - ydb.connection.670426  - _log_request: RpcState(ExecuteQuery, 60594ab6-fc18-4ca1-81a4-2a42132b4fa1, ghrun-icmf7pzugu.auto.internal:24463): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:39:37,364 - DEBUG - ydb.connection.670426  - _log_response: RpcState(ExecuteQuery, 60594ab6-fc18-4ca1-81a4-2a42132b4fa1, ghrun-icmf7pzugu.auto.internal:24463): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:39:37,972 - DEBUG - ydb.query.pool.670426  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwNjA5ZWUtOTA5NGNjZjQtOGM4YmJmODYtNzgwYWVkZGU=
2025-12-23 15:39:37,976 - INFO - root.670426  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_18_compression`, volumes `value` (8000000, 1497984)
2025-12-23 15:39:37,976 - INFO - root.670426  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_18_compression` 8092160 / 1497984: 5.402033666581218
2025-12-23 15:39:37,979 - INFO - ya.test.670426  - pytest_runtest_teardown: Test teardown
