2025-10-15 15:11:43,528 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:11:43,529 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_6_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 6]
2025-10-15 15:11:43,529 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:11:43,530 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-15 15:11:43,532 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-15 15:11:43,533 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-15 15:11:43,533 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001c0e/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:11:43,549 - DEBUG - ya.test - execute: Command pid: 566978
2025-10-15 15:11:44,615 - DEBUG - ya.test - _save_outputs: Command (pid 566978) rc: 0
2025-10-15 15:11:44,616 - DEBUG - ya.test - _save_outputs: Command (pid 566978) elapsed time (sec): 1.0645544528961182
2025-10-15 15:11:44,616 - DEBUG - ya.test - _save_outputs: Command (pid 566978) idrss: 0
2025-10-15 15:11:44,616 - DEBUG - ya.test - _save_outputs: Command (pid 566978) inblock: 0
2025-10-15 15:11:44,616 - DEBUG - ya.test - _save_outputs: Command (pid 566978) isrss: 0
2025-10-15 15:11:44,617 - DEBUG - ya.test - _save_outputs: Command (pid 566978) ixrss: 0
2025-10-15 15:11:44,617 - DEBUG - ya.test - _save_outputs: Command (pid 566978) majflt: 0
2025-10-15 15:11:44,617 - DEBUG - ya.test - _save_outputs: Command (pid 566978) maxrss: 474312
2025-10-15 15:11:44,618 - DEBUG - ya.test - _save_outputs: Command (pid 566978) minflt: 45117
2025-10-15 15:11:44,618 - DEBUG - ya.test - _save_outputs: Command (pid 566978) msgrcv: 0
2025-10-15 15:11:44,618 - DEBUG - ya.test - _save_outputs: Command (pid 566978) msgsnd: 0
2025-10-15 15:11:44,618 - DEBUG - ya.test - _save_outputs: Command (pid 566978) nivcsw: 244
2025-10-15 15:11:44,619 - DEBUG - ya.test - _save_outputs: Command (pid 566978) nsignals: 0
2025-10-15 15:11:44,620 - DEBUG - ya.test - _save_outputs: Command (pid 566978) nswap: 0
2025-10-15 15:11:44,621 - DEBUG - ya.test - _save_outputs: Command (pid 566978) nvcsw: 15
2025-10-15 15:11:44,621 - DEBUG - ya.test - _save_outputs: Command (pid 566978) oublock: 8
2025-10-15 15:11:44,621 - DEBUG - ya.test - _save_outputs: Command (pid 566978) stime: 0.231342
2025-10-15 15:11:44,622 - DEBUG - ya.test - _save_outputs: Command (pid 566978) utime: 0.5385289999999999
2025-10-15 15:11:44,622 - DEBUG - ya.test - _save_outputs: Command (pid 566978) wtime: 1.081
2025-10-15 15:11:44,623 - DEBUG - ya.test - _save_outputs: Command (pid 566978) output:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

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

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

2025-10-15 15:11:44,623 - DEBUG - ya.test - _save_outputs: Command (pid 566978) errors:

2025-10-15 15:11:44,624 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

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

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

2025-10-15 15:11:44,769 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001c0e/ydb/apps/ydbd/ydbd
2025-10-15 15:11:44,771 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13824
2025-10-15 15:11:44,774 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/w2kl/001c0e/r3tmp/kikimr_cluster_oysewx37
2025-10-15 15:11:44,821 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001c0e/ydb/apps/ydbd/ydbd
2025-10-15 15:11:44,822 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22481
2025-10-15 15:11:44,823 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16589
2025-10-15 15:11:44,824 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2979
2025-10-15 15:11:44,824 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9627
2025-10-15 15:11:44,826 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001c0e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/kikimr_configs"
2025-10-15 15:11:44,827 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001c0e/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/w2kl/001c0e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/node_1/logfile_s64hb1be.log --grpc-port=22481 --mon-port=16589 --ic-port=13824
2025-10-15 15:11:44,827 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:22481/1, disk_size 68719476736
2025-10-15 15:11:44,828 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001c0e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/kikimr_configs"
2025-10-15 15:11:44,828 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001c0e/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/w2kl/001c0e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/node_1/logfile_s64hb1be.log --grpc-port=22481 --mon-port=16589 --ic-port=13824
2025-10-15 15:11:44,830 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/w2kl/001c0e/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/w2kl/001c0e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/w2kl/001c0e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/node_1/logfile_s64hb1be.log', '--grpc-port=22481', '--mon-port=16589', '--ic-port=13824')' in '/home/runner/.ya/build/build_root/w2kl/001c0e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/node_1' (no wait)
2025-10-15 15:11:44,845 - DEBUG - ya.test - execute: Command pid: 567003
2025-10-15 15:11:44,846 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:22481/1
2025-10-15 15:11:44,848 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-15 15:11:44,855 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-15 15:11:44,856 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001c0e/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:11:44,865 - DEBUG - ya.test - execute: Command pid: 567004
2025-10-15 15:11:45,999 - DEBUG - ya.test - _save_outputs: Command (pid 567004) rc: 0
2025-10-15 15:11:45,999 - DEBUG - ya.test - _save_outputs: Command (pid 567004) elapsed time (sec): 1.1326076984405518
2025-10-15 15:11:46,000 - DEBUG - ya.test - _save_outputs: Command (pid 567004) idrss: 0
2025-10-15 15:11:46,000 - DEBUG - ya.test - _save_outputs: Command (pid 567004) inblock: 0
2025-10-15 15:11:46,000 - DEBUG - ya.test - _save_outputs: Command (pid 567004) isrss: 0
2025-10-15 15:11:46,001 - DEBUG - ya.test - _save_outputs: Command (pid 567004) ixrss: 0
2025-10-15 15:11:46,001 - DEBUG - ya.test - _save_outputs: Command (pid 567004) majflt: 0
2025-10-15 15:11:46,001 - DEBUG - ya.test - _save_outputs: Command (pid 567004) maxrss: 481708
2025-10-15 15:11:46,001 - DEBUG - ya.test - _save_outputs: Command (pid 567004) minflt: 45069
2025-10-15 15:11:46,002 - DEBUG - ya.test - _save_outputs: Command (pid 567004) msgrcv: 0
2025-10-15 15:11:46,002 - DEBUG - ya.test - _save_outputs: Command (pid 567004) msgsnd: 0
2025-10-15 15:11:46,002 - DEBUG - ya.test - _save_outputs: Command (pid 567004) nivcsw: 219
2025-10-15 15:11:46,002 - DEBUG - ya.test - _save_outputs: Command (pid 567004) nsignals: 0
2025-10-15 15:11:46,003 - DEBUG - ya.test - _save_outputs: Command (pid 567004) nswap: 0
2025-10-15 15:11:46,003 - DEBUG - ya.test - _save_outputs: Command (pid 567004) nvcsw: 14
2025-10-15 15:11:46,004 - DEBUG - ya.test - _save_outputs: Command (pid 567004) oublock: 8
2025-10-15 15:11:46,004 - DEBUG - ya.test - _save_outputs: Command (pid 567004) stime: 0.220442
2025-10-15 15:11:46,004 - DEBUG - ya.test - _save_outputs: Command (pid 567004) utime: 0.523076
2025-10-15 15:11:46,005 - DEBUG - ya.test - _save_outputs: Command (pid 567004) wtime: 1.142
2025-10-15 15:11:46,005 - DEBUG - ya.test - _save_outputs: Command (pid 567004) output:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

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

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

2025-10-15 15:11:46,006 - DEBUG - ya.test - _save_outputs: Command (pid 567004) errors:

2025-10-15 15:11:46,006 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b
2025-10-15 15:11:51,584 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001c0e/ydb/apps/ydbd/ydbd
2025-10-15 15:11:51,585 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2kl/001c0e/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:22481', '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: 13824\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-15 15:11:51,586 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-15 15:11:51,587 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-15 15:11:51,587 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001c0e/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:22481', '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: 13824\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-15 15:11:51,613 - DEBUG - ya.test - execute: Command pid: 567807
2025-10-15 15:11:53,146 - DEBUG - ya.test - _save_outputs: Command (pid 567807) rc: 0
2025-10-15 15:11:53,146 - DEBUG - ya.test - _save_outputs: Command (pid 567807) elapsed time (sec): 1.531261682510376
2025-10-15 15:11:53,146 - DEBUG - ya.test - _save_outputs: Command (pid 567807) idrss: 0
2025-10-15 15:11:53,147 - DEBUG - ya.test - _save_outputs: Command (pid 567807) inblock: 0
2025-10-15 15:11:53,147 - DEBUG - ya.test - _save_outputs: Command (pid 567807) isrss: 0
2025-10-15 15:11:53,147 - DEBUG - ya.test - _save_outputs: Command (pid 567807) ixrss: 0
2025-10-15 15:11:53,148 - DEBUG - ya.test - _save_outputs: Command (pid 567807) majflt: 0
2025-10-15 15:11:53,148 - DEBUG - ya.test - _save_outputs: Command (pid 567807) maxrss: 583496
2025-10-15 15:11:53,148 - DEBUG - ya.test - _save_outputs: Command (pid 567807) minflt: 51339
2025-10-15 15:11:53,148 - DEBUG - ya.test - _save_outputs: Command (pid 567807) msgrcv: 0
2025-10-15 15:11:53,153 - DEBUG - ya.test - _save_outputs: Command (pid 567807) msgsnd: 0
2025-10-15 15:11:53,153 - DEBUG - ya.test - _save_outputs: Command (pid 567807) nivcsw: 229
2025-10-15 15:11:53,154 - DEBUG - ya.test - _save_outputs: Command (pid 567807) nsignals: 0
2025-10-15 15:11:53,154 - DEBUG - ya.test - _save_outputs: Command (pid 567807) nswap: 0
2025-10-15 15:11:53,154 - DEBUG - ya.test - _save_outputs: Command (pid 567807) nvcsw: 470
2025-10-15 15:11:53,155 - DEBUG - ya.test - _save_outputs: Command (pid 567807) oublock: 8
2025-10-15 15:11:53,155 - DEBUG - ya.test - _save_outputs: Command (pid 567807) stime: 0.27485699999999996
2025-10-15 15:11:53,155 - DEBUG - ya.test - _save_outputs: Command (pid 567807) utime: 0.568394
2025-10-15 15:11:53,156 - DEBUG - ya.test - _save_outputs: Command (pid 567807) wtime: 1.557
2025-10-15 15:11:53,156 - DEBUG - ya.test - _save_outputs: Command (pid 567807) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-15 15:11:53,156 - DEBUG - ya.test - _save_outputs: Command (pid 567807) errors:

2025-10-15 15:11:53,170 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001c0e/ydb/apps/ydbd/ydbd
2025-10-15 15:11:53,171 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2kl/001c0e/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:22481', '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-10-15 15:11:53,172 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-15 15:11:53,177 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-15 15:11:53,178 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001c0e/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:22481', '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-10-15 15:11:53,193 - DEBUG - ya.test - execute: Command pid: 568209
2025-10-15 15:11:54,461 - DEBUG - ya.test - _save_outputs: Command (pid 568209) rc: 0
2025-10-15 15:11:54,462 - DEBUG - ya.test - _save_outputs: Command (pid 568209) elapsed time (sec): 1.2668428421020508
2025-10-15 15:11:54,462 - DEBUG - ya.test - _save_outputs: Command (pid 568209) idrss: 0
2025-10-15 15:11:54,463 - DEBUG - ya.test - _save_outputs: Command (pid 568209) inblock: 0
2025-10-15 15:11:54,463 - DEBUG - ya.test - _save_outputs: Command (pid 568209) isrss: 0
2025-10-15 15:11:54,463 - DEBUG - ya.test - _save_outputs: Command (pid 568209) ixrss: 0
2025-10-15 15:11:54,463 - DEBUG - ya.test - _save_outputs: Command (pid 568209) majflt: 0
2025-10-15 15:11:54,464 - DEBUG - ya.test - _save_outputs: Command (pid 568209) maxrss: 584068
2025-10-15 15:11:54,464 - DEBUG - ya.test - _save_outputs: Command (pid 568209) minflt: 51375
2025-10-15 15:11:54,464 - DEBUG - ya.test - _save_outputs: Command (pid 568209) msgrcv: 0
2025-10-15 15:11:54,469 - DEBUG - ya.test - _save_outputs: Command (pid 568209) msgsnd: 0
2025-10-15 15:11:54,469 - DEBUG - ya.test - _save_outputs: Command (pid 568209) nivcsw: 229
2025-10-15 15:11:54,470 - DEBUG - ya.test - _save_outputs: Command (pid 568209) nsignals: 0
2025-10-15 15:11:54,470 - DEBUG - ya.test - _save_outputs: Command (pid 568209) nswap: 0
2025-10-15 15:11:54,470 - DEBUG - ya.test - _save_outputs: Command (pid 568209) nvcsw: 609
2025-10-15 15:11:54,471 - DEBUG - ya.test - _save_outputs: Command (pid 568209) oublock: 8
2025-10-15 15:11:54,472 - DEBUG - ya.test - _save_outputs: Command (pid 568209) stime: 0.292122
2025-10-15 15:11:54,472 - DEBUG - ya.test - _save_outputs: Command (pid 568209) utime: 0.57879
2025-10-15 15:11:54,472 - DEBUG - ya.test - _save_outputs: Command (pid 568209) wtime: 1.282
2025-10-15 15:11:54,473 - DEBUG - ya.test - _save_outputs: Command (pid 568209) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-15 15:11:54,473 - DEBUG - ya.test - _save_outputs: Command (pid 568209) errors:

2025-10-15 15:11:54,474 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-15 15:11:54,700 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-15 15:11:54,752 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-15 15:11:54,758 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:11:54,766 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-15 15:11:54,812 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:11:54,813 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, cb493a1a-a09a-4fbb-b823-1c2004c286ea, localhost:22481): creating call state
2025-10-15 15:11:54,814 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, cb493a1a-a09a-4fbb-b823-1c2004c286ea, localhost:22481): request = { database: "/Root" }
2025-10-15 15:11:54,827 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, cb493a1a-a09a-4fbb-b823-1c2004c286ea, localhost:22481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 22481 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-10-15 15:11:54,829 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:22481, location 1, ssl: False>]>
2025-10-15 15:11:54,834 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-15 15:11:54,877 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:22481
2025-10-15 15:11:54,881 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-10-15 15:11:54,883 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 9302378c-c4cc-4830-be2d-1c9d5cdf9a48, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:11:54,883 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 9302378c-c4cc-4830-be2d-1c9d5cdf9a48, ghrun-lvxbqnxafi.auto.internal:22481): request = {  }
2025-10-15 15:11:54,892 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 9302378c-c4cc-4830-be2d-1c9d5cdf9a48, ghrun-lvxbqnxafi.auto.internal:22481): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" node_id: 1 }
2025-10-15 15:11:54,893 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 6c8ee3f1-00f3-416f-b9fe-b5c02bf7adf8, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:11:54,894 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 6c8ee3f1-00f3-416f-b9fe-b5c02bf7adf8, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" }
2025-10-15 15:11:54,898 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 6c8ee3f1-00f3-416f-b9fe-b5c02bf7adf8, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:11:54,926 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:11:54,927 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8455b6bb-439d-4f52-8c5c-71f986888b11, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:11:54,928 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8455b6bb-439d-4f52-8c5c-71f986888b11, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:11:54,932 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8455b6bb-439d-4f52-8c5c-71f986888b11, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:11:58,514 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:11:58,515 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-10-15 15:11:58,516 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:11:58,516 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f146ca1b-4174-4356-8973-fdd5bf798026, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:11:58,517 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f146ca1b-4174-4356-8973-fdd5bf798026, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:11:58,519 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f146ca1b-4174-4356-8973-fdd5bf798026, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:11:59,832 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:11:59,834 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:11:59,834 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 68939ca2-d775-4b37-b61d-fee46f006807, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:11:59,835 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 68939ca2-d775-4b37-b61d-fee46f006807, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:11:59,841 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 68939ca2-d775-4b37-b61d-fee46f006807, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:01,309 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:01,310 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:01,311 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9968f9cc-67e7-4d05-8d64-eee8825061c8, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:01,312 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9968f9cc-67e7-4d05-8d64-eee8825061c8, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:01,313 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9968f9cc-67e7-4d05-8d64-eee8825061c8, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:02,342 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:02,343 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:02,344 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 373c789e-3410-4ca9-a48b-60e8a7f8d3dd, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:02,356 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 373c789e-3410-4ca9-a48b-60e8a7f8d3dd, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:02,358 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 373c789e-3410-4ca9-a48b-60e8a7f8d3dd, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:04,990 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:04,991 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 1618432, 'BlobCount': 128}}
2025-10-15 15:12:04,992 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:04,992 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2b0d1e9b-143e-42ce-bb3f-4e11c0d7adf6, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:04,993 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2b0d1e9b-143e-42ce-bb3f-4e11c0d7adf6, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:04,996 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2b0d1e9b-143e-42ce-bb3f-4e11c0d7adf6, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:06,193 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:06,194 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:06,198 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a440f48e-bb39-4851-8bed-7a171b6bb3d7, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:06,200 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a440f48e-bb39-4851-8bed-7a171b6bb3d7, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:06,201 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a440f48e-bb39-4851-8bed-7a171b6bb3d7, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:06,511 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:06,511 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:06,512 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6f252135-2fd0-491b-ad52-898554ea5b2d, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:06,513 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6f252135-2fd0-491b-ad52-898554ea5b2d, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:06,516 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6f252135-2fd0-491b-ad52-898554ea5b2d, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:07,087 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:07,087 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 3236864, 'BlobCount': 256}}
2025-10-15 15:12:07,088 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:07,089 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 43e7b73e-8e8e-47a1-8069-2ac1944d69f9, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:07,090 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 43e7b73e-8e8e-47a1-8069-2ac1944d69f9, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:07,093 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 43e7b73e-8e8e-47a1-8069-2ac1944d69f9, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:08,429 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:08,430 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:08,431 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b58d6944-4f20-4ae0-bb04-38d72158e29e, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:08,431 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b58d6944-4f20-4ae0-bb04-38d72158e29e, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:08,442 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b58d6944-4f20-4ae0-bb04-38d72158e29e, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:08,831 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:08,832 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:08,833 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5beca238-f3b5-4005-87ec-f3ded1be13c7, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:08,834 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5beca238-f3b5-4005-87ec-f3ded1be13c7, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:08,836 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5beca238-f3b5-4005-87ec-f3ded1be13c7, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:09,605 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:09,606 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 312439, 'Portions': 190}}, blobs: {'__DEFAULT': {'Portions': 181, 'BlobSize': 4858936, 'BlobCount': 384}}
2025-10-15 15:12:09,607 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:09,608 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 520bed2f-c501-469f-ba73-e9e6857722ce, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:09,609 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 520bed2f-c501-469f-ba73-e9e6857722ce, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:09,610 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 520bed2f-c501-469f-ba73-e9e6857722ce, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:11,723 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:11,724 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:11,724 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 305c49ef-fbb6-44a1-9349-e37e51a17e5c, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:11,725 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 305c49ef-fbb6-44a1-9349-e37e51a17e5c, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:11,729 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 305c49ef-fbb6-44a1-9349-e37e51a17e5c, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:12,091 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:12,092 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:12,093 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8d75d98e-a424-468b-ade6-dfa8e74f6a70, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:12,094 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8d75d98e-a424-468b-ade6-dfa8e74f6a70, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:12,097 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8d75d98e-a424-468b-ade6-dfa8e74f6a70, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:12,742 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:12,743 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 245}}, blobs: {'__DEFAULT': {'Portions': 245, 'BlobSize': 6477368, 'BlobCount': 512}}
2025-10-15 15:12:12,744 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:12,744 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d1c5cd28-f1d1-4f2c-b71f-942a52b66d35, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:12,746 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d1c5cd28-f1d1-4f2c-b71f-942a52b66d35, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:12,754 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d1c5cd28-f1d1-4f2c-b71f-942a52b66d35, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:14,073 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:14,074 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:14,075 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 674667ba-5746-4858-a419-8456ffa239b5, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:14,076 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 674667ba-5746-4858-a419-8456ffa239b5, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:14,078 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 674667ba-5746-4858-a419-8456ffa239b5, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:14,495 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:14,496 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:14,496 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ef0b8ad8-aa6a-4235-83f1-a69608bd0dce, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:14,497 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ef0b8ad8-aa6a-4235-83f1-a69608bd0dce, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:14,502 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ef0b8ad8-aa6a-4235-83f1-a69608bd0dce, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:15,390 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:15,391 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 300}}, blobs: {'__DEFAULT': {'Portions': 300, 'BlobSize': 8095760, 'BlobCount': 628}}
2025-10-15 15:12:15,392 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:15,392 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6bd7598e-7f5a-4856-bcfd-65e5f1512e69, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:15,393 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6bd7598e-7f5a-4856-bcfd-65e5f1512e69, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:15,394 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6bd7598e-7f5a-4856-bcfd-65e5f1512e69, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:16,929 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:16,929 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:16,930 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, db219064-56cb-4ea5-bb91-5edecf0dfbca, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:16,931 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, db219064-56cb-4ea5-bb91-5edecf0dfbca, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:16,932 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, db219064-56cb-4ea5-bb91-5edecf0dfbca, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:17,391 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:17,391 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:17,392 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3e1be80d-9728-4cfd-9265-3851308f5281, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:17,393 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3e1be80d-9728-4cfd-9265-3851308f5281, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:17,394 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3e1be80d-9728-4cfd-9265-3851308f5281, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:18,050 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:18,051 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 360}}, blobs: {'__DEFAULT': {'Portions': 360, 'BlobSize': 9714000, 'BlobCount': 750}}
2025-10-15 15:12:18,051 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:18,052 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 06420bb0-bdca-4102-ab9b-d9ea0a0df73b, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:18,053 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 06420bb0-bdca-4102-ab9b-d9ea0a0df73b, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:18,062 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 06420bb0-bdca-4102-ab9b-d9ea0a0df73b, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:19,377 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:19,377 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:19,378 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bf901b51-247b-4dbc-8fa2-3ec3920059c9, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:19,379 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bf901b51-247b-4dbc-8fa2-3ec3920059c9, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:19,380 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bf901b51-247b-4dbc-8fa2-3ec3920059c9, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:19,787 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:19,788 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:19,789 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c97ae79b-35b0-42e1-a8c2-edd7f791bc9c, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:19,790 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c97ae79b-35b0-42e1-a8c2-edd7f791bc9c, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:19,791 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c97ae79b-35b0-42e1-a8c2-edd7f791bc9c, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:20,566 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:20,567 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 709398, 'Portions': 405}}, blobs: {'__DEFAULT': {'Portions': 394, 'BlobSize': 11330416, 'BlobCount': 830}}
2025-10-15 15:12:20,567 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:20,568 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 97e6fe00-b6a9-4b67-9970-4605044898d0, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:20,578 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 97e6fe00-b6a9-4b67-9970-4605044898d0, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:20,580 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 97e6fe00-b6a9-4b67-9970-4605044898d0, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:21,805 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:21,806 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:21,807 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b72ddb09-3149-43d3-b839-e6d5e4a14dc6, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:21,808 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b72ddb09-3149-43d3-b839-e6d5e4a14dc6, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:21,812 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b72ddb09-3149-43d3-b839-e6d5e4a14dc6, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:22,104 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:22,105 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:22,105 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 48da9fec-e817-4ade-8f9c-42953066cf2d, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:22,106 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 48da9fec-e817-4ade-8f9c-42953066cf2d, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:22,114 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 48da9fec-e817-4ade-8f9c-42953066cf2d, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:22,763 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:22,764 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 452}}, blobs: {'__DEFAULT': {'Portions': 452, 'BlobSize': 12948328, 'BlobCount': 948}}
2025-10-15 15:12:22,765 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:22,765 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b59ac6c6-9d9c-43ce-b703-b864eee1dcc9, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:22,767 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b59ac6c6-9d9c-43ce-b703-b864eee1dcc9, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:22,768 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b59ac6c6-9d9c-43ce-b703-b864eee1dcc9, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:23,946 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:23,947 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:23,948 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7017fcd1-620e-4f51-8c57-88dad8677ada, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:23,949 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7017fcd1-620e-4f51-8c57-88dad8677ada, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:23,953 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7017fcd1-620e-4f51-8c57-88dad8677ada, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:24,271 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:24,272 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:24,272 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f7554c36-a43e-4511-95aa-844a1455e1a1, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:24,273 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f7554c36-a43e-4511-95aa-844a1455e1a1, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:24,280 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f7554c36-a43e-4511-95aa-844a1455e1a1, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:24,939 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:24,940 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 502}}, blobs: {'__DEFAULT': {'Portions': 502, 'BlobSize': 14565400, 'BlobCount': 1052}}
2025-10-15 15:12:24,941 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:24,942 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5667c287-2d81-4f10-98d7-c55407af47d8, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:24,943 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5667c287-2d81-4f10-98d7-c55407af47d8, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:24,945 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5667c287-2d81-4f10-98d7-c55407af47d8, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:26,135 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:26,136 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:26,137 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 27311144-6163-4e3f-8c54-5b1c2dfb6ec8, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:26,138 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 27311144-6163-4e3f-8c54-5b1c2dfb6ec8, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:26,140 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 27311144-6163-4e3f-8c54-5b1c2dfb6ec8, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:26,550 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:26,551 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:26,552 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5d51577a-c983-4ceb-8b2d-7688d69a4403, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:26,554 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5d51577a-c983-4ceb-8b2d-7688d69a4403, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:26,556 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5d51577a-c983-4ceb-8b2d-7688d69a4403, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:27,554 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:27,555 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 566}}, blobs: {'__DEFAULT': {'Portions': 566, 'BlobSize': 16183832, 'BlobCount': 1180}}
2025-10-15 15:12:27,556 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:27,563 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d2b530ce-9a13-446b-99ef-d6efc43bae73, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:27,564 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d2b530ce-9a13-446b-99ef-d6efc43bae73, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:27,566 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d2b530ce-9a13-446b-99ef-d6efc43bae73, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:28,846 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:28,847 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:28,848 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9bd80b63-3856-4d29-9643-7a1c70ff6696, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:28,849 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9bd80b63-3856-4d29-9643-7a1c70ff6696, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:28,850 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9bd80b63-3856-4d29-9643-7a1c70ff6696, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:29,283 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:29,284 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:29,284 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f97e60d7-a2e6-4aff-9043-cfb938e46be6, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:29,285 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f97e60d7-a2e6-4aff-9043-cfb938e46be6, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:29,287 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f97e60d7-a2e6-4aff-9043-cfb938e46be6, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:29,649 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:29,650 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:29,650 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6917f032-e18a-4a6f-bd4c-3d5b626f3af2, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:29,651 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6917f032-e18a-4a6f-bd4c-3d5b626f3af2, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:29,652 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6917f032-e18a-4a6f-bd4c-3d5b626f3af2, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:34,235 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:44,281 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:44,282 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 356b76d1-ea89-44aa-83ac-582107b315a8, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:44,283 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 356b76d1-ea89-44aa-83ac-582107b315a8, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:44,284 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 356b76d1-ea89-44aa-83ac-582107b315a8, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:48,754 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:48,787 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8081504)
2025-10-15 15:12:48,788 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:12:48,789 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2b0c5971-26f7-450c-a02d-8b8e01d96f83, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:12:48,790 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2b0c5971-26f7-450c-a02d-8b8e01d96f83, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:48,791 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2b0c5971-26f7-450c-a02d-8b8e01d96f83, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:53,306 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:03,345 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:03,346 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3a458082-b648-47b6-855d-d63ce4be83e0, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:03,347 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3a458082-b648-47b6-855d-d63ce4be83e0, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:03,348 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3a458082-b648-47b6-855d-d63ce4be83e0, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:05,562 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:13,097 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:13:13,098 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-15 15:13:13,123 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:13:13,124 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, def5899f-5a42-4c4a-aeae-ceff9221d088, localhost:22481): creating call state
2025-10-15 15:13:13,124 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, def5899f-5a42-4c4a-aeae-ceff9221d088, localhost:22481): request = { database: "/Root" }
2025-10-15 15:13:13,136 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, def5899f-5a42-4c4a-aeae-ceff9221d088, localhost:22481): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 22481 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-10-15 15:13:13,137 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:22481, location 1, ssl: False>]>
2025-10-15 15:13:13,138 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:22481
2025-10-15 15:13:15,577 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:15,578 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5620be8d-34ad-4472-a33a-c0144c58cbc0, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:15,579 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5620be8d-34ad-4472-a33a-c0144c58cbc0, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:15,581 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5620be8d-34ad-4472-a33a-c0144c58cbc0, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:17,010 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:27,032 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:27,033 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 233a26a7-b90a-4a6d-844b-38c9c6ed0b8e, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:27,034 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 233a26a7-b90a-4a6d-844b-38c9c6ed0b8e, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:27,035 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 233a26a7-b90a-4a6d-844b-38c9c6ed0b8e, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:28,389 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:28,403 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8029664)
2025-10-15 15:13:28,403 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:28,404 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 34353da5-fbb5-49ab-afbb-696eead85f73, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:28,405 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 34353da5-fbb5-49ab-afbb-696eead85f73, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:28,407 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 34353da5-fbb5-49ab-afbb-696eead85f73, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:28,675 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:28,679 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_6_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 6])
2025-10-15 15:13:28,680 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:28,681 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aa56da46-2e76-4201-992b-eddf29794a12, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:28,683 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aa56da46-2e76-4201-992b-eddf29794a12, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_6_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 6)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:28,684 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aa56da46-2e76-4201-992b-eddf29794a12, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:32,037 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:32,038 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_6_compression created
2025-10-15 15:13:32,038 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:32,039 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 85037613-5aab-4be4-b3e5-a1b1f1e01fbd, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:32,040 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 85037613-5aab-4be4-b3e5-a1b1f1e01fbd, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_6_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:32,043 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 85037613-5aab-4be4-b3e5-a1b1f1e01fbd, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:32,900 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:32,901 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:32,902 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 49eb15b4-2382-4677-88ea-9a8bcfea843a, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:32,903 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 49eb15b4-2382-4677-88ea-9a8bcfea843a, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:32,918 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 49eb15b4-2382-4677-88ea-9a8bcfea843a, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:34,526 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:34,527 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:34,528 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e04442a-b076-4153-aa5e-93f75ad3583a, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:34,528 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e04442a-b076-4153-aa5e-93f75ad3583a, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:34,530 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e04442a-b076-4153-aa5e-93f75ad3583a, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:35,813 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:35,815 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:35,815 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d7621b3b-2024-412c-b280-af9eef4f6aed, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:35,816 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d7621b3b-2024-412c-b280-af9eef4f6aed, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:35,822 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d7621b3b-2024-412c-b280-af9eef4f6aed, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:37,744 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:37,745 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318016, 'BlobCount': 128}}
2025-10-15 15:13:37,745 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:37,746 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a0a94d2f-499f-4ab5-85a5-f6bd79cea832, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:37,747 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a0a94d2f-499f-4ab5-85a5-f6bd79cea832, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:37,748 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a0a94d2f-499f-4ab5-85a5-f6bd79cea832, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:39,309 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:39,310 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:39,311 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1d6d7f04-718a-4043-b6e9-e4fe2b40b802, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:39,312 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1d6d7f04-718a-4043-b6e9-e4fe2b40b802, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:39,313 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1d6d7f04-718a-4043-b6e9-e4fe2b40b802, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:39,693 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:39,694 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:39,695 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a90239ce-50c3-493a-9db1-eed45e19e94b, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:39,696 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a90239ce-50c3-493a-9db1-eed45e19e94b, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:39,698 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a90239ce-50c3-493a-9db1-eed45e19e94b, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:40,095 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:40,096 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637312, 'BlobCount': 256}}
2025-10-15 15:13:40,096 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:40,097 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 04569c36-a251-4bb4-bbf1-eebf0982f664, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:40,098 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 04569c36-a251-4bb4-bbf1-eebf0982f664, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:40,099 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 04569c36-a251-4bb4-bbf1-eebf0982f664, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:41,809 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:41,810 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:41,811 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 05895a6c-cf98-4446-9a1b-b0106546adf8, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:41,812 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 05895a6c-cf98-4446-9a1b-b0106546adf8, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:41,813 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 05895a6c-cf98-4446-9a1b-b0106546adf8, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:42,167 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:42,168 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:42,169 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2b254087-a9c2-4766-b298-b46b68a09011, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:42,170 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2b254087-a9c2-4766-b298-b46b68a09011, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:42,172 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2b254087-a9c2-4766-b298-b46b68a09011, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:42,966 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:42,967 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 183}}, blobs: {'__DEFAULT': {'Portions': 183, 'BlobSize': 958008, 'BlobCount': 384}}
2025-10-15 15:13:42,967 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:42,968 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e85239e2-5992-4b71-86ad-1455caeee0eb, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:42,969 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e85239e2-5992-4b71-86ad-1455caeee0eb, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:42,979 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e85239e2-5992-4b71-86ad-1455caeee0eb, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:44,809 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:44,809 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:44,810 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c0eb99e2-ec89-4a15-86d3-f85cda2c8fc6, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:44,810 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c0eb99e2-ec89-4a15-86d3-f85cda2c8fc6, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:44,811 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c0eb99e2-ec89-4a15-86d3-f85cda2c8fc6, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:45,137 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:45,138 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:45,139 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3bbdb43d-723e-4094-b605-a8028b1a31e4, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:45,140 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3bbdb43d-723e-4094-b605-a8028b1a31e4, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:45,141 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3bbdb43d-723e-4094-b605-a8028b1a31e4, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:45,804 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:45,805 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 241}}, blobs: {'__DEFAULT': {'Portions': 241, 'BlobSize': 1276872, 'BlobCount': 506}}
2025-10-15 15:13:45,805 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:45,806 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 677d37e9-79bf-48ef-b1d1-3ca3476208ff, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:45,807 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 677d37e9-79bf-48ef-b1d1-3ca3476208ff, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:45,811 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 677d37e9-79bf-48ef-b1d1-3ca3476208ff, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:47,425 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:47,426 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:47,427 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 490be1a6-686a-4103-aa13-0f4b786d9934, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:47,428 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 490be1a6-686a-4103-aa13-0f4b786d9934, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:47,432 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 490be1a6-686a-4103-aa13-0f4b786d9934, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:47,817 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:47,818 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:47,819 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d3947edf-6a1a-442f-b6e9-24e13446d1f6, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:47,819 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d3947edf-6a1a-442f-b6e9-24e13446d1f6, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:47,821 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d3947edf-6a1a-442f-b6e9-24e13446d1f6, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:48,453 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:48,454 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 506209, 'Portions': 288}}, blobs: {'__DEFAULT': {'Portions': 284, 'BlobSize': 1592424, 'BlobCount': 606}}
2025-10-15 15:13:48,455 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:48,455 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3d3abadf-f232-41e0-bd10-2b6be520e25d, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:48,456 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3d3abadf-f232-41e0-bd10-2b6be520e25d, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:48,470 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3d3abadf-f232-41e0-bd10-2b6be520e25d, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:50,175 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:50,176 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:50,177 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 225b6c8e-d217-4dac-b439-2469ef4ec479, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:50,178 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 225b6c8e-d217-4dac-b439-2469ef4ec479, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:50,187 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 225b6c8e-d217-4dac-b439-2469ef4ec479, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:50,551 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:50,551 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:50,552 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4d33ddf9-712c-4037-84dc-0b970151dc3e, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:50,553 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4d33ddf9-712c-4037-84dc-0b970151dc3e, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:50,554 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4d33ddf9-712c-4037-84dc-0b970151dc3e, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:51,148 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:51,149 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 336}}, blobs: {'__DEFAULT': {'Portions': 336, 'BlobSize': 1909144, 'BlobCount': 716}}
2025-10-15 15:13:51,150 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:51,150 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e2c91874-0ae5-43fc-9eec-ffc4b560ee39, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:51,151 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e2c91874-0ae5-43fc-9eec-ffc4b560ee39, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:51,152 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e2c91874-0ae5-43fc-9eec-ffc4b560ee39, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:52,495 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:52,496 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:52,497 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cba38691-65a6-4dfd-b5dd-7d466939b617, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:52,498 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cba38691-65a6-4dfd-b5dd-7d466939b617, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:52,500 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cba38691-65a6-4dfd-b5dd-7d466939b617, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:53,001 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:53,002 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:53,002 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1225d5b6-65b1-47d2-a580-7e4c81a3cfd5, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:53,004 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1225d5b6-65b1-47d2-a580-7e4c81a3cfd5, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:53,005 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1225d5b6-65b1-47d2-a580-7e4c81a3cfd5, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:53,618 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:53,619 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 400}}, blobs: {'__DEFAULT': {'Portions': 400, 'BlobSize': 2228280, 'BlobCount': 844}}
2025-10-15 15:13:53,619 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:53,620 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d46e4103-6f2d-469a-aa86-7d274dae833c, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:53,621 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d46e4103-6f2d-469a-aa86-7d274dae833c, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:53,623 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d46e4103-6f2d-469a-aa86-7d274dae833c, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:55,128 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:55,129 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:55,130 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 49bee09d-88fd-4898-afda-46c1421bf8c7, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:55,131 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 49bee09d-88fd-4898-afda-46c1421bf8c7, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:55,135 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 49bee09d-88fd-4898-afda-46c1421bf8c7, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:55,535 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:55,536 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:55,537 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6fc74eba-eaf0-4a8e-9174-900fbcd4df72, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:55,538 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6fc74eba-eaf0-4a8e-9174-900fbcd4df72, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:55,540 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6fc74eba-eaf0-4a8e-9174-900fbcd4df72, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:56,262 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:56,263 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 810887, 'Portions': 459}}, blobs: {'__DEFAULT': {'Portions': 452, 'BlobSize': 2544792, 'BlobCount': 952}}
2025-10-15 15:13:56,264 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:56,264 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6076b648-5c67-4f70-9a9f-a7f43a636128, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:56,265 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6076b648-5c67-4f70-9a9f-a7f43a636128, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:56,274 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6076b648-5c67-4f70-9a9f-a7f43a636128, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:57,681 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:57,682 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:57,683 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e5b426bd-e9d3-4071-8d63-3d06ac020599, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:57,684 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e5b426bd-e9d3-4071-8d63-3d06ac020599, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:57,686 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e5b426bd-e9d3-4071-8d63-3d06ac020599, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:58,181 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:58,182 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:58,183 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 463f590f-0672-4e1d-8450-cf2b3ebfc74a, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:58,184 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 463f590f-0672-4e1d-8450-cf2b3ebfc74a, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:58,190 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 463f590f-0672-4e1d-8450-cf2b3ebfc74a, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:58,923 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:58,924 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 509}}, blobs: {'__DEFAULT': {'Portions': 509, 'BlobSize': 2861952, 'BlobCount': 1068}}
2025-10-15 15:13:58,925 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:13:58,926 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1c062829-2fe0-4cfc-a5a4-5c2f4f574054, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:13:58,927 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1c062829-2fe0-4cfc-a5a4-5c2f4f574054, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:58,930 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1c062829-2fe0-4cfc-a5a4-5c2f4f574054, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:00,625 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:00,626 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:00,626 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2af73389-7458-4920-bee6-f0cb58a661bb, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:00,627 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2af73389-7458-4920-bee6-f0cb58a661bb, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:00,628 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2af73389-7458-4920-bee6-f0cb58a661bb, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:01,306 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:01,307 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:01,308 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 49727f48-e398-4451-84d3-6ca3c698eee4, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:01,309 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 49727f48-e398-4451-84d3-6ca3c698eee4, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:01,310 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 49727f48-e398-4451-84d3-6ca3c698eee4, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:02,809 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:02,810 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 573}}, blobs: {'__DEFAULT': {'Portions': 573, 'BlobSize': 3181456, 'BlobCount': 1196}}
2025-10-15 15:14:02,811 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:02,811 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8c878605-a325-4f8d-b31c-17028336ecca, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:02,812 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8c878605-a325-4f8d-b31c-17028336ecca, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_6_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:02,818 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8c878605-a325-4f8d-b31c-17028336ecca, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:04,557 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:04,557 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:04,558 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d5024417-29f0-4063-9780-1d98a68342ab, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:04,559 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d5024417-29f0-4063-9780-1d98a68342ab, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:04,560 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d5024417-29f0-4063-9780-1d98a68342ab, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:05,164 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:05,164 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:05,165 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f8b26a40-59fa-4c88-82ff-bcec8bc1458a, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:05,166 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f8b26a40-59fa-4c88-82ff-bcec8bc1458a, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:05,167 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f8b26a40-59fa-4c88-82ff-bcec8bc1458a, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:05,523 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:05,524 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:05,524 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 09410dbe-c9b1-4ec8-b6cc-b68ec184d893, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:05,525 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 09410dbe-c9b1-4ec8-b6cc-b68ec184d893, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:05,527 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 09410dbe-c9b1-4ec8-b6cc-b68ec184d893, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:10,523 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:20,579 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:20,580 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 48f24c73-3795-42a6-a23b-e4cc79bb2502, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:20,581 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 48f24c73-3795-42a6-a23b-e4cc79bb2502, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:20,583 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 48f24c73-3795-42a6-a23b-e4cc79bb2502, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:24,530 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:24,573 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_6_compression`, volumes `value` (8000000, 1578736)
2025-10-15 15:14:24,573 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:24,574 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eee2691b-35ca-41f7-94aa-ff1215d331bc, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:24,575 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eee2691b-35ca-41f7-94aa-ff1215d331bc, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:24,576 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eee2691b-35ca-41f7-94aa-ff1215d331bc, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:24,966 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:24,967 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:24,968 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c11b9826-39fd-4154-9384-36c7c4a3edee, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:24,969 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c11b9826-39fd-4154-9384-36c7c4a3edee, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:24,970 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c11b9826-39fd-4154-9384-36c7c4a3edee, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:29,370 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:39,418 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:39,419 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d8d438b7-4cfb-40c7-a1cb-3a4cec04e6d6, ghrun-lvxbqnxafi.auto.internal:22481): creating call state
2025-10-15 15:14:39,420 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d8d438b7-4cfb-40c7-a1cb-3a4cec04e6d6, ghrun-lvxbqnxafi.auto.internal:22481): request = { session_id: "ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:39,421 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d8d438b7-4cfb-40c7-a1cb-3a4cec04e6d6, ghrun-lvxbqnxafi.auto.internal:22481): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:41,048 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjA3M2VhZmItODU2N2E1ODctZmNjN2NiZWMtOWQ1YTY4MWU=
2025-10-15 15:14:41,081 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_6_compression`, volumes `value` (8000000, 1501976)
2025-10-15 15:14:41,082 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_6_compression` 8081504 / 1501976: 5.380581314215407
2025-10-15 15:14:41,084 - INFO - ya.test - pytest_runtest_teardown: Test teardown
