2025-12-23 15:36:51,288 - INFO - ya.test.668984  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:36:51,289 - INFO - ya.test.668984  - pytest_runtest_setup: test_all_supported_compression[zstd_2_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 2]
2025-12-23 15:36:51,289 - INFO - ya.test.668984  - pytest_runtest_setup: ####################################################################################################
2025-12-23 15:36:51,290 - INFO - ya.test.668984  - pytest_runtest_setup: Test setup
2025-12-23 15:36:51,292 - DEBUG - ya.test.668984  - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-23 15:36:51,293 - DEBUG - ya.test.668984  - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-23 15:36:51,293 - DEBUG - ya.test.668984  - execute: Executing '['/home/runner/.ya/build/build_root/wyw9/00219c/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-23 15:36:51,298 - DEBUG - ya.test.668984  - execute: Command pid: 673202
2025-12-23 15:36:52,151 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) rc: 0
2025-12-23 15:36:52,152 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) elapsed time (sec): 0.8516852855682373
2025-12-23 15:36:52,152 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) idrss: 0
2025-12-23 15:36:52,153 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) inblock: 0
2025-12-23 15:36:52,153 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) isrss: 0
2025-12-23 15:36:52,153 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) ixrss: 0
2025-12-23 15:36:52,153 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) majflt: 0
2025-12-23 15:36:52,154 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) maxrss: 495792
2025-12-23 15:36:52,154 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) minflt: 45796
2025-12-23 15:36:52,154 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) msgrcv: 0
2025-12-23 15:36:52,155 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) msgsnd: 0
2025-12-23 15:36:52,155 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) nivcsw: 202
2025-12-23 15:36:52,155 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) nsignals: 0
2025-12-23 15:36:52,155 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) nswap: 0
2025-12-23 15:36:52,156 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) nvcsw: 14
2025-12-23 15:36:52,156 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) oublock: 8
2025-12-23 15:36:52,156 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) stime: 0.19991399999999998
2025-12-23 15:36:52,157 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) utime: 0.5085879999999999
2025-12-23 15:36:52,157 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) wtime: 0.857
2025-12-23 15:36:52,157 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) 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:52,158 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673202) errors:

2025-12-23 15:36:52,158 - INFO - __tests__.base.668984  - _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:52,296 - DEBUG - ya.test.668984  - get_binary: Binary was found by /home/runner/.ya/build/build_root/wyw9/00219c/ydb/apps/ydbd/ydbd
2025-12-23 15:36:52,297 - DEBUG - library.python.filelock.668984  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12868
2025-12-23 15:36:52,308 - DEBUG - ydb.tests.library.harness.kikimr_runner.668984  - start: Working directory: /home/runner/.ya/build/build_root/wyw9/00219c/r3tmp/kikimr_cluster_zhc9wacs
2025-12-23 15:36:52,366 - DEBUG - ya.test.668984  - get_binary: Binary was found by /home/runner/.ya/build/build_root/wyw9/00219c/ydb/apps/ydbd/ydbd
2025-12-23 15:36:52,367 - DEBUG - library.python.filelock.668984  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26388
2025-12-23 15:36:52,368 - DEBUG - library.python.filelock.668984  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61028
2025-12-23 15:36:52,368 - DEBUG - library.python.filelock.668984  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3734
2025-12-23 15:36:52,369 - DEBUG - library.python.filelock.668984  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10519
2025-12-23 15:36:52,370 - INFO - ydb.tests.library.harness.kikimr_runner.668984  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/wyw9/00219c/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs"
2025-12-23 15:36:52,370 - INFO - ydb.tests.library.harness.kikimr_runner.668984  - __make_run_command: Final command: /home/runner/.ya/build/build_root/wyw9/00219c/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/00219c/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_h72with2.log --grpc-port=26388 --mon-port=61028 --ic-port=12868
2025-12-23 15:36:52,370 - DEBUG - ydb.tests.library.harness.kikimr_runner.668984  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:26388/1, disk_size 68719476736
2025-12-23 15:36:52,371 - INFO - ydb.tests.library.harness.kikimr_runner.668984  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/wyw9/00219c/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs"
2025-12-23 15:36:52,371 - INFO - ydb.tests.library.harness.kikimr_runner.668984  - __make_run_command: Final command: /home/runner/.ya/build/build_root/wyw9/00219c/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/00219c/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_h72with2.log --grpc-port=26388 --mon-port=61028 --ic-port=12868
2025-12-23 15:36:52,372 - DEBUG - ya.test.668984  - execute: Executing '('/home/runner/.ya/build/build_root/wyw9/00219c/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/wyw9/00219c/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/wyw9/00219c/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_h72with2.log', '--grpc-port=26388', '--mon-port=61028', '--ic-port=12868')' in '/home/runner/.ya/build/build_root/wyw9/00219c/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1' (no wait)
2025-12-23 15:36:52,375 - DEBUG - ya.test.668984  - execute: Command pid: 673572
2025-12-23 15:36:52,376 - INFO - ydb.tests.library.harness.kikimr_runner.668984  - start: Started node localhost:26388/1
2025-12-23 15:36:52,378 - DEBUG - ya.test.668984  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-23 15:36:52,378 - DEBUG - ya.test.668984  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-23 15:36:52,379 - DEBUG - ya.test.668984  - execute: Executing '['/home/runner/.ya/build/build_root/wyw9/00219c/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-23 15:36:52,381 - DEBUG - ya.test.668984  - execute: Command pid: 673590
2025-12-23 15:36:53,236 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) rc: 0
2025-12-23 15:36:53,237 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) elapsed time (sec): 0.8547558784484863
2025-12-23 15:36:53,237 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) idrss: 0
2025-12-23 15:36:53,238 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) inblock: 0
2025-12-23 15:36:53,238 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) isrss: 0
2025-12-23 15:36:53,239 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) ixrss: 0
2025-12-23 15:36:53,239 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) majflt: 0
2025-12-23 15:36:53,239 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) maxrss: 498988
2025-12-23 15:36:53,240 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) minflt: 45821
2025-12-23 15:36:53,240 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) msgrcv: 0
2025-12-23 15:36:53,240 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) msgsnd: 0
2025-12-23 15:36:53,240 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) nivcsw: 153
2025-12-23 15:36:53,241 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) nsignals: 0
2025-12-23 15:36:53,241 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) nswap: 0
2025-12-23 15:36:53,241 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) nvcsw: 14
2025-12-23 15:36:53,242 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) oublock: 8
2025-12-23 15:36:53,242 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) stime: 0.20881999999999998
2025-12-23 15:36:53,243 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) utime: 0.491761
2025-12-23 15:36:53,243 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) wtime: 0.857
2025-12-23 15:36:53,243 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) 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,244 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 673590) errors:

2025-12-23 15:36:53,244 - INFO - ydb.tests.library.harness.kikimr_runner.668984  - 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:58,266 - DEBUG - ya.test.668984  - get_binary: Binary was found by /home/runner/.ya/build/build_root/wyw9/00219c/ydb/apps/ydbd/ydbd
2025-12-23 15:36:58,267 - DEBUG - ydb.tests.library.harness.kikimr_runner.668984  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/wyw9/00219c/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:26388', '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: 12868\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-23 15:36:58,268 - DEBUG - ya.test.668984  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-23 15:36:58,269 - DEBUG - ya.test.668984  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-23 15:36:58,269 - DEBUG - ya.test.668984  - execute: Executing '['/home/runner/.ya/build/build_root/wyw9/00219c/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:26388', '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: 12868\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-23 15:36:58,273 - DEBUG - ya.test.668984  - execute: Command pid: 677830
2025-12-23 15:36:59,295 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) rc: 0
2025-12-23 15:36:59,296 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) elapsed time (sec): 1.021336317062378
2025-12-23 15:36:59,296 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) idrss: 0
2025-12-23 15:36:59,296 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) inblock: 0
2025-12-23 15:36:59,297 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) isrss: 0
2025-12-23 15:36:59,297 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) ixrss: 0
2025-12-23 15:36:59,297 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) majflt: 0
2025-12-23 15:36:59,297 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) maxrss: 595096
2025-12-23 15:36:59,298 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) minflt: 52239
2025-12-23 15:36:59,298 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) msgrcv: 0
2025-12-23 15:36:59,298 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) msgsnd: 0
2025-12-23 15:36:59,299 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) nivcsw: 112
2025-12-23 15:36:59,299 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) nsignals: 0
2025-12-23 15:36:59,299 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) nswap: 0
2025-12-23 15:36:59,300 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) nvcsw: 800
2025-12-23 15:36:59,302 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) oublock: 8
2025-12-23 15:36:59,302 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) stime: 0.29156499999999996
2025-12-23 15:36:59,302 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) utime: 0.535022
2025-12-23 15:36:59,303 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) wtime: 1.025
2025-12-23 15:36:59,303 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-23 15:36:59,303 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 677830) errors:

2025-12-23 15:36:59,305 - DEBUG - ya.test.668984  - get_binary: Binary was found by /home/runner/.ya/build/build_root/wyw9/00219c/ydb/apps/ydbd/ydbd
2025-12-23 15:36:59,305 - DEBUG - ydb.tests.library.harness.kikimr_runner.668984  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/wyw9/00219c/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:26388', '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:36:59,307 - DEBUG - ya.test.668984  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-23 15:36:59,308 - DEBUG - ya.test.668984  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-23 15:36:59,308 - DEBUG - ya.test.668984  - execute: Executing '['/home/runner/.ya/build/build_root/wyw9/00219c/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:26388', '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:36:59,310 - DEBUG - ya.test.668984  - execute: Command pid: 678494
2025-12-23 15:37:00,137 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) rc: 0
2025-12-23 15:37:00,138 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) elapsed time (sec): 0.8266632556915283
2025-12-23 15:37:00,138 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) idrss: 0
2025-12-23 15:37:00,138 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) inblock: 0
2025-12-23 15:37:00,139 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) isrss: 0
2025-12-23 15:37:00,139 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) ixrss: 0
2025-12-23 15:37:00,139 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) majflt: 0
2025-12-23 15:37:00,139 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) maxrss: 595096
2025-12-23 15:37:00,140 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) minflt: 52213
2025-12-23 15:37:00,140 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) msgrcv: 0
2025-12-23 15:37:00,140 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) msgsnd: 0
2025-12-23 15:37:00,141 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) nivcsw: 94
2025-12-23 15:37:00,141 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) nsignals: 0
2025-12-23 15:37:00,141 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) nswap: 0
2025-12-23 15:37:00,141 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) nvcsw: 462
2025-12-23 15:37:00,141 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) oublock: 8
2025-12-23 15:37:00,142 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) stime: 0.24201199999999998
2025-12-23 15:37:00,142 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) utime: 0.514328
2025-12-23 15:37:00,142 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) wtime: 0.828
2025-12-23 15:37:00,143 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-23 15:37:00,143 - DEBUG - ya.test.668984  - _save_outputs: Command (pid 678494) errors:

2025-12-23 15:37:00,144 - INFO - ydb.tests.library.harness.kikimr_runner.668984  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-23 15:37:00,267 - INFO - ydb.tests.library.harness.kikimr_runner.668984  - __run: Cluster started and initialized
2025-12-23 15:37:00,308 - DEBUG - ydb.connection.668984  - 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:00,312 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.668984  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 15:37:00,312 - DEBUG - ydb.connection.668984  - 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:00,327 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.668984  - context_resolve: Resolving endpoints for database /Root
2025-12-23 15:37:00,329 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ListEndpoints, e8538989-5e89-4d6a-a452-bf667be8de88, localhost:26388): creating call state
2025-12-23 15:37:00,330 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ListEndpoints, e8538989-5e89-4d6a-a452-bf667be8de88, localhost:26388): request = { database: "/Root" }
2025-12-23 15:37:00,342 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ListEndpoints, e8538989-5e89-4d6a-a452-bf667be8de88, localhost:26388): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-icmf7pzugu.auto.internal" port: 26388 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:00,343 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.668984  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-icmf7pzugu.auto.internal:26388, location 1, ssl: False>]>
2025-12-23 15:37:00,344 - DEBUG - ydb.connection.668984  - 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:00,362 - DEBUG - ydb.connection.668984  - close: Closing channel for endpoint localhost:26388
2025-12-23 15:37:00,364 - DEBUG - ydb.query.pool.668984  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-23 15:37:00,367 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(CreateSession, f093b649-475e-4b70-9fa4-553f41d7452a, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:00,367 - DEBUG - ydb.connection.668984  - _log_request: RpcState(CreateSession, f093b649-475e-4b70-9fa4-553f41d7452a, ghrun-icmf7pzugu.auto.internal:26388): request = {  }
2025-12-23 15:37:00,377 - DEBUG - ydb.connection.668984  - _log_response: RpcState(CreateSession, f093b649-475e-4b70-9fa4-553f41d7452a, ghrun-icmf7pzugu.auto.internal:26388): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" node_id: 1 }
2025-12-23 15:37:00,378 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(AttachSession, 2d42dd3e-00c9-4a80-99f3-6c7da15f5625, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:00,379 - DEBUG - ydb.connection.668984  - _log_request: RpcState(AttachSession, 2d42dd3e-00c9-4a80-99f3-6c7da15f5625, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" }
2025-12-23 15:37:00,382 - DEBUG - ydb.connection.668984  - _log_response: RpcState(AttachSession, 2d42dd3e-00c9-4a80-99f3-6c7da15f5625, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:00,384 - DEBUG - ydb.query.pool.668984  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:00,385 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 9b660be3-8c64-4862-855d-a7ab6c815fdc, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:00,387 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 9b660be3-8c64-4862-855d-a7ab6c815fdc, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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:00,388 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 9b660be3-8c64-4862-855d-a7ab6c815fdc, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:02,919 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:02,920 - INFO - __tests__.alter_compression.668984  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-12-23 15:37:02,920 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:02,921 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 2192d780-4037-4887-a35f-7947072fa664, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:02,922 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 2192d780-4037-4887-a35f-7947072fa664, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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:02,924 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 2192d780-4037-4887-a35f-7947072fa664, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:03,885 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:03,886 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:03,887 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 566ecd65-15a8-44df-974d-0403cc33b892, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:03,888 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 566ecd65-15a8-44df-974d-0403cc33b892, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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:03,890 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 566ecd65-15a8-44df-974d-0403cc33b892, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:04,796 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:04,797 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:04,797 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, f6e5a6d5-6202-4c08-aa1e-dad9e14a6b1d, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:04,798 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, f6e5a6d5-6202-4c08-aa1e-dad9e14a6b1d, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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:04,800 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, f6e5a6d5-6202-4c08-aa1e-dad9e14a6b1d, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:05,556 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:05,557 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:05,557 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 1e02fd28-7631-457c-bd7f-18ffe8ff9d77, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:05,558 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 1e02fd28-7631-457c-bd7f-18ffe8ff9d77, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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:05,560 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 1e02fd28-7631-457c-bd7f-18ffe8ff9d77, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:06,909 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:06,910 - INFO - __tests__.alter_compression.668984  - 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:06,910 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:06,911 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 82ed95f2-20dd-461c-80e9-c99e4f26fbb2, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:06,912 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 82ed95f2-20dd-461c-80e9-c99e4f26fbb2, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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:06,913 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 82ed95f2-20dd-461c-80e9-c99e4f26fbb2, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:07,738 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:07,738 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:07,739 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 4c9f9585-75ad-4116-b5dd-dd39c4c73b07, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:07,740 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 4c9f9585-75ad-4116-b5dd-dd39c4c73b07, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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:07,742 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 4c9f9585-75ad-4116-b5dd-dd39c4c73b07, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:07,942 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:07,943 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:07,943 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 39d0b5e8-f2ad-428d-9694-d313dca81be1, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:07,944 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 39d0b5e8-f2ad-428d-9694-d313dca81be1, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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:07,946 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 39d0b5e8-f2ad-428d-9694-d313dca81be1, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:08,201 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:08,202 - INFO - __tests__.alter_compression.668984  - 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,203 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:08,204 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 384608ac-c372-4566-b127-455761220465, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:08,205 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 384608ac-c372-4566-b127-455761220465, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,206 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 384608ac-c372-4566-b127-455761220465, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:09,065 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:09,066 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:09,066 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 0d9d81bc-7006-493a-aa32-8e7ddbe99009, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:09,067 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 0d9d81bc-7006-493a-aa32-8e7ddbe99009, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,069 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 0d9d81bc-7006-493a-aa32-8e7ddbe99009, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:09,254 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:09,255 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:09,255 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, b0c63b42-23e3-44a5-8a8f-d8b1ab5be202, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:09,256 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, b0c63b42-23e3-44a5-8a8f-d8b1ab5be202, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,259 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, b0c63b42-23e3-44a5-8a8f-d8b1ab5be202, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:09,531 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:09,531 - INFO - __tests__.alter_compression.668984  - 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,532 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:09,533 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, b70a9ab1-ca5e-4026-b07a-552645060cfb, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:09,534 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, b70a9ab1-ca5e-4026-b07a-552645060cfb, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,535 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, b70a9ab1-ca5e-4026-b07a-552645060cfb, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:10,260 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:10,261 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:10,261 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 3af6cbb2-1537-4c15-9298-f48f546ce787, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:10,262 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 3af6cbb2-1537-4c15-9298-f48f546ce787, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,263 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 3af6cbb2-1537-4c15-9298-f48f546ce787, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:10,422 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:10,422 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:10,423 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 3625cfe3-9510-42a0-8101-68f87024c306, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:10,424 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 3625cfe3-9510-42a0-8101-68f87024c306, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,425 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 3625cfe3-9510-42a0-8101-68f87024c306, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:10,800 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:10,801 - INFO - __tests__.alter_compression.668984  - 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:10,802 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:10,802 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 6a9ac13f-7103-489a-86a6-5df71b3a8a4d, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:10,803 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 6a9ac13f-7103-489a-86a6-5df71b3a8a4d, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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:10,804 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 6a9ac13f-7103-489a-86a6-5df71b3a8a4d, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:11,689 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:11,690 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:11,691 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, b9b558f8-9938-40b0-b32f-df788099c6a0, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:11,691 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, b9b558f8-9938-40b0-b32f-df788099c6a0, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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:11,693 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, b9b558f8-9938-40b0-b32f-df788099c6a0, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:11,913 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:11,914 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:11,914 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 3058dde2-2aa8-41d4-9f70-f3f7090b0a92, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:11,915 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 3058dde2-2aa8-41d4-9f70-f3f7090b0a92, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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:11,921 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 3058dde2-2aa8-41d4-9f70-f3f7090b0a92, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:12,325 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:12,326 - INFO - __tests__.alter_compression.668984  - 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,327 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:12,327 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 57a7d587-866e-4ecb-813a-fb0b85c95486, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:12,328 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 57a7d587-866e-4ecb-813a-fb0b85c95486, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,330 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 57a7d587-866e-4ecb-813a-fb0b85c95486, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:13,192 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:13,193 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:13,194 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 5b289d4b-be24-4ff1-ad7a-dc83f020d8d9, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:13,195 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 5b289d4b-be24-4ff1-ad7a-dc83f020d8d9, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,210 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 5b289d4b-be24-4ff1-ad7a-dc83f020d8d9, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:13,624 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:13,625 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:13,626 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 00d4fd10-3ff8-426d-9ddc-a4fc11bfa5dd, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:13,627 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 00d4fd10-3ff8-426d-9ddc-a4fc11bfa5dd, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,628 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 00d4fd10-3ff8-426d-9ddc-a4fc11bfa5dd, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:14,074 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:14,075 - INFO - __tests__.alter_compression.668984  - 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,075 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:14,076 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, c4ac801f-2859-4ca1-baa1-5df265804363, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:14,077 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, c4ac801f-2859-4ca1-baa1-5df265804363, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,078 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, c4ac801f-2859-4ca1-baa1-5df265804363, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:14,912 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:14,913 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:14,914 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 53ad2829-e97f-45fe-b7d6-23f9397d54c1, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:14,915 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 53ad2829-e97f-45fe-b7d6-23f9397d54c1, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,921 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 53ad2829-e97f-45fe-b7d6-23f9397d54c1, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:15,137 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:15,138 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:15,139 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, d79a67bf-0356-4dd7-bd24-a138fc84a745, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:15,140 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, d79a67bf-0356-4dd7-bd24-a138fc84a745, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,141 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, d79a67bf-0356-4dd7-bd24-a138fc84a745, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:15,633 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:15,634 - INFO - __tests__.alter_compression.668984  - 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,634 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:15,635 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 83a7aeca-dc2b-43be-9da2-a06710f0616b, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:15,637 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 83a7aeca-dc2b-43be-9da2-a06710f0616b, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,638 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 83a7aeca-dc2b-43be-9da2-a06710f0616b, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:16,624 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:16,625 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:16,626 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, e575fabc-9fc0-46b6-9728-e8baabc53e17, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:16,627 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, e575fabc-9fc0-46b6-9728-e8baabc53e17, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,636 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, e575fabc-9fc0-46b6-9728-e8baabc53e17, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:16,848 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:16,849 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:16,849 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 46113dc7-5de9-423e-b35e-14988ade80e2, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:16,850 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 46113dc7-5de9-423e-b35e-14988ade80e2, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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:16,852 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 46113dc7-5de9-423e-b35e-14988ade80e2, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:17,343 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:17,344 - INFO - __tests__.alter_compression.668984  - 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,344 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:17,345 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 2b0ca0cc-bfb5-4b04-9260-eae1a6f58cf1, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:17,345 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 2b0ca0cc-bfb5-4b04-9260-eae1a6f58cf1, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,346 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 2b0ca0cc-bfb5-4b04-9260-eae1a6f58cf1, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:18,295 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:18,296 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:18,297 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, d0bc396f-df5b-4b2b-9fb7-8cab1ead7eea, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:18,298 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, d0bc396f-df5b-4b2b-9fb7-8cab1ead7eea, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,299 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, d0bc396f-df5b-4b2b-9fb7-8cab1ead7eea, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:18,497 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:18,497 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:18,498 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, db905718-d93e-4810-90e3-2b60b9b01fa3, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:18,499 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, db905718-d93e-4810-90e3-2b60b9b01fa3, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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:18,501 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, db905718-d93e-4810-90e3-2b60b9b01fa3, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:19,061 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:19,062 - INFO - __tests__.alter_compression.668984  - 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,062 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:19,063 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, fc271224-9e66-4d9c-aa38-ec4e2073c15e, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:19,064 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, fc271224-9e66-4d9c-aa38-ec4e2073c15e, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,069 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, fc271224-9e66-4d9c-aa38-ec4e2073c15e, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:20,088 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:20,089 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:20,090 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, a396fb5d-2479-4063-84fb-c9c62f2aba46, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:20,090 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, a396fb5d-2479-4063-84fb-c9c62f2aba46, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,092 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, a396fb5d-2479-4063-84fb-c9c62f2aba46, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:20,361 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:20,361 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:20,362 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 3d3bb6a5-ea2c-429f-8f24-620a1e907f74, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:20,363 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 3d3bb6a5-ea2c-429f-8f24-620a1e907f74, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,369 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 3d3bb6a5-ea2c-429f-8f24-620a1e907f74, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:21,030 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:21,031 - INFO - __tests__.alter_compression.668984  - 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,031 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:21,032 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 3ad5f25d-0b7a-4a8e-a611-8870112a3d9f, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:21,033 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 3ad5f25d-0b7a-4a8e-a611-8870112a3d9f, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,034 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 3ad5f25d-0b7a-4a8e-a611-8870112a3d9f, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:22,055 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:22,056 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:22,056 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, e907b370-94ad-4f63-8544-8411efc76052, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:22,057 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, e907b370-94ad-4f63-8544-8411efc76052, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,059 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, e907b370-94ad-4f63-8544-8411efc76052, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:22,298 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:22,299 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:22,299 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 00a43a34-e8b9-49e2-8e78-007ed404e1c3, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:22,300 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 00a43a34-e8b9-49e2-8e78-007ed404e1c3, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,302 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 00a43a34-e8b9-49e2-8e78-007ed404e1c3, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:22,506 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:22,506 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:22,507 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, f6be8752-f81a-454c-b926-6cc0d9ed06ea, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:22,508 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, f6be8752-f81a-454c-b926-6cc0d9ed06ea, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,509 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, f6be8752-f81a-454c-b926-6cc0d9ed06ea, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:26,153 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:36,192 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:36,193 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 95d223aa-8a62-486c-8295-8d2f052e28df, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:36,193 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 95d223aa-8a62-486c-8295-8d2f052e28df, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,195 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 95d223aa-8a62-486c-8295-8d2f052e28df, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:39,176 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:39,214 - INFO - root.668984  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-12-23 15:37:39,215 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:39,216 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 0734210c-5858-4757-a23a-307eddb7788f, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:39,217 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 0734210c-5858-4757-a23a-307eddb7788f, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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,218 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 0734210c-5858-4757-a23a-307eddb7788f, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:41,715 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:51,758 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:51,759 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 377c733e-7c38-4728-88d6-371cb469974f, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:51,760 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 377c733e-7c38-4728-88d6-371cb469974f, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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:51,761 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 377c733e-7c38-4728-88d6-371cb469974f, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:54,514 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:54,551 - INFO - root.668984  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-12-23 15:37:54,552 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:54,553 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 54f51343-a52a-4b53-9312-4b84cf4d442c, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:54,554 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 54f51343-a52a-4b53-9312-4b84cf4d442c, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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:54,555 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 54f51343-a52a-4b53-9312-4b84cf4d442c, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:54,813 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:54,815 - INFO - ya.test.668984  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_2_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 2])
2025-12-23 15:37:54,815 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:54,816 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 2be049f4-b8d4-4760-bd5e-f67528dc5ac8, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:54,818 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 2be049f4-b8d4-4760-bd5e-f67528dc5ac8, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_2_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 2)\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:54,820 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 2be049f4-b8d4-4760-bd5e-f67528dc5ac8, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:57,388 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:57,388 - INFO - __tests__.alter_compression.668984  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_2_compression created
2025-12-23 15:37:57,389 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:57,390 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 1e042823-2cf8-450b-bb7a-68374884751c, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:57,390 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 1e042823-2cf8-450b-bb7a-68374884751c, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_2_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:37:57,393 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 1e042823-2cf8-450b-bb7a-68374884751c, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:58,163 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:58,164 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:58,165 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 008ad18c-2448-4708-9b88-40599e0c6e37, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:58,166 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 008ad18c-2448-4708-9b88-40599e0c6e37, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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,167 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 008ad18c-2448-4708-9b88-40599e0c6e37, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:37:59,237 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:59,237 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:37:59,238 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, e91b8274-1a69-4d7b-870f-29d477fbf4aa, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:37:59,239 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, e91b8274-1a69-4d7b-870f-29d477fbf4aa, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:59,240 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, e91b8274-1a69-4d7b-870f-29d477fbf4aa, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:00,020 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:00,021 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:00,022 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 7a94520a-e5da-4e04-9a1f-321a4506cd3e, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:00,022 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 7a94520a-e5da-4e04-9a1f-321a4506cd3e, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:00,024 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 7a94520a-e5da-4e04-9a1f-321a4506cd3e, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:01,504 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:01,504 - INFO - __tests__.alter_compression.668984  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318880, 'BlobCount': 128}}
2025-12-23 15:38:01,505 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:01,506 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 1af084b6-29f4-4737-af58-9c40a1bb2b0e, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:01,507 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 1af084b6-29f4-4737-af58-9c40a1bb2b0e, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:01,508 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 1af084b6-29f4-4737-af58-9c40a1bb2b0e, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:02,370 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:02,371 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:02,371 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 86a6dc5c-79d5-4988-a2e5-b5bbad652f17, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:02,373 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 86a6dc5c-79d5-4988-a2e5-b5bbad652f17, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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,374 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 86a6dc5c-79d5-4988-a2e5-b5bbad652f17, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:02,687 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:02,688 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:02,688 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, a37865f6-972b-4919-8255-e1f2e2cd8374, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:02,689 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, a37865f6-972b-4919-8255-e1f2e2cd8374, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:02,691 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, a37865f6-972b-4919-8255-e1f2e2cd8374, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:03,183 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:03,184 - INFO - __tests__.alter_compression.668984  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638656, 'BlobCount': 256}}
2025-12-23 15:38:03,185 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:03,185 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 82e67ed2-e7df-4cc1-af91-6683c3873ee3, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:03,186 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 82e67ed2-e7df-4cc1-af91-6683c3873ee3, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:03,189 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 82e67ed2-e7df-4cc1-af91-6683c3873ee3, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:04,388 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:04,388 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:04,389 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 46126373-0d01-44aa-b697-1d3cf8be1d8b, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:04,390 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 46126373-0d01-44aa-b697-1d3cf8be1d8b, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:04,392 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 46126373-0d01-44aa-b697-1d3cf8be1d8b, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:04,723 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:04,723 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:04,724 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 3b609b1e-daae-401b-b4b4-8a955c3a2172, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:04,725 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 3b609b1e-daae-401b-b4b4-8a955c3a2172, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:04,727 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 3b609b1e-daae-401b-b4b4-8a955c3a2172, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:05,211 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:05,211 - INFO - __tests__.alter_compression.668984  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957984, 'BlobCount': 384}}
2025-12-23 15:38:05,212 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:05,213 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 5dc625eb-5da4-4f8a-bc2d-dbc4259494c0, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:05,213 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 5dc625eb-5da4-4f8a-bc2d-dbc4259494c0, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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,215 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 5dc625eb-5da4-4f8a-bc2d-dbc4259494c0, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:06,279 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:06,280 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:06,281 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, bf9af0a0-827f-4199-8105-ef25e6fdf5cd, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:06,281 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, bf9af0a0-827f-4199-8105-ef25e6fdf5cd, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:06,283 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, bf9af0a0-827f-4199-8105-ef25e6fdf5cd, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:06,536 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:06,537 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:06,537 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 3a92ad79-c43e-47d4-9169-937cf204c6e6, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:06,538 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 3a92ad79-c43e-47d4-9169-937cf204c6e6, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:06,540 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 3a92ad79-c43e-47d4-9169-937cf204c6e6, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:06,908 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:06,908 - INFO - __tests__.alter_compression.668984  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1277728, 'BlobCount': 512}}
2025-12-23 15:38:06,909 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:06,909 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, cca5d2e7-719d-488d-b08c-015a1b7346d4, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:06,932 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, cca5d2e7-719d-488d-b08c-015a1b7346d4, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:06,933 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, cca5d2e7-719d-488d-b08c-015a1b7346d4, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:08,044 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:08,045 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:08,046 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 9939624b-3b06-4acd-a9fc-488158a470cb, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:08,047 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 9939624b-3b06-4acd-a9fc-488158a470cb, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:08,048 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 9939624b-3b06-4acd-a9fc-488158a470cb, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:08,365 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:08,366 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:08,367 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 8c88d453-745d-4538-a989-4ba3f0ad0603, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:08,368 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 8c88d453-745d-4538-a989-4ba3f0ad0603, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:08,370 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 8c88d453-745d-4538-a989-4ba3f0ad0603, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:09,039 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:09,040 - INFO - __tests__.alter_compression.668984  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1597184, 'BlobCount': 640}}
2025-12-23 15:38:09,040 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:09,041 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 43254724-4e38-47ec-a166-965a74d28a3c, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:09,042 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 43254724-4e38-47ec-a166-965a74d28a3c, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:09,043 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 43254724-4e38-47ec-a166-965a74d28a3c, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:10,393 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:10,393 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:10,394 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, b74ba73a-4bdb-4a73-8625-c49b9b0dee41, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:10,395 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, b74ba73a-4bdb-4a73-8625-c49b9b0dee41, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:10,397 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, b74ba73a-4bdb-4a73-8625-c49b9b0dee41, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:10,785 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:10,786 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:10,786 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 18612e2c-c6f1-4b24-bdea-80dddaad1812, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:10,787 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 18612e2c-c6f1-4b24-bdea-80dddaad1812, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:10,790 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 18612e2c-c6f1-4b24-bdea-80dddaad1812, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:11,389 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:11,389 - INFO - __tests__.alter_compression.668984  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1916800, 'BlobCount': 768}}
2025-12-23 15:38:11,390 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:11,391 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, d5dc357d-75ae-40d7-9746-94e5e5616a84, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:11,391 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, d5dc357d-75ae-40d7-9746-94e5e5616a84, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:11,400 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, d5dc357d-75ae-40d7-9746-94e5e5616a84, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:12,633 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:12,634 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:12,635 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 462588ba-9d76-4211-a8ab-5377261b6398, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:12,636 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 462588ba-9d76-4211-a8ab-5377261b6398, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:12,637 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 462588ba-9d76-4211-a8ab-5377261b6398, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:12,949 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:12,950 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:12,951 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 3ce6c55e-2d19-4b17-9c65-c1146d8176fc, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:12,960 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 3ce6c55e-2d19-4b17-9c65-c1146d8176fc, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:12,962 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 3ce6c55e-2d19-4b17-9c65-c1146d8176fc, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:13,565 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:13,566 - INFO - __tests__.alter_compression.668984  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2236496, 'BlobCount': 896}}
2025-12-23 15:38:13,566 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:13,567 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, ec70571c-d287-4e16-bf6b-4b573f5d53f4, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:13,568 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, ec70571c-d287-4e16-bf6b-4b573f5d53f4, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:13,569 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, ec70571c-d287-4e16-bf6b-4b573f5d53f4, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:14,751 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:14,751 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:14,752 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, e2b1d0b2-10fd-4443-9af9-557f1e353320, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:14,753 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, e2b1d0b2-10fd-4443-9af9-557f1e353320, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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,754 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, e2b1d0b2-10fd-4443-9af9-557f1e353320, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:15,039 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:15,040 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:15,040 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 11288353-a101-4e65-9f46-5cb0a83d93b0, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:15,041 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 11288353-a101-4e65-9f46-5cb0a83d93b0, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:15,043 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 11288353-a101-4e65-9f46-5cb0a83d93b0, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:15,705 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:15,706 - INFO - __tests__.alter_compression.668984  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2556272, 'BlobCount': 1024}}
2025-12-23 15:38:15,706 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:15,707 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 01685507-ffab-4d86-bb7e-31bf3ce06697, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:15,708 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 01685507-ffab-4d86-bb7e-31bf3ce06697, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:15,710 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 01685507-ffab-4d86-bb7e-31bf3ce06697, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:16,968 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:16,969 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:16,970 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 640d446c-2494-4d88-9ade-01979ab782f9, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:16,971 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 640d446c-2494-4d88-9ade-01979ab782f9, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:16,972 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 640d446c-2494-4d88-9ade-01979ab782f9, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:17,274 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:17,275 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:17,276 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 4d35bd93-f4ce-4af3-8ba8-c960f517bf34, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:17,277 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 4d35bd93-f4ce-4af3-8ba8-c960f517bf34, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:17,279 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 4d35bd93-f4ce-4af3-8ba8-c960f517bf34, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:17,954 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:17,954 - INFO - __tests__.alter_compression.668984  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2875584, 'BlobCount': 1152}}
2025-12-23 15:38:17,955 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:17,956 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, ce0d3acb-d05c-476a-ae62-36d014c042d9, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:17,957 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, ce0d3acb-d05c-476a-ae62-36d014c042d9, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:17,958 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, ce0d3acb-d05c-476a-ae62-36d014c042d9, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:18,909 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:18,910 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:18,910 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, adb760c8-e3d2-4f83-81e3-edb137ffc9d8, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:18,911 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, adb760c8-e3d2-4f83-81e3-edb137ffc9d8, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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,913 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, adb760c8-e3d2-4f83-81e3-edb137ffc9d8, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:19,156 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:19,157 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:19,158 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, c1fa83b9-7c08-4755-9d8d-7e1194b50eff, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:19,159 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, c1fa83b9-7c08-4755-9d8d-7e1194b50eff, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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,161 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, c1fa83b9-7c08-4755-9d8d-7e1194b50eff, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:19,220 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.668984  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 15:38:19,221 - DEBUG - ydb.connection.668984  - 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:19,234 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.668984  - context_resolve: Resolving endpoints for database /Root
2025-12-23 15:38:19,235 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ListEndpoints, 8f496f15-5292-469c-a5e0-5097eda3d985, localhost:26388): creating call state
2025-12-23 15:38:19,236 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ListEndpoints, 8f496f15-5292-469c-a5e0-5097eda3d985, localhost:26388): request = { database: "/Root" }
2025-12-23 15:38:19,262 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ListEndpoints, 8f496f15-5292-469c-a5e0-5097eda3d985, localhost:26388): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-icmf7pzugu.auto.internal" port: 26388 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:19,265 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.668984  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-icmf7pzugu.auto.internal:26388, location 1, ssl: False>]>
2025-12-23 15:38:19,266 - DEBUG - ydb.connection.668984  - close: Closing channel for endpoint localhost:26388
2025-12-23 15:38:19,894 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:19,895 - INFO - __tests__.alter_compression.668984  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3195568, 'BlobCount': 1280}}
2025-12-23 15:38:19,896 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:19,896 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 02791280-cf14-4a68-b73f-3d0c552bbd1a, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:19,897 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 02791280-cf14-4a68-b73f-3d0c552bbd1a, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_2_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 15:38:19,899 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 02791280-cf14-4a68-b73f-3d0c552bbd1a, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:21,028 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:21,029 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:21,031 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 47671fb2-5834-4598-b0d3-faf93130c00c, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:21,031 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 47671fb2-5834-4598-b0d3-faf93130c00c, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:21,033 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 47671fb2-5834-4598-b0d3-faf93130c00c, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:21,308 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:21,308 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:21,309 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, b220d18a-b9a6-4ca0-a62e-c12101a05ae8, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:21,310 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, b220d18a-b9a6-4ca0-a62e-c12101a05ae8, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:21,311 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, b220d18a-b9a6-4ca0-a62e-c12101a05ae8, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:21,597 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:21,598 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:21,599 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, c3188e4c-a964-4f98-a390-9f587b3e7aab, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:21,600 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, c3188e4c-a964-4f98-a390-9f587b3e7aab, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_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:21,601 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, c3188e4c-a964-4f98-a390-9f587b3e7aab, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:25,831 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:35,894 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:35,895 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 74ccbe5c-d955-48ec-a2e5-02fc8ba7d749, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:35,896 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 74ccbe5c-d955-48ec-a2e5-02fc8ba7d749, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_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:35,897 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 74ccbe5c-d955-48ec-a2e5-02fc8ba7d749, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:39,030 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:39,078 - INFO - root.668984  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_2_compression`, volumes `value` (8000000, 1597784)
2025-12-23 15:38:39,079 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:39,079 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 53ef79f1-ec4f-4e00-b911-498a09fb4e1d, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:39,081 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 53ef79f1-ec4f-4e00-b911-498a09fb4e1d, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" 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_2_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:39,082 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 53ef79f1-ec4f-4e00-b911-498a09fb4e1d, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:39,411 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:39,412 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:39,413 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, f66f7ade-cc63-4b8b-b26f-f569e7e87f3b, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:39,413 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, f66f7ade-cc63-4b8b-b26f-f569e7e87f3b, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_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:39,415 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, f66f7ade-cc63-4b8b-b26f-f569e7e87f3b, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:42,195 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:52,228 - DEBUG - ydb.query.pool.668984  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:52,229 - DEBUG - ydb.connection.668984  - _prepare_call: RpcState(ExecuteQuery, 0c21b022-9138-4c7c-a182-b979dbc2ca43, ghrun-icmf7pzugu.auto.internal:26388): creating call state
2025-12-23 15:38:52,230 - DEBUG - ydb.connection.668984  - _log_request: RpcState(ExecuteQuery, 0c21b022-9138-4c7c-a182-b979dbc2ca43, ghrun-icmf7pzugu.auto.internal:26388): request = { session_id: "ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_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:52,232 - DEBUG - ydb.connection.668984  - _log_response: RpcState(ExecuteQuery, 0c21b022-9138-4c7c-a182-b979dbc2ca43, ghrun-icmf7pzugu.auto.internal:26388): response = { <_MultiThreadedRendezvous object> }
2025-12-23 15:38:54,998 - DEBUG - ydb.query.pool.668984  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWU4NzUxN2UtYmE1M2JhYTUtMmI4YjhlNWUtYmQwYzMyZjE=
2025-12-23 15:38:55,036 - INFO - root.668984  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_2_compression`, volumes `value` (8000000, 1597784)
2025-12-23 15:38:55,037 - INFO - root.668984  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_2_compression` 8092160 / 1597784: 5.064614491070132
2025-12-23 15:38:55,039 - INFO - ya.test.668984  - pytest_runtest_teardown: Test teardown
