2025-10-15 15:11:47,375 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:11:47,376 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_8_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 8]
2025-10-15 15:11:47,376 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:11:47,377 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-15 15:11:47,380 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-15 15:11:47,381 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-15 15:11:47,382 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001c08/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:11:47,389 - DEBUG - ya.test - execute: Command pid: 567255
2025-10-15 15:11:48,569 - DEBUG - ya.test - _save_outputs: Command (pid 567255) rc: 0
2025-10-15 15:11:48,570 - DEBUG - ya.test - _save_outputs: Command (pid 567255) elapsed time (sec): 1.1785962581634521
2025-10-15 15:11:48,570 - DEBUG - ya.test - _save_outputs: Command (pid 567255) idrss: 0
2025-10-15 15:11:48,571 - DEBUG - ya.test - _save_outputs: Command (pid 567255) inblock: 0
2025-10-15 15:11:48,571 - DEBUG - ya.test - _save_outputs: Command (pid 567255) isrss: 0
2025-10-15 15:11:48,572 - DEBUG - ya.test - _save_outputs: Command (pid 567255) ixrss: 0
2025-10-15 15:11:48,572 - DEBUG - ya.test - _save_outputs: Command (pid 567255) majflt: 0
2025-10-15 15:11:48,572 - DEBUG - ya.test - _save_outputs: Command (pid 567255) maxrss: 477804
2025-10-15 15:11:48,573 - DEBUG - ya.test - _save_outputs: Command (pid 567255) minflt: 45079
2025-10-15 15:11:48,573 - DEBUG - ya.test - _save_outputs: Command (pid 567255) msgrcv: 0
2025-10-15 15:11:48,573 - DEBUG - ya.test - _save_outputs: Command (pid 567255) msgsnd: 0
2025-10-15 15:11:48,574 - DEBUG - ya.test - _save_outputs: Command (pid 567255) nivcsw: 184
2025-10-15 15:11:48,574 - DEBUG - ya.test - _save_outputs: Command (pid 567255) nsignals: 0
2025-10-15 15:11:48,575 - DEBUG - ya.test - _save_outputs: Command (pid 567255) nswap: 0
2025-10-15 15:11:48,575 - DEBUG - ya.test - _save_outputs: Command (pid 567255) nvcsw: 14
2025-10-15 15:11:48,575 - DEBUG - ya.test - _save_outputs: Command (pid 567255) oublock: 8
2025-10-15 15:11:48,576 - DEBUG - ya.test - _save_outputs: Command (pid 567255) stime: 0.232435
2025-10-15 15:11:48,581 - DEBUG - ya.test - _save_outputs: Command (pid 567255) utime: 0.536883
2025-10-15 15:11:48,582 - DEBUG - ya.test - _save_outputs: Command (pid 567255) wtime: 1.187
2025-10-15 15:11:48,582 - DEBUG - ya.test - _save_outputs: Command (pid 567255) 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:48,583 - DEBUG - ya.test - _save_outputs: Command (pid 567255) errors:

2025-10-15 15:11:48,583 - 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:48,719 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001c08/ydb/apps/ydbd/ydbd
2025-10-15 15:11:48,720 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20329
2025-10-15 15:11:48,724 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/w2kl/001c08/r3tmp/kikimr_cluster_0ej11qdg
2025-10-15 15:11:48,799 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001c08/ydb/apps/ydbd/ydbd
2025-10-15 15:11:48,800 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10291
2025-10-15 15:11:48,800 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4411
2025-10-15 15:11:48,801 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11562
2025-10-15 15:11:48,802 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19869
2025-10-15 15:11:48,803 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001c08/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/kikimr_configs"
2025-10-15 15:11:48,803 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001c08/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/001c08/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/node_1/logfile_0btz157d.log --grpc-port=10291 --mon-port=4411 --ic-port=20329
2025-10-15 15:11:48,804 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:10291/1, disk_size 68719476736
2025-10-15 15:11:48,804 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001c08/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/kikimr_configs"
2025-10-15 15:11:48,805 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001c08/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/001c08/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/node_1/logfile_0btz157d.log --grpc-port=10291 --mon-port=4411 --ic-port=20329
2025-10-15 15:11:48,806 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/w2kl/001c08/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/w2kl/001c08/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/w2kl/001c08/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/node_1/logfile_0btz157d.log', '--grpc-port=10291', '--mon-port=4411', '--ic-port=20329')' in '/home/runner/.ya/build/build_root/w2kl/001c08/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/node_1' (no wait)
2025-10-15 15:11:48,809 - DEBUG - ya.test - execute: Command pid: 567423
2025-10-15 15:11:48,810 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:10291/1
2025-10-15 15:11:48,812 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-15 15:11:48,812 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-15 15:11:48,813 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001c08/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:11:48,815 - DEBUG - ya.test - execute: Command pid: 567424
2025-10-15 15:11:50,049 - DEBUG - ya.test - _save_outputs: Command (pid 567424) rc: 0
2025-10-15 15:11:50,050 - DEBUG - ya.test - _save_outputs: Command (pid 567424) elapsed time (sec): 1.2335896492004395
2025-10-15 15:11:50,050 - DEBUG - ya.test - _save_outputs: Command (pid 567424) idrss: 0
2025-10-15 15:11:50,051 - DEBUG - ya.test - _save_outputs: Command (pid 567424) inblock: 0
2025-10-15 15:11:50,051 - DEBUG - ya.test - _save_outputs: Command (pid 567424) isrss: 0
2025-10-15 15:11:50,051 - DEBUG - ya.test - _save_outputs: Command (pid 567424) ixrss: 0
2025-10-15 15:11:50,052 - DEBUG - ya.test - _save_outputs: Command (pid 567424) majflt: 0
2025-10-15 15:11:50,052 - DEBUG - ya.test - _save_outputs: Command (pid 567424) maxrss: 486448
2025-10-15 15:11:50,052 - DEBUG - ya.test - _save_outputs: Command (pid 567424) minflt: 45121
2025-10-15 15:11:50,053 - DEBUG - ya.test - _save_outputs: Command (pid 567424) msgrcv: 0
2025-10-15 15:11:50,054 - DEBUG - ya.test - _save_outputs: Command (pid 567424) msgsnd: 0
2025-10-15 15:11:50,054 - DEBUG - ya.test - _save_outputs: Command (pid 567424) nivcsw: 176
2025-10-15 15:11:50,054 - DEBUG - ya.test - _save_outputs: Command (pid 567424) nsignals: 0
2025-10-15 15:11:50,055 - DEBUG - ya.test - _save_outputs: Command (pid 567424) nswap: 0
2025-10-15 15:11:50,055 - DEBUG - ya.test - _save_outputs: Command (pid 567424) nvcsw: 14
2025-10-15 15:11:50,056 - DEBUG - ya.test - _save_outputs: Command (pid 567424) oublock: 8
2025-10-15 15:11:50,056 - DEBUG - ya.test - _save_outputs: Command (pid 567424) stime: 0.23485
2025-10-15 15:11:50,056 - DEBUG - ya.test - _save_outputs: Command (pid 567424) utime: 0.517882
2025-10-15 15:11:50,056 - DEBUG - ya.test - _save_outputs: Command (pid 567424) wtime: 1.235
2025-10-15 15:11:50,061 - DEBUG - ya.test - _save_outputs: Command (pid 567424) 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:50,062 - DEBUG - ya.test - _save_outputs: Command (pid 567424) errors:

2025-10-15 15:11:50,062 - 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:55,743 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001c08/ydb/apps/ydbd/ydbd
2025-10-15 15:11:55,744 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2kl/001c08/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:10291', '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: 20329\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-15 15:11:55,746 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-15 15:11:55,746 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-15 15:11:55,747 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001c08/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:10291', '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: 20329\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-15 15:11:55,765 - DEBUG - ya.test - execute: Command pid: 568606
2025-10-15 15:11:57,735 - DEBUG - ya.test - _save_outputs: Command (pid 568606) rc: 0
2025-10-15 15:11:57,736 - DEBUG - ya.test - _save_outputs: Command (pid 568606) elapsed time (sec): 1.9694504737854004
2025-10-15 15:11:57,736 - DEBUG - ya.test - _save_outputs: Command (pid 568606) idrss: 0
2025-10-15 15:11:57,737 - DEBUG - ya.test - _save_outputs: Command (pid 568606) inblock: 0
2025-10-15 15:11:57,737 - DEBUG - ya.test - _save_outputs: Command (pid 568606) isrss: 0
2025-10-15 15:11:57,737 - DEBUG - ya.test - _save_outputs: Command (pid 568606) ixrss: 0
2025-10-15 15:11:57,737 - DEBUG - ya.test - _save_outputs: Command (pid 568606) majflt: 0
2025-10-15 15:11:57,738 - DEBUG - ya.test - _save_outputs: Command (pid 568606) maxrss: 588180
2025-10-15 15:11:57,738 - DEBUG - ya.test - _save_outputs: Command (pid 568606) minflt: 51354
2025-10-15 15:11:57,738 - DEBUG - ya.test - _save_outputs: Command (pid 568606) msgrcv: 0
2025-10-15 15:11:57,739 - DEBUG - ya.test - _save_outputs: Command (pid 568606) msgsnd: 0
2025-10-15 15:11:57,739 - DEBUG - ya.test - _save_outputs: Command (pid 568606) nivcsw: 229
2025-10-15 15:11:57,739 - DEBUG - ya.test - _save_outputs: Command (pid 568606) nsignals: 0
2025-10-15 15:11:57,740 - DEBUG - ya.test - _save_outputs: Command (pid 568606) nswap: 0
2025-10-15 15:11:57,740 - DEBUG - ya.test - _save_outputs: Command (pid 568606) nvcsw: 414
2025-10-15 15:11:57,741 - DEBUG - ya.test - _save_outputs: Command (pid 568606) oublock: 8
2025-10-15 15:11:57,741 - DEBUG - ya.test - _save_outputs: Command (pid 568606) stime: 0.28740099999999996
2025-10-15 15:11:57,741 - DEBUG - ya.test - _save_outputs: Command (pid 568606) utime: 0.554365
2025-10-15 15:11:57,741 - DEBUG - ya.test - _save_outputs: Command (pid 568606) wtime: 1.988
2025-10-15 15:11:57,742 - DEBUG - ya.test - _save_outputs: Command (pid 568606) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-15 15:11:57,742 - DEBUG - ya.test - _save_outputs: Command (pid 568606) errors:

2025-10-15 15:11:57,744 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001c08/ydb/apps/ydbd/ydbd
2025-10-15 15:11:57,744 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2kl/001c08/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:10291', '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:57,746 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-15 15:11:57,747 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-15 15:11:57,747 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001c08/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:10291', '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:57,760 - DEBUG - ya.test - execute: Command pid: 568987
2025-10-15 15:11:59,113 - DEBUG - ya.test - _save_outputs: Command (pid 568987) rc: 0
2025-10-15 15:11:59,113 - DEBUG - ya.test - _save_outputs: Command (pid 568987) elapsed time (sec): 1.3515536785125732
2025-10-15 15:11:59,114 - DEBUG - ya.test - _save_outputs: Command (pid 568987) idrss: 0
2025-10-15 15:11:59,114 - DEBUG - ya.test - _save_outputs: Command (pid 568987) inblock: 0
2025-10-15 15:11:59,114 - DEBUG - ya.test - _save_outputs: Command (pid 568987) isrss: 0
2025-10-15 15:11:59,115 - DEBUG - ya.test - _save_outputs: Command (pid 568987) ixrss: 0
2025-10-15 15:11:59,115 - DEBUG - ya.test - _save_outputs: Command (pid 568987) majflt: 0
2025-10-15 15:11:59,115 - DEBUG - ya.test - _save_outputs: Command (pid 568987) maxrss: 589068
2025-10-15 15:11:59,116 - DEBUG - ya.test - _save_outputs: Command (pid 568987) minflt: 51694
2025-10-15 15:11:59,116 - DEBUG - ya.test - _save_outputs: Command (pid 568987) msgrcv: 0
2025-10-15 15:11:59,116 - DEBUG - ya.test - _save_outputs: Command (pid 568987) msgsnd: 0
2025-10-15 15:11:59,117 - DEBUG - ya.test - _save_outputs: Command (pid 568987) nivcsw: 283
2025-10-15 15:11:59,117 - DEBUG - ya.test - _save_outputs: Command (pid 568987) nsignals: 0
2025-10-15 15:11:59,118 - DEBUG - ya.test - _save_outputs: Command (pid 568987) nswap: 0
2025-10-15 15:11:59,118 - DEBUG - ya.test - _save_outputs: Command (pid 568987) nvcsw: 656
2025-10-15 15:11:59,118 - DEBUG - ya.test - _save_outputs: Command (pid 568987) oublock: 8
2025-10-15 15:11:59,119 - DEBUG - ya.test - _save_outputs: Command (pid 568987) stime: 0.31331899999999996
2025-10-15 15:11:59,119 - DEBUG - ya.test - _save_outputs: Command (pid 568987) utime: 0.517805
2025-10-15 15:11:59,119 - DEBUG - ya.test - _save_outputs: Command (pid 568987) wtime: 1.365
2025-10-15 15:11:59,120 - DEBUG - ya.test - _save_outputs: Command (pid 568987) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-15 15:11:59,120 - DEBUG - ya.test - _save_outputs: Command (pid 568987) errors:

2025-10-15 15:11:59,120 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-15 15:11:59,293 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-15 15:11:59,354 - 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:59,369 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:11:59,370 - 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:59,442 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:11:59,443 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 488dec9d-3ac0-4bb4-9211-b997fb6726a2, localhost:10291): creating call state
2025-10-15 15:11:59,444 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 488dec9d-3ac0-4bb4-9211-b997fb6726a2, localhost:10291): request = { database: "/Root" }
2025-10-15 15:11:59,459 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 488dec9d-3ac0-4bb4-9211-b997fb6726a2, localhost:10291): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 10291 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:59,460 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:10291, location 1, ssl: False>]>
2025-10-15 15:11:59,462 - 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:59,480 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10291
2025-10-15 15:11:59,481 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-10-15 15:11:59,482 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ef08aa73-716e-42a8-b1f2-9344496df567, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:11:59,483 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ef08aa73-716e-42a8-b1f2-9344496df567, ghrun-lvxbqnxafi.auto.internal:10291): request = {  }
2025-10-15 15:11:59,491 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ef08aa73-716e-42a8-b1f2-9344496df567, ghrun-lvxbqnxafi.auto.internal:10291): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" node_id: 1 }
2025-10-15 15:11:59,492 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, c995f0a1-a6ec-4297-96dc-82f76d4e1ce6, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:11:59,493 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, c995f0a1-a6ec-4297-96dc-82f76d4e1ce6, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" }
2025-10-15 15:11:59,497 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, c995f0a1-a6ec-4297-96dc-82f76d4e1ce6, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:11:59,514 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:11:59,515 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 36ced4d3-3efb-43a2-951e-ede99477101d, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:11:59,517 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 36ced4d3-3efb-43a2-951e-ede99477101d, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:59,518 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 36ced4d3-3efb-43a2-951e-ede99477101d, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:03,458 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:03,459 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-10-15 15:12:03,459 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:03,460 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 08634fa1-a836-426b-b16e-27a7d8ec53dd, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:03,463 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 08634fa1-a836-426b-b16e-27a7d8ec53dd, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:03,465 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 08634fa1-a836-426b-b16e-27a7d8ec53dd, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:04,836 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:04,837 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:04,837 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a97a8730-f324-4079-870e-07ab0f9d0388, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:04,838 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a97a8730-f324-4079-870e-07ab0f9d0388, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:12:04,840 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a97a8730-f324-4079-870e-07ab0f9d0388, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:06,498 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:06,499 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:06,500 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5e2ee16b-2f22-4a75-a756-52f99f099e0f, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:06,501 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5e2ee16b-2f22-4a75-a756-52f99f099e0f, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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,503 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5e2ee16b-2f22-4a75-a756-52f99f099e0f, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:07,487 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:07,488 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:07,489 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f9c34081-5396-4cca-b437-997af8dde88d, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:07,490 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f9c34081-5396-4cca-b437-997af8dde88d, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:07,492 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f9c34081-5396-4cca-b437-997af8dde88d, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:09,431 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:09,432 - 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:09,433 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:09,433 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7111282e-d08c-4c18-a9dc-359bdd32c15a, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:09,434 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7111282e-d08c-4c18-a9dc-359bdd32c15a, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:09,436 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7111282e-d08c-4c18-a9dc-359bdd32c15a, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:10,840 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:10,841 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:10,842 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 49a2a296-7917-4607-81eb-63294bf7700c, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:10,843 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 49a2a296-7917-4607-81eb-63294bf7700c, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:10,844 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 49a2a296-7917-4607-81eb-63294bf7700c, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:11,109 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:11,110 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:11,111 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 56e35672-3dfb-4b0f-9530-a2d10435eff1, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:11,112 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 56e35672-3dfb-4b0f-9530-a2d10435eff1, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:11,113 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 56e35672-3dfb-4b0f-9530-a2d10435eff1, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:11,789 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:11,790 - 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:11,790 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:11,791 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fe82b157-f716-4c7c-9ce5-f5cba466f5b1, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:11,793 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fe82b157-f716-4c7c-9ce5-f5cba466f5b1, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:11,794 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fe82b157-f716-4c7c-9ce5-f5cba466f5b1, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:13,788 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:13,789 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:13,790 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e3288091-f1bc-47df-9848-795bd1dccd6c, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:13,790 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e3288091-f1bc-47df-9848-795bd1dccd6c, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:13,792 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e3288091-f1bc-47df-9848-795bd1dccd6c, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:14,237 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:14,238 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:14,239 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 11e6b282-332e-43ca-a65f-99784f2f3adf, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:14,240 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 11e6b282-332e-43ca-a65f-99784f2f3adf, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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,246 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 11e6b282-332e-43ca-a65f-99784f2f3adf, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:14,751 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:14,752 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 187}}, blobs: {'__DEFAULT': {'Portions': 187, 'BlobSize': 4856952, 'BlobCount': 384}}
2025-10-15 15:12:14,752 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:14,753 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 96a6ead5-f014-45e3-8f4e-27fe7cee7d6f, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:14,754 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 96a6ead5-f014-45e3-8f4e-27fe7cee7d6f, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:14,756 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 96a6ead5-f014-45e3-8f4e-27fe7cee7d6f, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:16,231 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:16,232 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:16,232 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 428c2cd0-28ac-469c-9a7f-310eabc0ea4e, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:16,235 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 428c2cd0-28ac-469c-9a7f-310eabc0ea4e, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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,236 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 428c2cd0-28ac-469c-9a7f-310eabc0ea4e, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:16,634 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:16,635 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:16,636 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bd835fa0-4b9d-41e4-911d-c88014d1b1c2, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:16,636 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bd835fa0-4b9d-41e4-911d-c88014d1b1c2, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:16,643 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bd835fa0-4b9d-41e4-911d-c88014d1b1c2, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:17,126 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:17,127 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 243}}, blobs: {'__DEFAULT': {'Portions': 243, 'BlobSize': 6476016, 'BlobCount': 504}}
2025-10-15 15:12:17,128 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:17,129 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 04ff015b-fba8-40a3-bfab-b8817f0fb238, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:17,130 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 04ff015b-fba8-40a3-bfab-b8817f0fb238, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:17,131 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 04ff015b-fba8-40a3-bfab-b8817f0fb238, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:18,338 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:18,339 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:18,340 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, abdc1fa6-5421-4ef6-96dd-a7f5b8f3a696, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:18,341 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, abdc1fa6-5421-4ef6-96dd-a7f5b8f3a696, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:18,344 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, abdc1fa6-5421-4ef6-96dd-a7f5b8f3a696, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:18,901 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:18,902 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:18,903 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 512cc017-0c34-4da4-8337-95ec995ee4fb, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:18,904 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 512cc017-0c34-4da4-8337-95ec995ee4fb, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:18,906 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 512cc017-0c34-4da4-8337-95ec995ee4fb, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:19,261 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:19,262 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 506156, 'Portions': 302}}, blobs: {'__DEFAULT': {'Portions': 298, 'BlobSize': 8094368, 'BlobCount': 620}}
2025-10-15 15:12:19,262 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:19,263 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e64bb047-8961-44d9-a262-71cca2228565, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:19,264 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e64bb047-8961-44d9-a262-71cca2228565, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:19,265 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e64bb047-8961-44d9-a262-71cca2228565, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:20,530 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:20,531 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:20,532 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 08bf1ba5-b083-44c2-9986-93c6b8279573, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:20,533 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 08bf1ba5-b083-44c2-9986-93c6b8279573, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:20,534 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 08bf1ba5-b083-44c2-9986-93c6b8279573, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:20,893 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:20,894 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:20,895 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e2d639cf-1402-4d31-881c-28c25c29a689, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:20,896 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e2d639cf-1402-4d31-881c-28c25c29a689, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:20,898 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e2d639cf-1402-4d31-881c-28c25c29a689, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:21,458 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:21,459 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 615721, 'Portions': 360}}, blobs: {'__DEFAULT': {'Portions': 350, 'BlobSize': 9712224, 'BlobCount': 730}}
2025-10-15 15:12:21,459 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:21,460 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f715f64d-37cd-4d85-80bf-a3a9b79d3b64, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:21,461 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f715f64d-37cd-4d85-80bf-a3a9b79d3b64, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:21,463 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f715f64d-37cd-4d85-80bf-a3a9b79d3b64, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:22,689 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:22,690 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:22,690 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6baeaf81-aea6-4ad2-aebe-198fc29e8442, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:22,691 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6baeaf81-aea6-4ad2-aebe-198fc29e8442, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:22,694 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6baeaf81-aea6-4ad2-aebe-198fc29e8442, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:23,073 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:23,074 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:23,075 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 68b6b87e-0e11-43b3-bcf6-1b8cc333d4e5, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:23,076 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 68b6b87e-0e11-43b3-bcf6-1b8cc333d4e5, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:23,077 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 68b6b87e-0e11-43b3-bcf6-1b8cc333d4e5, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:23,696 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:23,697 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 409}}, blobs: {'__DEFAULT': {'Portions': 409, 'BlobSize': 11330320, 'BlobCount': 850}}
2025-10-15 15:12:23,698 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:23,698 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d58e8a72-dfa0-4dd0-b031-cf1f79f16cd2, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:23,699 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d58e8a72-dfa0-4dd0-b031-cf1f79f16cd2, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:23,701 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d58e8a72-dfa0-4dd0-b031-cf1f79f16cd2, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:25,071 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:25,072 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:25,073 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 25e8a623-a655-44a1-817d-e73936366f49, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:25,074 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 25e8a623-a655-44a1-817d-e73936366f49, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:25,077 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 25e8a623-a655-44a1-817d-e73936366f49, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:25,422 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:25,423 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:25,424 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 42a81c64-88ce-466a-89c6-71ac0e57802f, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:25,425 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 42a81c64-88ce-466a-89c6-71ac0e57802f, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:25,427 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 42a81c64-88ce-466a-89c6-71ac0e57802f, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:25,923 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:25,924 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 455}}, blobs: {'__DEFAULT': {'Portions': 443, 'BlobSize': 12946176, 'BlobCount': 928}}
2025-10-15 15:12:25,924 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:25,925 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 17099ac2-1ff3-45ee-94a5-d3a1515fc8b9, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:25,926 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 17099ac2-1ff3-45ee-94a5-d3a1515fc8b9, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:25,935 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 17099ac2-1ff3-45ee-94a5-d3a1515fc8b9, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:27,310 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:27,311 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:27,312 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c8a33d99-f9e3-4d6c-962f-99da85b699cd, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:27,313 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c8a33d99-f9e3-4d6c-962f-99da85b699cd, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:27,314 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c8a33d99-f9e3-4d6c-962f-99da85b699cd, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:27,610 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:27,611 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:27,612 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a9a95671-c011-4e4e-a97e-2e640aac8920, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:27,613 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a9a95671-c011-4e4e-a97e-2e640aac8920, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:27,615 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a9a95671-c011-4e4e-a97e-2e640aac8920, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:28,338 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:28,338 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 500}}, blobs: {'__DEFAULT': {'Portions': 500, 'BlobSize': 14563928, 'BlobCount': 1044}}
2025-10-15 15:12:28,339 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:28,340 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ce005c1c-88c6-4ccc-8749-c9e17ff87d0e, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:28,341 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ce005c1c-88c6-4ccc-8749-c9e17ff87d0e, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:28,343 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ce005c1c-88c6-4ccc-8749-c9e17ff87d0e, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:29,593 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:29,594 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:29,595 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f4f4631d-f7f6-44ec-9574-95d8eb9f12cc, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:29,596 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f4f4631d-f7f6-44ec-9574-95d8eb9f12cc, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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,598 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f4f4631d-f7f6-44ec-9574-95d8eb9f12cc, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:30,041 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:30,042 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:30,043 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f0feaf13-9285-42a8-b01a-075bcfd274e1, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:30,044 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f0feaf13-9285-42a8-b01a-075bcfd274e1, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:30,046 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f0feaf13-9285-42a8-b01a-075bcfd274e1, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:30,898 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:30,899 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1013999, 'Portions': 549}}, blobs: {'__DEFAULT': {'Portions': 524, 'BlobSize': 16178048, 'BlobCount': 1102}}
2025-10-15 15:12:30,900 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:30,900 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 86330137-25dd-41ae-9369-84b6943f446c, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:30,901 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 86330137-25dd-41ae-9369-84b6943f446c, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:30,903 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 86330137-25dd-41ae-9369-84b6943f446c, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:32,038 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:32,040 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:32,040 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aa34bfc1-0d05-44a8-9728-f92217ae3924, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:32,041 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aa34bfc1-0d05-44a8-9728-f92217ae3924, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:32,043 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aa34bfc1-0d05-44a8-9728-f92217ae3924, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:32,477 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:32,478 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:32,478 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c3ea27ad-fcd8-4d2b-90dc-b2e3a57bc407, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:32,485 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c3ea27ad-fcd8-4d2b-90dc-b2e3a57bc407, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:32,487 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c3ea27ad-fcd8-4d2b-90dc-b2e3a57bc407, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:32,892 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:32,893 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:32,894 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e5df34c5-681f-41af-8b90-cfaba3c9a52b, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:32,895 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e5df34c5-681f-41af-8b90-cfaba3c9a52b, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:32,898 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e5df34c5-681f-41af-8b90-cfaba3c9a52b, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:37,614 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:47,655 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:47,656 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 156e368c-32a9-4119-9a90-581e04ae44c9, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:47,657 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 156e368c-32a9-4119-9a90-581e04ae44c9, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:47,659 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 156e368c-32a9-4119-9a90-581e04ae44c9, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:51,808 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:51,873 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8075456)
2025-10-15 15:12:51,874 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:12:51,875 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e3a6a74-4f6c-4ad7-b625-1eab86b7335a, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:12:51,875 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e3a6a74-4f6c-4ad7-b625-1eab86b7335a, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:51,877 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e3a6a74-4f6c-4ad7-b625-1eab86b7335a, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:55,720 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:05,760 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:05,761 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2922448e-fe60-4e5b-be8b-f73e81165c8d, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:05,762 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2922448e-fe60-4e5b-be8b-f73e81165c8d, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:05,763 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2922448e-fe60-4e5b-be8b-f73e81165c8d, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:08,940 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:14,485 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:13:14,486 - 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:14,513 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:13:14,515 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, d0ba4daf-db36-4cfc-81bd-515da422a822, localhost:10291): creating call state
2025-10-15 15:13:14,516 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, d0ba4daf-db36-4cfc-81bd-515da422a822, localhost:10291): request = { database: "/Root" }
2025-10-15 15:13:14,525 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, d0ba4daf-db36-4cfc-81bd-515da422a822, localhost:10291): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 10291 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:14,527 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:10291, location 1, ssl: False>]>
2025-10-15 15:13:14,527 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10291
2025-10-15 15:13:19,013 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:19,014 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4d3d5bf1-d99f-463c-a7ef-0ea27ca47c59, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:19,015 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4d3d5bf1-d99f-463c-a7ef-0ea27ca47c59, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:19,016 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4d3d5bf1-d99f-463c-a7ef-0ea27ca47c59, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:20,629 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:30,642 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:30,644 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1b2ce3b3-f0a1-42c5-9a2f-705e0fd9bfc8, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:30,644 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1b2ce3b3-f0a1-42c5-9a2f-705e0fd9bfc8, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:30,646 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1b2ce3b3-f0a1-42c5-9a2f-705e0fd9bfc8, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:32,078 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:32,091 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8028800)
2025-10-15 15:13:32,092 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:32,094 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 57829d07-0d16-4398-8900-adb40ccc2f60, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:32,095 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 57829d07-0d16-4398-8900-adb40ccc2f60, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" 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:32,096 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 57829d07-0d16-4398-8900-adb40ccc2f60, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:32,467 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:32,471 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_8_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 8])
2025-10-15 15:13:32,472 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:32,475 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 58a8b699-e253-4750-a9bb-225b64ca03e3, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:32,476 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 58a8b699-e253-4750-a9bb-225b64ca03e3, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_8_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 8)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:32,478 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 58a8b699-e253-4750-a9bb-225b64ca03e3, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:36,369 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:36,370 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_8_compression created
2025-10-15 15:13:36,370 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:36,371 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f285a87d-07fb-4767-befb-c99b326ebad6, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:36,372 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f285a87d-07fb-4767-befb-c99b326ebad6, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_8_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:36,376 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f285a87d-07fb-4767-befb-c99b326ebad6, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:37,397 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:37,398 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:37,399 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be2d7784-7bae-4bd6-a126-d93933c6ddd8, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:37,400 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be2d7784-7bae-4bd6-a126-d93933c6ddd8, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:37,402 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be2d7784-7bae-4bd6-a126-d93933c6ddd8, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:39,990 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:39,990 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:39,991 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d3bd052d-9a0d-4011-8b22-7f0a694b2ab4, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:39,993 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d3bd052d-9a0d-4011-8b22-7f0a694b2ab4, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:39,994 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d3bd052d-9a0d-4011-8b22-7f0a694b2ab4, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:41,069 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:41,070 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:41,071 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb9d9a5f-b634-4d5c-867c-83f2f518dd0d, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:41,072 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb9d9a5f-b634-4d5c-867c-83f2f518dd0d, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:41,078 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb9d9a5f-b634-4d5c-867c-83f2f518dd0d, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:42,873 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:42,874 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318016, 'BlobCount': 128}}
2025-10-15 15:13:42,874 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:42,875 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7f3c0bf3-be2a-4abd-abbf-21bcb2236ecf, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:42,875 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7f3c0bf3-be2a-4abd-abbf-21bcb2236ecf, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:42,877 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7f3c0bf3-be2a-4abd-abbf-21bcb2236ecf, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:45,295 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:45,296 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:45,297 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 35a20163-b70e-43c1-b883-7ecb9db57796, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:45,298 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 35a20163-b70e-43c1-b883-7ecb9db57796, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:45,299 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 35a20163-b70e-43c1-b883-7ecb9db57796, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:45,638 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:45,639 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:45,640 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 59cc4a0f-711d-4f4b-9647-1e51ba34355d, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:45,644 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 59cc4a0f-711d-4f4b-9647-1e51ba34355d, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:45,646 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 59cc4a0f-711d-4f4b-9647-1e51ba34355d, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:46,466 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:46,466 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637312, 'BlobCount': 256}}
2025-10-15 15:13:46,467 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:46,467 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 76ed55f5-ebb3-450e-8ca7-efce355d5200, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:46,468 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 76ed55f5-ebb3-450e-8ca7-efce355d5200, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:46,470 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 76ed55f5-ebb3-450e-8ca7-efce355d5200, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:49,672 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:49,673 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:49,674 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 07ce36a5-4865-4998-8fb0-3279d984850f, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:49,674 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 07ce36a5-4865-4998-8fb0-3279d984850f, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:49,676 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 07ce36a5-4865-4998-8fb0-3279d984850f, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:50,116 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:50,118 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:50,118 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b597cbfd-4e32-4b6c-8c9e-42a9ea8d2a31, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:50,119 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b597cbfd-4e32-4b6c-8c9e-42a9ea8d2a31, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:50,122 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b597cbfd-4e32-4b6c-8c9e-42a9ea8d2a31, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:50,624 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:50,625 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 181}}, blobs: {'__DEFAULT': {'Portions': 181, 'BlobSize': 958312, 'BlobCount': 384}}
2025-10-15 15:13:50,626 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:50,627 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6d8aa89e-c46f-4cbc-8819-d01933c6d858, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:50,628 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6d8aa89e-c46f-4cbc-8819-d01933c6d858, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:50,630 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6d8aa89e-c46f-4cbc-8819-d01933c6d858, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:53,287 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:53,287 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:53,288 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bc814d9a-267b-4aed-8eb0-7a93983cbe9c, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:53,289 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bc814d9a-267b-4aed-8eb0-7a93983cbe9c, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:53,291 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bc814d9a-267b-4aed-8eb0-7a93983cbe9c, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:53,754 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:53,755 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:53,755 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c0cf418c-3df5-4b81-aeb0-a1f166316f64, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:53,756 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c0cf418c-3df5-4b81-aeb0-a1f166316f64, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:53,758 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c0cf418c-3df5-4b81-aeb0-a1f166316f64, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:54,457 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:54,458 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 237}}, blobs: {'__DEFAULT': {'Portions': 237, 'BlobSize': 1276984, 'BlobCount': 504}}
2025-10-15 15:13:54,458 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:54,459 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5eb934b6-8a03-4aa1-a50c-1ba15823dcba, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:54,460 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5eb934b6-8a03-4aa1-a50c-1ba15823dcba, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:54,466 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5eb934b6-8a03-4aa1-a50c-1ba15823dcba, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:56,894 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:56,895 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:56,896 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 695c5968-4bfb-486b-b55a-60a4d330e83a, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:56,897 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 695c5968-4bfb-486b-b55a-60a4d330e83a, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:56,898 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 695c5968-4bfb-486b-b55a-60a4d330e83a, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:57,257 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:57,258 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:57,258 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 11a29a60-c683-4424-ab75-151ef0b9986e, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:57,259 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 11a29a60-c683-4424-ab75-151ef0b9986e, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:57,265 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 11a29a60-c683-4424-ab75-151ef0b9986e, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:57,971 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:57,971 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 289}}, blobs: {'__DEFAULT': {'Portions': 289, 'BlobSize': 1593928, 'BlobCount': 616}}
2025-10-15 15:13:57,972 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:13:57,973 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f54e871e-daf7-49e2-b6a2-a9f1a9530421, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:13:57,974 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f54e871e-daf7-49e2-b6a2-a9f1a9530421, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:57,975 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f54e871e-daf7-49e2-b6a2-a9f1a9530421, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:00,269 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:00,270 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:00,270 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1198e5c9-f589-412c-bbc4-22a966b94d87, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:00,271 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1198e5c9-f589-412c-bbc4-22a966b94d87, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:00,272 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1198e5c9-f589-412c-bbc4-22a966b94d87, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:00,685 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:00,686 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:00,687 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 030f6392-57c3-4640-8571-a7a09ca525fd, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:00,688 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 030f6392-57c3-4640-8571-a7a09ca525fd, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:00,698 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 030f6392-57c3-4640-8571-a7a09ca525fd, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:01,549 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:01,550 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 337}}, blobs: {'__DEFAULT': {'Portions': 337, 'BlobSize': 1909744, 'BlobCount': 720}}
2025-10-15 15:14:01,551 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:01,552 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0842e150-ba53-4cb6-b3a7-11345f97debc, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:01,554 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0842e150-ba53-4cb6-b3a7-11345f97debc, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:01,556 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0842e150-ba53-4cb6-b3a7-11345f97debc, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:04,550 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:04,551 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:04,552 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8399971a-2186-4a2e-8de7-960266608621, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:04,552 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8399971a-2186-4a2e-8de7-960266608621, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:04,554 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8399971a-2186-4a2e-8de7-960266608621, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:05,027 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:05,028 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:05,028 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 639b2f00-ea9d-4d9d-8813-12efcd8e40f3, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:05,029 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 639b2f00-ea9d-4d9d-8813-12efcd8e40f3, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:05,032 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 639b2f00-ea9d-4d9d-8813-12efcd8e40f3, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:06,254 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:06,255 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 396}}, blobs: {'__DEFAULT': {'Portions': 396, 'BlobSize': 2227736, 'BlobCount': 840}}
2025-10-15 15:14:06,255 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:06,256 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e5737c1d-03b7-4251-af0b-1b5818533b5e, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:06,262 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e5737c1d-03b7-4251-af0b-1b5818533b5e, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:06,264 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e5737c1d-03b7-4251-af0b-1b5818533b5e, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:09,649 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:09,650 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:09,651 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 80687d1d-1222-423d-bbb8-eb3e22255334, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:09,652 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 80687d1d-1222-423d-bbb8-eb3e22255334, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:09,653 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 80687d1d-1222-423d-bbb8-eb3e22255334, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:09,962 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:09,963 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:09,964 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ed1a5aa9-ba50-41c2-8ffc-991bda5f207d, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:09,969 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ed1a5aa9-ba50-41c2-8ffc-991bda5f207d, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:09,971 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ed1a5aa9-ba50-41c2-8ffc-991bda5f207d, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:11,083 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:11,084 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 438}}, blobs: {'__DEFAULT': {'Portions': 438, 'BlobSize': 2541976, 'BlobCount': 932}}
2025-10-15 15:14:11,085 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:11,086 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, afb9a7ed-d3fe-48ba-8123-b66bd867575a, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:11,087 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, afb9a7ed-d3fe-48ba-8123-b66bd867575a, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:11,097 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, afb9a7ed-d3fe-48ba-8123-b66bd867575a, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:13,413 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:13,414 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:13,415 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fb72d04c-ef64-4aad-9e6b-8549f4596aed, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:13,416 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fb72d04c-ef64-4aad-9e6b-8549f4596aed, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:13,418 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fb72d04c-ef64-4aad-9e6b-8549f4596aed, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:13,729 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:13,730 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:13,731 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ef6790e2-980c-46b6-9349-7ec35d020ac7, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:13,732 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ef6790e2-980c-46b6-9349-7ec35d020ac7, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:13,738 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ef6790e2-980c-46b6-9349-7ec35d020ac7, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:14,554 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:14,559 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 439}}, blobs: {'__DEFAULT': {'Portions': 439, 'BlobSize': 2844496, 'BlobCount': 952}}
2025-10-15 15:14:14,559 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:14,560 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c9bf3295-678a-4959-bba6-7994797fec77, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:14,561 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c9bf3295-678a-4959-bba6-7994797fec77, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:14,563 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c9bf3295-678a-4959-bba6-7994797fec77, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:16,627 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:16,628 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:16,629 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c667eeda-7121-419b-89bd-ed87c48a2849, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:16,630 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c667eeda-7121-419b-89bd-ed87c48a2849, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:16,633 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c667eeda-7121-419b-89bd-ed87c48a2849, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:17,006 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:17,007 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:17,008 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e4a4ff0-48cf-4cfd-bc97-b00798e0c3b1, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:17,009 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e4a4ff0-48cf-4cfd-bc97-b00798e0c3b1, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:17,010 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e4a4ff0-48cf-4cfd-bc97-b00798e0c3b1, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:17,913 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:17,914 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 479}}, blobs: {'__DEFAULT': {'Portions': 479, 'BlobSize': 3157448, 'BlobCount': 1038}}
2025-10-15 15:14:17,914 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:17,915 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 96f9ce88-6e63-4bb4-bb18-3a3d9ec18607, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:17,916 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 96f9ce88-6e63-4bb4-bb18-3a3d9ec18607, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_8_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:17,918 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 96f9ce88-6e63-4bb4-bb18-3a3d9ec18607, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:19,172 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:19,173 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:19,174 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d26101cf-d6c4-416f-a9be-4e03f1fe91ae, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:19,175 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d26101cf-d6c4-416f-a9be-4e03f1fe91ae, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:19,176 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d26101cf-d6c4-416f-a9be-4e03f1fe91ae, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:19,477 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:19,478 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:19,479 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd3597fc-d2f7-48d0-8077-a6ad01dcbf8b, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:19,480 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd3597fc-d2f7-48d0-8077-a6ad01dcbf8b, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:19,482 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd3597fc-d2f7-48d0-8077-a6ad01dcbf8b, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:19,824 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:19,824 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:19,825 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 23ba4dbf-977a-4639-ae88-879d76c10e34, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:19,826 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 23ba4dbf-977a-4639-ae88-879d76c10e34, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:19,828 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 23ba4dbf-977a-4639-ae88-879d76c10e34, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:24,031 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:34,063 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:34,064 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a493f91a-e3e5-4394-a97c-cf693a4e8f7b, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:34,065 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a493f91a-e3e5-4394-a97c-cf693a4e8f7b, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:34,066 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a493f91a-e3e5-4394-a97c-cf693a4e8f7b, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:37,026 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:37,076 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_8_compression`, volumes `value` (8000000, 1557224)
2025-10-15 15:14:37,076 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:37,077 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, baa43607-e105-4a81-96ef-dd42ed3d7756, ghrun-lvxbqnxafi.auto.internal:10291): creating call state
2025-10-15 15:14:37,078 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, baa43607-e105-4a81-96ef-dd42ed3d7756, ghrun-lvxbqnxafi.auto.internal:10291): request = { session_id: "ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:37,085 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, baa43607-e105-4a81-96ef-dd42ed3d7756, ghrun-lvxbqnxafi.auto.internal:10291): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:37,500 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTIxYjcwMzctNDcyZWNmZmUtMzgzY2JhNWEtYWYzNmE2ZDM=
2025-10-15 15:14:38,388 - ERROR - ya.test - logreport: ydb/tests/olap/column_family/compression/alter_compression.py:194: in test_all_supported_compression
    assert table.get_portion_stat_by_tier()['__DEFAULT']['Rows'] == expected_raw // 8
E   assert 1015664 == (8000000 // 8)
2025-10-15 15:14:38,392 - INFO - ya.test - pytest_runtest_teardown: Test teardown
