2025-10-16 17:17:46,854 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:17:46,855 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_18_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 18]
2025-10-16 17:17:46,855 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:17:46,855 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-16 17:17:46,857 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-16 17:17:46,858 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-16 17:17:46,859 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0016e2/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 17:17:46,864 - DEBUG - ya.test - execute: Command pid: 1010600
2025-10-16 17:17:48,068 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) rc: 0
2025-10-16 17:17:48,069 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) elapsed time (sec): 1.2024714946746826
2025-10-16 17:17:48,069 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) idrss: 0
2025-10-16 17:17:48,070 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) inblock: 0
2025-10-16 17:17:48,070 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) isrss: 0
2025-10-16 17:17:48,070 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) ixrss: 0
2025-10-16 17:17:48,071 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) majflt: 0
2025-10-16 17:17:48,071 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) maxrss: 488188
2025-10-16 17:17:48,071 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) minflt: 45099
2025-10-16 17:17:48,071 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) msgrcv: 0
2025-10-16 17:17:48,072 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) msgsnd: 0
2025-10-16 17:17:48,072 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) nivcsw: 160
2025-10-16 17:17:48,072 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) nsignals: 0
2025-10-16 17:17:48,073 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) nswap: 0
2025-10-16 17:17:48,073 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) nvcsw: 14
2025-10-16 17:17:48,074 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) oublock: 8
2025-10-16 17:17:48,074 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) stime: 0.207069
2025-10-16 17:17:48,074 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) utime: 0.53064
2025-10-16 17:17:48,075 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) wtime: 1.209
2025-10-16 17:17:48,075 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) output:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-2kszfvc6cm
    Host information: 
        Linux ghrun-2kszfvc6cm 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-16 17:17:48,075 - DEBUG - ya.test - _save_outputs: Command (pid 1010600) errors:

2025-10-16 17:17:48,076 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-2kszfvc6cm
    Host information: 
        Linux ghrun-2kszfvc6cm 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-16 17:17:48,233 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0016e2/ydb/apps/ydbd/ydbd
2025-10-16 17:17:48,234 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10249
2025-10-16 17:17:48,237 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/b9t0/0016e2/r3tmp/kikimr_cluster_o0qh2bpk
2025-10-16 17:17:48,296 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0016e2/ydb/apps/ydbd/ydbd
2025-10-16 17:17:48,297 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21181
2025-10-16 17:17:48,298 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9734
2025-10-16 17:17:48,298 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9312
2025-10-16 17:17:48,299 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24978
2025-10-16 17:17:48,300 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/b9t0/0016e2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs"
2025-10-16 17:17:48,301 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/b9t0/0016e2/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/b9t0/0016e2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_y0snt8cn.log --grpc-port=21181 --mon-port=9734 --ic-port=10249
2025-10-16 17:17:48,301 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:21181/1, disk_size 68719476736
2025-10-16 17:17:48,302 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/b9t0/0016e2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs"
2025-10-16 17:17:48,302 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/b9t0/0016e2/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/b9t0/0016e2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_y0snt8cn.log --grpc-port=21181 --mon-port=9734 --ic-port=10249
2025-10-16 17:17:48,303 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/b9t0/0016e2/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/b9t0/0016e2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/b9t0/0016e2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_y0snt8cn.log', '--grpc-port=21181', '--mon-port=9734', '--ic-port=10249')' in '/home/runner/.ya/build/build_root/b9t0/0016e2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1' (no wait)
2025-10-16 17:17:48,321 - DEBUG - ya.test - execute: Command pid: 1010871
2025-10-16 17:17:48,322 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:21181/1
2025-10-16 17:17:48,323 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-16 17:17:48,324 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-16 17:17:48,325 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0016e2/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 17:17:48,340 - DEBUG - ya.test - execute: Command pid: 1010874
2025-10-16 17:17:49,188 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) rc: 0
2025-10-16 17:17:49,189 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) elapsed time (sec): 0.8462615013122559
2025-10-16 17:17:49,189 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) idrss: 0
2025-10-16 17:17:49,189 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) inblock: 0
2025-10-16 17:17:49,190 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) isrss: 0
2025-10-16 17:17:49,190 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) ixrss: 0
2025-10-16 17:17:49,190 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) majflt: 0
2025-10-16 17:17:49,191 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) maxrss: 488188
2025-10-16 17:17:49,191 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) minflt: 45067
2025-10-16 17:17:49,191 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) msgrcv: 0
2025-10-16 17:17:49,192 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) msgsnd: 0
2025-10-16 17:17:49,192 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) nivcsw: 149
2025-10-16 17:17:49,192 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) nsignals: 0
2025-10-16 17:17:49,193 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) nswap: 0
2025-10-16 17:17:49,193 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) nvcsw: 15
2025-10-16 17:17:49,193 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) oublock: 8
2025-10-16 17:17:49,194 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) stime: 0.17516199999999998
2025-10-16 17:17:49,194 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) utime: 0.514732
2025-10-16 17:17:49,194 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) wtime: 0.863
2025-10-16 17:17:49,195 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) output:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-2kszfvc6cm
    Host information: 
        Linux ghrun-2kszfvc6cm 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-16 17:17:49,195 - DEBUG - ya.test - _save_outputs: Command (pid 1010874) errors:

2025-10-16 17:17:49,195 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a
2025-10-16 17:17:56,775 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0016e2/ydb/apps/ydbd/ydbd
2025-10-16 17:17:56,776 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/b9t0/0016e2/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:21181', '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: 10249\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-16 17:17:56,778 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-16 17:17:56,779 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-16 17:17:56,779 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0016e2/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:21181', '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: 10249\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-16 17:17:56,785 - DEBUG - ya.test - execute: Command pid: 1012040
2025-10-16 17:17:57,997 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) rc: 0
2025-10-16 17:17:57,997 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) elapsed time (sec): 1.210101842880249
2025-10-16 17:17:57,998 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) idrss: 0
2025-10-16 17:17:57,998 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) inblock: 0
2025-10-16 17:17:57,998 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) isrss: 0
2025-10-16 17:17:57,998 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) ixrss: 0
2025-10-16 17:17:57,999 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) majflt: 0
2025-10-16 17:17:57,999 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) maxrss: 601408
2025-10-16 17:17:57,999 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) minflt: 51382
2025-10-16 17:17:58,000 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) msgrcv: 0
2025-10-16 17:17:58,000 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) msgsnd: 0
2025-10-16 17:17:58,001 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) nivcsw: 295
2025-10-16 17:17:58,001 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) nsignals: 0
2025-10-16 17:17:58,001 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) nswap: 0
2025-10-16 17:17:58,001 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) nvcsw: 366
2025-10-16 17:17:58,002 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) oublock: 8
2025-10-16 17:17:58,002 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) stime: 0.244777
2025-10-16 17:17:58,002 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) utime: 0.577693
2025-10-16 17:17:58,003 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) wtime: 1.217
2025-10-16 17:17:58,003 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-16 17:17:58,003 - DEBUG - ya.test - _save_outputs: Command (pid 1012040) errors:

2025-10-16 17:17:58,018 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0016e2/ydb/apps/ydbd/ydbd
2025-10-16 17:17:58,018 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/b9t0/0016e2/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:21181', '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-16 17:17:58,020 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-16 17:17:58,021 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-16 17:17:58,022 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0016e2/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:21181', '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-16 17:17:58,023 - DEBUG - ya.test - execute: Command pid: 1012175
2025-10-16 17:17:59,273 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) rc: 0
2025-10-16 17:17:59,274 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) elapsed time (sec): 1.248471736907959
2025-10-16 17:17:59,274 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) idrss: 0
2025-10-16 17:17:59,274 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) inblock: 0
2025-10-16 17:17:59,275 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) isrss: 0
2025-10-16 17:17:59,275 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) ixrss: 0
2025-10-16 17:17:59,275 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) majflt: 0
2025-10-16 17:17:59,275 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) maxrss: 602196
2025-10-16 17:17:59,276 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) minflt: 51405
2025-10-16 17:17:59,276 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) msgrcv: 0
2025-10-16 17:17:59,277 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) msgsnd: 0
2025-10-16 17:17:59,278 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) nivcsw: 233
2025-10-16 17:17:59,278 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) nsignals: 0
2025-10-16 17:17:59,278 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) nswap: 0
2025-10-16 17:17:59,279 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) nvcsw: 496
2025-10-16 17:17:59,279 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) oublock: 8
2025-10-16 17:17:59,279 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) stime: 0.223909
2025-10-16 17:17:59,280 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) utime: 0.613238
2025-10-16 17:17:59,280 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) wtime: 1.25
2025-10-16 17:17:59,292 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-16 17:17:59,293 - DEBUG - ya.test - _save_outputs: Command (pid 1012175) errors:

2025-10-16 17:17:59,293 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-16 17:17:59,457 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-16 17:17:59,538 - 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-16 17:17:59,542 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:17:59,544 - 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-16 17:17:59,565 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-16 17:17:59,566 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 8de3e442-d1bc-4852-9c9e-73b286d769c6, localhost:21181): creating call state
2025-10-16 17:17:59,567 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 8de3e442-d1bc-4852-9c9e-73b286d769c6, localhost:21181): request = { database: "/Root" }
2025-10-16 17:17:59,578 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 8de3e442-d1bc-4852-9c9e-73b286d769c6, localhost:21181): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 21181 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-16 17:17:59,584 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:21181, location 1, ssl: False>]>
2025-10-16 17:17:59,585 - 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-16 17:17:59,625 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21181
2025-10-16 17:17:59,626 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-10-16 17:17:59,628 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 689e89fe-155a-4fe5-9f3f-7d5ef6386799, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:17:59,629 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 689e89fe-155a-4fe5-9f3f-7d5ef6386799, ghrun-2kszfvc6cm.auto.internal:21181): request = {  }
2025-10-16 17:17:59,639 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 689e89fe-155a-4fe5-9f3f-7d5ef6386799, ghrun-2kszfvc6cm.auto.internal:21181): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" node_id: 1 }
2025-10-16 17:17:59,640 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, a512dda9-20cf-475e-8b7e-5e0d548c7083, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:17:59,641 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, a512dda9-20cf-475e-8b7e-5e0d548c7083, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" }
2025-10-16 17:17:59,648 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, a512dda9-20cf-475e-8b7e-5e0d548c7083, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:59,662 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:17:59,664 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 59e921ba-f9f1-4952-9477-63001893e248, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:17:59,665 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 59e921ba-f9f1-4952-9477-63001893e248, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:17:59,666 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 59e921ba-f9f1-4952-9477-63001893e248, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:03,293 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:03,294 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-10-16 17:18:03,294 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:03,295 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f4b596af-9b04-4f0e-bc6b-6ab60bcb5c57, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:03,296 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f4b596af-9b04-4f0e-bc6b-6ab60bcb5c57, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:03,302 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f4b596af-9b04-4f0e-bc6b-6ab60bcb5c57, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:04,507 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:04,508 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:04,509 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a9641bbd-bec8-471d-9545-846f6b2aa46c, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:04,510 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a9641bbd-bec8-471d-9545-846f6b2aa46c, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:04,517 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a9641bbd-bec8-471d-9545-846f6b2aa46c, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:05,665 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:05,666 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:05,667 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e97a8c77-e0d7-45b9-8f1d-4ba9840f6229, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:05,668 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e97a8c77-e0d7-45b9-8f1d-4ba9840f6229, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:05,669 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e97a8c77-e0d7-45b9-8f1d-4ba9840f6229, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:06,610 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:06,612 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:06,613 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 054994d4-516e-470b-a6dc-dbfcb7d437ac, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:06,614 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 054994d4-516e-470b-a6dc-dbfcb7d437ac, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:06,618 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 054994d4-516e-470b-a6dc-dbfcb7d437ac, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:08,399 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:08,400 - 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-16 17:18:08,400 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:08,401 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3e4825af-0548-49bf-ae0c-8d23cd3e562f, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:08,402 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3e4825af-0548-49bf-ae0c-8d23cd3e562f, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:08,403 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3e4825af-0548-49bf-ae0c-8d23cd3e562f, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:09,447 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:09,447 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:09,448 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 500a3524-a453-4daf-9389-9cfb9c9523c9, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:09,449 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 500a3524-a453-4daf-9389-9cfb9c9523c9, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:09,455 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 500a3524-a453-4daf-9389-9cfb9c9523c9, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:09,749 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:09,750 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:09,750 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e4452951-52ec-4daa-9d21-22bda186fe9c, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:09,751 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e4452951-52ec-4daa-9d21-22bda186fe9c, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:09,753 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e4452951-52ec-4daa-9d21-22bda186fe9c, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:10,110 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:10,110 - 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-16 17:18:10,111 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:10,112 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f9d21f2d-136b-4711-82e6-275d09dcd6b3, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:10,118 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f9d21f2d-136b-4711-82e6-275d09dcd6b3, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:10,119 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f9d21f2d-136b-4711-82e6-275d09dcd6b3, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:11,064 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:11,065 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:11,066 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d00aec97-4234-438f-bc2a-c002f7aae6eb, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:11,067 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d00aec97-4234-438f-bc2a-c002f7aae6eb, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:11,068 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d00aec97-4234-438f-bc2a-c002f7aae6eb, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:11,295 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:11,296 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:11,296 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 89fe7e49-af29-485a-a45d-1b65444bcec5, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:11,297 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 89fe7e49-af29-485a-a45d-1b65444bcec5, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:11,300 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 89fe7e49-af29-485a-a45d-1b65444bcec5, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:11,914 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:11,914 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 191}}, blobs: {'__DEFAULT': {'Portions': 191, 'BlobSize': 4855632, 'BlobCount': 384}}
2025-10-16 17:18:11,915 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:11,916 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eefae8b1-431a-4914-ac4e-2a9da64c7472, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:11,917 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eefae8b1-431a-4914-ac4e-2a9da64c7472, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:11,919 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eefae8b1-431a-4914-ac4e-2a9da64c7472, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:13,042 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:13,043 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:13,044 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4970c634-1799-4442-b7a1-d6fb2fc714b6, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:13,045 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4970c634-1799-4442-b7a1-d6fb2fc714b6, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:13,046 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4970c634-1799-4442-b7a1-d6fb2fc714b6, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:13,553 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:13,553 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:13,554 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c3cf8198-daf3-46a3-84bc-07875febaa9e, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:13,555 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c3cf8198-daf3-46a3-84bc-07875febaa9e, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:13,564 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c3cf8198-daf3-46a3-84bc-07875febaa9e, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:14,432 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:14,433 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 255}}, blobs: {'__DEFAULT': {'Portions': 255, 'BlobSize': 6474064, 'BlobCount': 512}}
2025-10-16 17:18:14,434 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:14,435 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a45071c5-4e07-46b0-a7ba-3d61e06bdb0d, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:14,436 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a45071c5-4e07-46b0-a7ba-3d61e06bdb0d, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:14,440 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a45071c5-4e07-46b0-a7ba-3d61e06bdb0d, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:15,534 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:15,535 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:15,536 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 87dcd922-66d2-429e-a428-e1715892d0fa, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:15,537 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 87dcd922-66d2-429e-a428-e1715892d0fa, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:15,539 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 87dcd922-66d2-429e-a428-e1715892d0fa, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:15,816 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:15,817 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:15,817 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 85ff3d15-1ce3-497f-9c7a-36f3f2b0c06a, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:15,818 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 85ff3d15-1ce3-497f-9c7a-36f3f2b0c06a, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:15,821 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 85ff3d15-1ce3-497f-9c7a-36f3f2b0c06a, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:16,511 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:16,512 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 316}}, blobs: {'__DEFAULT': {'Portions': 316, 'BlobSize': 8092480, 'BlobCount': 636}}
2025-10-16 17:18:16,513 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:16,513 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 386e1c9a-a4ed-48fe-b129-4ac9f6252d9c, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:16,516 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 386e1c9a-a4ed-48fe-b129-4ac9f6252d9c, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:16,521 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 386e1c9a-a4ed-48fe-b129-4ac9f6252d9c, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:17,718 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:17,719 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:17,720 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9ef21936-cfbd-48f0-a2cf-e1c16cecca2b, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:17,721 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9ef21936-cfbd-48f0-a2cf-e1c16cecca2b, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:17,723 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9ef21936-cfbd-48f0-a2cf-e1c16cecca2b, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:18,322 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:18,323 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:18,324 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 922ab2e3-e326-4fd6-ba1c-c828e614beb4, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:18,325 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 922ab2e3-e326-4fd6-ba1c-c828e614beb4, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:18,327 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 922ab2e3-e326-4fd6-ba1c-c828e614beb4, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:18,987 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:18,988 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 372}}, blobs: {'__DEFAULT': {'Portions': 372, 'BlobSize': 9710512, 'BlobCount': 752}}
2025-10-16 17:18:18,988 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:18,989 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a341b5d0-ea6b-44d8-95dc-fce7413a1a45, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:18,990 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a341b5d0-ea6b-44d8-95dc-fce7413a1a45, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:18,993 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a341b5d0-ea6b-44d8-95dc-fce7413a1a45, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:20,393 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:20,394 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:20,395 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9a876b22-71bd-4e44-8a37-3c395e26c361, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:20,395 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9a876b22-71bd-4e44-8a37-3c395e26c361, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:20,397 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9a876b22-71bd-4e44-8a37-3c395e26c361, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:20,733 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:20,734 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:20,734 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2f1230a5-5288-47a5-bc52-541a4b9fb7c0, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:20,735 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2f1230a5-5288-47a5-bc52-541a4b9fb7c0, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:20,737 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2f1230a5-5288-47a5-bc52-541a4b9fb7c0, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:21,466 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:21,467 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 718823, 'Portions': 418}}, blobs: {'__DEFAULT': {'Portions': 406, 'BlobSize': 11326936, 'BlobCount': 832}}
2025-10-16 17:18:21,468 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:21,469 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f61a43ce-c6d4-4a0c-b0d9-8385a60f1d36, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:21,470 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f61a43ce-c6d4-4a0c-b0d9-8385a60f1d36, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:21,471 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f61a43ce-c6d4-4a0c-b0d9-8385a60f1d36, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:22,739 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:22,740 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:22,741 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 615b6062-00e7-420e-a068-ce1d8a6207ef, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:22,742 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 615b6062-00e7-420e-a068-ce1d8a6207ef, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:22,744 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 615b6062-00e7-420e-a068-ce1d8a6207ef, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:23,098 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:23,099 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:23,100 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1624f0da-7443-455f-9a68-1cd70c972c61, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:23,101 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1624f0da-7443-455f-9a68-1cd70c972c61, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:23,103 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1624f0da-7443-455f-9a68-1cd70c972c61, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:23,894 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:23,895 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 822025, 'Portions': 460}}, blobs: {'__DEFAULT': {'Portions': 446, 'BlobSize': 12943312, 'BlobCount': 920}}
2025-10-16 17:18:23,895 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:23,896 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bd7c12af-8f83-4014-9981-f881495ceac2, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:23,897 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bd7c12af-8f83-4014-9981-f881495ceac2, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:23,913 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bd7c12af-8f83-4014-9981-f881495ceac2, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:25,330 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:25,330 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:25,331 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 00615e39-6c03-4378-b8bc-257a04c45e18, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:25,332 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 00615e39-6c03-4378-b8bc-257a04c45e18, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:25,364 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 00615e39-6c03-4378-b8bc-257a04c45e18, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:25,697 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:25,698 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:25,699 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2bf2e985-ccac-456a-93ea-e7990db729c9, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:25,700 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2bf2e985-ccac-456a-93ea-e7990db729c9, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:25,701 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2bf2e985-ccac-456a-93ea-e7990db729c9, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:26,409 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:26,410 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 503}}, blobs: {'__DEFAULT': {'Portions': 503, 'BlobSize': 14561048, 'BlobCount': 1036}}
2025-10-16 17:18:26,410 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:26,411 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d703a7ce-c66e-41cb-9840-d874fbd7872e, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:26,413 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d703a7ce-c66e-41cb-9840-d874fbd7872e, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:26,415 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d703a7ce-c66e-41cb-9840-d874fbd7872e, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:27,774 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:27,774 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:27,775 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 74698913-c9ea-49a1-b5aa-dff92e4f9780, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:27,776 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 74698913-c9ea-49a1-b5aa-dff92e4f9780, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:27,777 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 74698913-c9ea-49a1-b5aa-dff92e4f9780, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:28,112 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:28,113 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:28,114 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e86ed148-886f-429e-a3b1-8fa7d30b4eac, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:28,114 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e86ed148-886f-429e-a3b1-8fa7d30b4eac, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:28,116 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e86ed148-886f-429e-a3b1-8fa7d30b4eac, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:28,703 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:28,704 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1013924, 'Portions': 552}}, blobs: {'__DEFAULT': {'Portions': 538, 'BlobSize': 16855408, 'BlobCount': 1120}}
2025-10-16 17:18:28,704 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:28,705 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f0efba8f-070f-4e38-8c01-d9d23ea2078a, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:28,706 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f0efba8f-070f-4e38-8c01-d9d23ea2078a, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:28,712 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f0efba8f-070f-4e38-8c01-d9d23ea2078a, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:29,818 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:29,819 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:29,820 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a0b8aad4-4017-4a1f-b5a2-98d16444291f, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:29,821 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a0b8aad4-4017-4a1f-b5a2-98d16444291f, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:29,822 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a0b8aad4-4017-4a1f-b5a2-98d16444291f, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:30,238 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:30,239 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:30,239 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ba21eaf4-470c-4c85-a976-dcfc80f5b928, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:30,240 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ba21eaf4-470c-4c85-a976-dcfc80f5b928, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:30,241 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ba21eaf4-470c-4c85-a976-dcfc80f5b928, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:30,586 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:30,587 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:30,588 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 61bb8c0e-f440-4b2a-9954-510efeaa9e51, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:30,588 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 61bb8c0e-f440-4b2a-9954-510efeaa9e51, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:30,590 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 61bb8c0e-f440-4b2a-9954-510efeaa9e51, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:35,176 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:45,229 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:45,230 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d4a4deea-17e9-4fbe-8c2b-44e219eb6bba, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:45,231 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d4a4deea-17e9-4fbe-8c2b-44e219eb6bba, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:45,233 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d4a4deea-17e9-4fbe-8c2b-44e219eb6bba, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:49,325 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:49,378 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8073584)
2025-10-16 17:18:49,379 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:18:49,380 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 26b90508-9dbd-4a04-a236-dcb789de0dd0, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:18:49,381 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 26b90508-9dbd-4a04-a236-dcb789de0dd0, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:18:49,382 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 26b90508-9dbd-4a04-a236-dcb789de0dd0, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:53,734 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:03,771 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:03,772 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 29920d98-42fd-4f14-8122-303ccd2a6efd, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:19:03,773 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 29920d98-42fd-4f14-8122-303ccd2a6efd, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:19:03,774 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 29920d98-42fd-4f14-8122-303ccd2a6efd, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:08,865 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:18,886 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:18,887 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 01c693fa-696d-4393-b591-cd93fb3d9330, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:19:18,888 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 01c693fa-696d-4393-b591-cd93fb3d9330, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:19:18,890 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 01c693fa-696d-4393-b591-cd93fb3d9330, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:20,598 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:25,893 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:19:25,894 - 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-16 17:19:25,925 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-16 17:19:25,926 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 29f3640d-489c-4223-b4e9-fefee03ceb2e, localhost:21181): creating call state
2025-10-16 17:19:25,928 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 29f3640d-489c-4223-b4e9-fefee03ceb2e, localhost:21181): request = { database: "/Root" }
2025-10-16 17:19:25,938 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 29f3640d-489c-4223-b4e9-fefee03ceb2e, localhost:21181): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 21181 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-16 17:19:25,939 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:21181, location 1, ssl: False>]>
2025-10-16 17:19:25,940 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21181
2025-10-16 17:19:30,617 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:30,618 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d6e090f-3a42-4f98-8176-94f54dd34671, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:19:30,619 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d6e090f-3a42-4f98-8176-94f54dd34671, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:19:30,622 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d6e090f-3a42-4f98-8176-94f54dd34671, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:32,369 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:32,378 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8020448)
2025-10-16 17:19:32,378 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:32,379 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a731426e-21d5-402f-bac8-759d4cd55b56, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:19:32,380 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a731426e-21d5-402f-bac8-759d4cd55b56, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" 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-16 17:19:32,382 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a731426e-21d5-402f-bac8-759d4cd55b56, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:32,797 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:32,802 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_18_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 18])
2025-10-16 17:19:32,803 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:32,804 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 09b6a0cf-3d92-466f-b15b-f52844671d95, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:19:32,805 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 09b6a0cf-3d92-466f-b15b-f52844671d95, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_18_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 18)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:32,808 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 09b6a0cf-3d92-466f-b15b-f52844671d95, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:38,053 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:38,054 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_18_compression created
2025-10-16 17:19:38,054 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:38,055 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 500bfc4f-f981-4939-8cc1-2e9a081893ed, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:19:38,056 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 500bfc4f-f981-4939-8cc1-2e9a081893ed, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_18_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:38,059 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 500bfc4f-f981-4939-8cc1-2e9a081893ed, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:39,256 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:39,257 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:39,258 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f89e1a77-b868-48ac-ba9c-9ab1127094a7, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:19:39,259 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f89e1a77-b868-48ac-ba9c-9ab1127094a7, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:39,261 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f89e1a77-b868-48ac-ba9c-9ab1127094a7, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:47,034 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:47,035 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:47,036 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4ea6b524-b6ce-41d1-bfff-bd69d571ae1c, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:19:47,037 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4ea6b524-b6ce-41d1-bfff-bd69d571ae1c, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:47,039 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4ea6b524-b6ce-41d1-bfff-bd69d571ae1c, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:48,182 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:48,183 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:48,184 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 30a7861a-ecfc-403a-b047-8b205071b53b, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:19:48,185 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 30a7861a-ecfc-403a-b047-8b205071b53b, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:48,187 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 30a7861a-ecfc-403a-b047-8b205071b53b, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:50,605 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:50,606 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-10-16 17:19:50,607 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:50,608 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5c851f4f-d3e4-41c8-85b2-066dc91c01f1, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:19:50,609 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5c851f4f-d3e4-41c8-85b2-066dc91c01f1, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:50,611 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5c851f4f-d3e4-41c8-85b2-066dc91c01f1, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:56,217 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:56,218 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:56,218 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a64f9c63-e636-458b-84a4-db1a21918b11, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:19:56,219 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a64f9c63-e636-458b-84a4-db1a21918b11, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:56,222 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a64f9c63-e636-458b-84a4-db1a21918b11, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:56,591 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:56,592 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:56,593 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6a48686a-8ee7-4150-bd51-74f572357e85, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:19:56,594 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6a48686a-8ee7-4150-bd51-74f572357e85, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:56,596 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6a48686a-8ee7-4150-bd51-74f572357e85, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:56,982 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:56,983 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-10-16 17:19:56,984 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:19:56,985 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c3121263-63d6-4ce7-bda2-42846a6c2a76, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:19:56,986 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c3121263-63d6-4ce7-bda2-42846a6c2a76, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:56,988 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c3121263-63d6-4ce7-bda2-42846a6c2a76, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:02,342 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:02,343 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:02,343 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f9a85fc6-e6fc-4fdc-a012-5461a76a894e, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:02,344 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f9a85fc6-e6fc-4fdc-a012-5461a76a894e, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:02,346 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f9a85fc6-e6fc-4fdc-a012-5461a76a894e, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:02,845 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:02,846 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:02,846 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 73392893-ce44-4ea0-ad72-14114ca1ad4f, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:02,847 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 73392893-ce44-4ea0-ad72-14114ca1ad4f, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:02,853 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 73392893-ce44-4ea0-ad72-14114ca1ad4f, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:03,350 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:03,350 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 174}}, blobs: {'__DEFAULT': {'Portions': 174, 'BlobSize': 959944, 'BlobCount': 384}}
2025-10-16 17:20:03,351 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:03,351 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5eb64800-c3bc-47ef-b62c-15aea773ded9, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:03,352 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5eb64800-c3bc-47ef-b62c-15aea773ded9, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:03,353 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5eb64800-c3bc-47ef-b62c-15aea773ded9, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:09,764 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:09,766 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:09,767 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2431d8e5-5e9f-45b7-af24-dc3472dc0114, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:09,767 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2431d8e5-5e9f-45b7-af24-dc3472dc0114, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:09,770 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2431d8e5-5e9f-45b7-af24-dc3472dc0114, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:10,108 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:10,108 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:10,109 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c5c70932-7760-450e-ad12-009750e62edc, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:10,109 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c5c70932-7760-450e-ad12-009750e62edc, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:10,111 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c5c70932-7760-450e-ad12-009750e62edc, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:10,670 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:10,671 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 222}}, blobs: {'__DEFAULT': {'Portions': 222, 'BlobSize': 1278080, 'BlobCount': 496}}
2025-10-16 17:20:10,672 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:10,672 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d88ed7ad-95d7-444b-8c8f-692a74036238, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:10,673 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d88ed7ad-95d7-444b-8c8f-692a74036238, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:10,675 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d88ed7ad-95d7-444b-8c8f-692a74036238, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:17,051 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:17,052 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:17,052 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8360e361-a3a1-429f-a62a-d57346a31d3d, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:17,053 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8360e361-a3a1-429f-a62a-d57346a31d3d, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:17,055 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8360e361-a3a1-429f-a62a-d57346a31d3d, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:17,538 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:17,539 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:17,539 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c3a0c01d-c090-434a-b296-1bfaad71f563, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:17,541 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c3a0c01d-c090-434a-b296-1bfaad71f563, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:17,542 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c3a0c01d-c090-434a-b296-1bfaad71f563, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:18,241 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:18,242 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 265}}, blobs: {'__DEFAULT': {'Portions': 265, 'BlobSize': 1604544, 'BlobCount': 596}}
2025-10-16 17:20:18,243 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:18,243 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0e23d280-006d-4d4f-952e-e8de1fb64ba2, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:18,244 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0e23d280-006d-4d4f-952e-e8de1fb64ba2, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:18,249 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0e23d280-006d-4d4f-952e-e8de1fb64ba2, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:23,938 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:23,939 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:23,940 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 812696f2-27d7-4657-a1e4-058d5e207607, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:23,941 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 812696f2-27d7-4657-a1e4-058d5e207607, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:23,944 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 812696f2-27d7-4657-a1e4-058d5e207607, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:24,306 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:24,307 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:24,307 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 59b56955-57d9-474e-adce-25db31cb8df0, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:24,308 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 59b56955-57d9-474e-adce-25db31cb8df0, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:24,310 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 59b56955-57d9-474e-adce-25db31cb8df0, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:24,882 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:24,883 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 285}}, blobs: {'__DEFAULT': {'Portions': 285, 'BlobSize': 1934152, 'BlobCount': 658}}
2025-10-16 17:20:24,884 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:24,884 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 426485ec-e70f-4d9b-a73c-d2afc8aa37d3, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:24,885 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 426485ec-e70f-4d9b-a73c-d2afc8aa37d3, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:24,887 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 426485ec-e70f-4d9b-a73c-d2afc8aa37d3, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:30,510 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:30,511 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:30,512 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0b3c7802-1a74-433e-8b09-4f0b08b5a42d, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:30,513 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0b3c7802-1a74-433e-8b09-4f0b08b5a42d, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:30,520 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0b3c7802-1a74-433e-8b09-4f0b08b5a42d, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:30,990 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:30,991 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:30,991 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2b216e70-8920-4d4f-b1bd-0da0b63cebec, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:30,992 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2b216e70-8920-4d4f-b1bd-0da0b63cebec, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:30,994 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2b216e70-8920-4d4f-b1bd-0da0b63cebec, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:31,637 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:31,637 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 319}}, blobs: {'__DEFAULT': {'Portions': 319, 'BlobSize': 2252264, 'BlobCount': 738}}
2025-10-16 17:20:31,638 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:31,639 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5133a632-d836-4763-ba32-e453ca44e1fd, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:31,640 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5133a632-d836-4763-ba32-e453ca44e1fd, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:31,641 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5133a632-d836-4763-ba32-e453ca44e1fd, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:37,394 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:37,395 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:37,395 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5fc24651-6e40-4036-9e73-767588901403, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:37,397 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5fc24651-6e40-4036-9e73-767588901403, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:37,399 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5fc24651-6e40-4036-9e73-767588901403, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:37,715 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:37,716 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:37,717 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7a4d910a-9c18-4a97-b05f-aae1ab5c1d5b, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:37,718 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7a4d910a-9c18-4a97-b05f-aae1ab5c1d5b, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:37,734 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7a4d910a-9c18-4a97-b05f-aae1ab5c1d5b, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:38,569 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:38,570 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 365}}, blobs: {'__DEFAULT': {'Portions': 365, 'BlobSize': 2570408, 'BlobCount': 836}}
2025-10-16 17:20:38,570 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:38,571 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ccc9de91-920e-4fe7-933c-d20cfc2a194b, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:38,577 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ccc9de91-920e-4fe7-933c-d20cfc2a194b, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:38,579 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ccc9de91-920e-4fe7-933c-d20cfc2a194b, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:44,282 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:44,283 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:44,283 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ec64f867-f2fe-4e36-836f-b1d7e758db22, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:44,284 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ec64f867-f2fe-4e36-836f-b1d7e758db22, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:44,285 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ec64f867-f2fe-4e36-836f-b1d7e758db22, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:44,769 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:44,770 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:44,771 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3aa2d578-ba2b-4ada-ad8c-66b4f1954f93, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:44,776 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3aa2d578-ba2b-4ada-ad8c-66b4f1954f93, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:44,779 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3aa2d578-ba2b-4ada-ad8c-66b4f1954f93, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:46,142 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:46,143 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 361}}, blobs: {'__DEFAULT': {'Portions': 361, 'BlobSize': 2883080, 'BlobCount': 850}}
2025-10-16 17:20:46,144 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:46,144 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3b899510-2f80-4dfa-980a-1960e02611ea, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:46,145 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3b899510-2f80-4dfa-980a-1960e02611ea, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:46,160 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3b899510-2f80-4dfa-980a-1960e02611ea, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:51,301 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:51,302 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:51,303 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c0b8d7c9-40b3-49cc-b1de-3ffc5e804aba, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:51,304 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c0b8d7c9-40b3-49cc-b1de-3ffc5e804aba, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:51,306 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c0b8d7c9-40b3-49cc-b1de-3ffc5e804aba, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:51,847 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:51,848 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:51,850 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3d0fc1cf-927f-4905-8422-3d9dee63f384, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:51,851 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3d0fc1cf-927f-4905-8422-3d9dee63f384, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:51,859 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3d0fc1cf-927f-4905-8422-3d9dee63f384, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:53,061 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:53,062 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 425}}, blobs: {'__DEFAULT': {'Portions': 425, 'BlobSize': 3202648, 'BlobCount': 978}}
2025-10-16 17:20:53,063 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:53,064 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dc72bbb3-d150-44ea-b97c-4f6087f16b40, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:53,069 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dc72bbb3-d150-44ea-b97c-4f6087f16b40, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_18_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:53,071 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dc72bbb3-d150-44ea-b97c-4f6087f16b40, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:54,813 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:54,814 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:54,815 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d143fd80-ddf5-4d3f-bb22-bfb22c2b70ca, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:54,816 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d143fd80-ddf5-4d3f-bb22-bfb22c2b70ca, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:54,818 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d143fd80-ddf5-4d3f-bb22-bfb22c2b70ca, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:55,230 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:55,231 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:55,232 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a8e4c206-784e-4dcc-9571-4d519a5c14b9, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:55,233 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a8e4c206-784e-4dcc-9571-4d519a5c14b9, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:55,238 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a8e4c206-784e-4dcc-9571-4d519a5c14b9, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:55,657 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:55,658 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:20:55,658 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1979f163-4e84-4409-a2a9-722c06eb081a, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:20:55,659 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1979f163-4e84-4409-a2a9-722c06eb081a, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:55,661 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1979f163-4e84-4409-a2a9-722c06eb081a, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:00,290 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:00,616 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:21:00,617 - 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-16 17:21:00,637 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-16 17:21:00,638 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, b8d531ea-a77b-4efc-bbb6-1a0714a9a8bc, localhost:21181): creating call state
2025-10-16 17:21:00,639 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, b8d531ea-a77b-4efc-bbb6-1a0714a9a8bc, localhost:21181): request = { database: "/Root" }
2025-10-16 17:21:00,644 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, b8d531ea-a77b-4efc-bbb6-1a0714a9a8bc, localhost:21181): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 21181 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-16 17:21:00,645 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:21181, location 1, ssl: False>]>
2025-10-16 17:21:00,646 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21181
2025-10-16 17:21:10,325 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:10,325 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 24aff480-2726-469d-9c90-f3aebe51f38a, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:21:10,326 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 24aff480-2726-469d-9c90-f3aebe51f38a, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:10,328 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 24aff480-2726-469d-9c90-f3aebe51f38a, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:13,572 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:13,597 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_18_compression`, volumes `value` (8000000, 1572632)
2025-10-16 17:21:13,597 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:13,598 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9610636f-4c71-4ca5-9593-bd8369f329fa, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:21:13,599 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9610636f-4c71-4ca5-9593-bd8369f329fa, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:13,602 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9610636f-4c71-4ca5-9593-bd8369f329fa, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:13,959 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:13,960 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:13,961 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7f0f99ca-c563-4ee6-bd27-36a2438f0f31, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:21:13,961 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7f0f99ca-c563-4ee6-bd27-36a2438f0f31, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:13,963 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7f0f99ca-c563-4ee6-bd27-36a2438f0f31, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:17,120 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:27,184 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:27,185 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b2046fd3-b7f9-49bf-839a-af672de3c0f9, ghrun-2kszfvc6cm.auto.internal:21181): creating call state
2025-10-16 17:21:27,186 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b2046fd3-b7f9-49bf-839a-af672de3c0f9, ghrun-2kszfvc6cm.auto.internal:21181): request = { session_id: "ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:27,187 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b2046fd3-b7f9-49bf-839a-af672de3c0f9, ghrun-2kszfvc6cm.auto.internal:21181): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:30,105 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTU3ZDRhN2EtYmVkYWY3ZS03NjQyNTE0Zi1lNDg5OGZhNA==
2025-10-16 17:21:30,132 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_18_compression`, volumes `value` (8000000, 1572632)
2025-10-16 17:21:30,133 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_18_compression` 8073584 / 1572632: 5.133803712502353
2025-10-16 17:21:30,135 - INFO - ya.test - pytest_runtest_teardown: Test teardown
