2025-10-16 17:18:52,249 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:18:52,249 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_2_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 2]
2025-10-16 17:18:52,249 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:18:52,250 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-16 17:18:52,252 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-16 17:18:52,254 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-16 17:18:52,254 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0016b1/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 17:18:52,268 - DEBUG - ya.test - execute: Command pid: 1016302
2025-10-16 17:18:53,360 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) rc: 0
2025-10-16 17:18:53,361 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) elapsed time (sec): 1.0887393951416016
2025-10-16 17:18:53,361 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) idrss: 0
2025-10-16 17:18:53,361 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) inblock: 0
2025-10-16 17:18:53,362 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) isrss: 0
2025-10-16 17:18:53,362 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) ixrss: 0
2025-10-16 17:18:53,362 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) majflt: 0
2025-10-16 17:18:53,363 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) maxrss: 476084
2025-10-16 17:18:53,363 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) minflt: 45183
2025-10-16 17:18:53,363 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) msgrcv: 0
2025-10-16 17:18:53,364 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) msgsnd: 0
2025-10-16 17:18:53,364 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) nivcsw: 255
2025-10-16 17:18:53,365 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) nsignals: 0
2025-10-16 17:18:53,365 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) nswap: 0
2025-10-16 17:18:53,365 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) nvcsw: 14
2025-10-16 17:18:53,365 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) oublock: 8
2025-10-16 17:18:53,366 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) stime: 0.17968299999999998
2025-10-16 17:18:53,366 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) utime: 0.561277
2025-10-16 17:18:53,367 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) wtime: 1.105
2025-10-16 17:18:53,367 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) 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:18:53,368 - DEBUG - ya.test - _save_outputs: Command (pid 1016302) errors:

2025-10-16 17:18:53,368 - 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:18:53,516 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0016b1/ydb/apps/ydbd/ydbd
2025-10-16 17:18:53,518 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17572
2025-10-16 17:18:53,520 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/b9t0/0016b1/r3tmp/kikimr_cluster_0k2f5jxa
2025-10-16 17:18:53,565 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0016b1/ydb/apps/ydbd/ydbd
2025-10-16 17:18:53,566 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12444
2025-10-16 17:18:53,566 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2527
2025-10-16 17:18:53,567 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29511
2025-10-16 17:18:53,568 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21486
2025-10-16 17:18:53,569 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/b9t0/0016b1/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs"
2025-10-16 17:18:53,569 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/b9t0/0016b1/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/0016b1/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_6pkjz726.log --grpc-port=12444 --mon-port=2527 --ic-port=17572
2025-10-16 17:18:53,570 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:12444/1, disk_size 68719476736
2025-10-16 17:18:53,570 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/b9t0/0016b1/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs"
2025-10-16 17:18:53,570 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/b9t0/0016b1/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/0016b1/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_6pkjz726.log --grpc-port=12444 --mon-port=2527 --ic-port=17572
2025-10-16 17:18:53,572 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/b9t0/0016b1/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/b9t0/0016b1/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/b9t0/0016b1/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_6pkjz726.log', '--grpc-port=12444', '--mon-port=2527', '--ic-port=17572')' in '/home/runner/.ya/build/build_root/b9t0/0016b1/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1' (no wait)
2025-10-16 17:18:53,575 - DEBUG - ya.test - execute: Command pid: 1016450
2025-10-16 17:18:53,576 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:12444/1
2025-10-16 17:18:53,577 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-16 17:18:53,578 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-16 17:18:53,579 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0016b1/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 17:18:53,604 - DEBUG - ya.test - execute: Command pid: 1016451
2025-10-16 17:18:54,633 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) rc: 0
2025-10-16 17:18:54,634 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) elapsed time (sec): 1.027752161026001
2025-10-16 17:18:54,634 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) idrss: 0
2025-10-16 17:18:54,634 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) inblock: 0
2025-10-16 17:18:54,635 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) isrss: 0
2025-10-16 17:18:54,635 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) ixrss: 0
2025-10-16 17:18:54,635 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) majflt: 0
2025-10-16 17:18:54,636 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) maxrss: 484740
2025-10-16 17:18:54,636 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) minflt: 45127
2025-10-16 17:18:54,636 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) msgrcv: 0
2025-10-16 17:18:54,637 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) msgsnd: 0
2025-10-16 17:18:54,637 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) nivcsw: 192
2025-10-16 17:18:54,637 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) nsignals: 0
2025-10-16 17:18:54,638 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) nswap: 0
2025-10-16 17:18:54,638 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) nvcsw: 14
2025-10-16 17:18:54,652 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) oublock: 8
2025-10-16 17:18:54,652 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) stime: 0.22875399999999999
2025-10-16 17:18:54,652 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) utime: 0.515988
2025-10-16 17:18:54,653 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) wtime: 1.053
2025-10-16 17:18:54,653 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) 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:18:54,654 - DEBUG - ya.test - _save_outputs: Command (pid 1016451) errors:

2025-10-16 17:18:54,654 - 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:19:00,475 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0016b1/ydb/apps/ydbd/ydbd
2025-10-16 17:19:00,476 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/b9t0/0016b1/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:12444', '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: 17572\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-16 17:19:00,477 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-16 17:19:00,478 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-16 17:19:00,478 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0016b1/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:12444', '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: 17572\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-16 17:19:00,484 - DEBUG - ya.test - execute: Command pid: 1017181
2025-10-16 17:19:01,869 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) rc: 0
2025-10-16 17:19:01,869 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) elapsed time (sec): 1.3838191032409668
2025-10-16 17:19:01,870 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) idrss: 0
2025-10-16 17:19:01,870 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) inblock: 0
2025-10-16 17:19:01,870 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) isrss: 0
2025-10-16 17:19:01,870 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) ixrss: 0
2025-10-16 17:19:01,871 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) majflt: 0
2025-10-16 17:19:01,871 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) maxrss: 584416
2025-10-16 17:19:01,871 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) minflt: 51515
2025-10-16 17:19:01,872 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) msgrcv: 0
2025-10-16 17:19:01,872 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) msgsnd: 0
2025-10-16 17:19:01,881 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) nivcsw: 264
2025-10-16 17:19:01,882 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) nsignals: 0
2025-10-16 17:19:01,882 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) nswap: 0
2025-10-16 17:19:01,883 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) nvcsw: 689
2025-10-16 17:19:01,883 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) oublock: 8
2025-10-16 17:19:01,883 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) stime: 0.293211
2025-10-16 17:19:01,883 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) utime: 0.589027
2025-10-16 17:19:01,884 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) wtime: 1.39
2025-10-16 17:19:01,884 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-16 17:19:01,884 - DEBUG - ya.test - _save_outputs: Command (pid 1017181) errors:

2025-10-16 17:19:01,886 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0016b1/ydb/apps/ydbd/ydbd
2025-10-16 17:19:01,886 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/b9t0/0016b1/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:12444', '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:19:01,896 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-16 17:19:01,897 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-16 17:19:01,898 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0016b1/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:12444', '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:19:01,899 - DEBUG - ya.test - execute: Command pid: 1017278
2025-10-16 17:19:03,529 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) rc: 0
2025-10-16 17:19:03,529 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) elapsed time (sec): 1.6284596920013428
2025-10-16 17:19:03,530 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) idrss: 0
2025-10-16 17:19:03,530 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) inblock: 0
2025-10-16 17:19:03,530 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) isrss: 0
2025-10-16 17:19:03,530 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) ixrss: 0
2025-10-16 17:19:03,531 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) majflt: 0
2025-10-16 17:19:03,531 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) maxrss: 584800
2025-10-16 17:19:03,531 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) minflt: 51556
2025-10-16 17:19:03,532 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) msgrcv: 0
2025-10-16 17:19:03,532 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) msgsnd: 0
2025-10-16 17:19:03,533 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) nivcsw: 292
2025-10-16 17:19:03,533 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) nsignals: 0
2025-10-16 17:19:03,533 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) nswap: 0
2025-10-16 17:19:03,534 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) nvcsw: 418
2025-10-16 17:19:03,534 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) oublock: 8
2025-10-16 17:19:03,534 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) stime: 0.291871
2025-10-16 17:19:03,534 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) utime: 0.554885
2025-10-16 17:19:03,535 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) wtime: 1.63
2025-10-16 17:19:03,535 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-16 17:19:03,535 - DEBUG - ya.test - _save_outputs: Command (pid 1017278) errors:

2025-10-16 17:19:03,536 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-16 17:19:03,697 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-16 17:19:03,758 - 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:03,766 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:19:03,767 - 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:03,830 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-16 17:19:03,831 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, fe437f8b-c90d-4bd9-90aa-6965aec934a0, localhost:12444): creating call state
2025-10-16 17:19:03,832 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, fe437f8b-c90d-4bd9-90aa-6965aec934a0, localhost:12444): request = { database: "/Root" }
2025-10-16 17:19:03,843 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, fe437f8b-c90d-4bd9-90aa-6965aec934a0, localhost:12444): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 12444 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:03,845 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:12444, location 1, ssl: False>]>
2025-10-16 17:19:03,846 - 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:03,873 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:12444
2025-10-16 17:19:03,874 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-10-16 17:19:03,875 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 3900dcfe-fa66-4479-9eb0-4ded24dd9f96, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:03,875 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 3900dcfe-fa66-4479-9eb0-4ded24dd9f96, ghrun-2kszfvc6cm.auto.internal:12444): request = {  }
2025-10-16 17:19:03,887 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 3900dcfe-fa66-4479-9eb0-4ded24dd9f96, ghrun-2kszfvc6cm.auto.internal:12444): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" node_id: 1 }
2025-10-16 17:19:03,888 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, cc3ae67a-03db-4223-b531-b74bbd0d2c9b, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:03,889 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, cc3ae67a-03db-4223-b531-b74bbd0d2c9b, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" }
2025-10-16 17:19:03,893 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, cc3ae67a-03db-4223-b531-b74bbd0d2c9b, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:03,932 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:03,933 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 762e7587-1a4a-4431-b12b-39a66543d7ce, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:03,934 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 762e7587-1a4a-4431-b12b-39a66543d7ce, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:03,936 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 762e7587-1a4a-4431-b12b-39a66543d7ce, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:08,726 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:08,726 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-10-16 17:19:08,727 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:08,728 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5a7a2952-0b41-4c40-a550-50348c322f52, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:08,728 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5a7a2952-0b41-4c40-a550-50348c322f52, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:08,730 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5a7a2952-0b41-4c40-a550-50348c322f52, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:10,152 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:10,153 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:10,154 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bea4cfd6-133e-4bb5-9196-5088e62d6d19, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:10,154 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bea4cfd6-133e-4bb5-9196-5088e62d6d19, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:10,156 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bea4cfd6-133e-4bb5-9196-5088e62d6d19, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:11,342 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:11,343 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:11,344 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 024c1d3c-50a9-45ba-82aa-15d06cd54583, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:11,345 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 024c1d3c-50a9-45ba-82aa-15d06cd54583, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:11,346 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 024c1d3c-50a9-45ba-82aa-15d06cd54583, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:12,422 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:12,423 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:12,424 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f32aeb1a-a10b-4f60-b63b-9dd3be280ac5, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:12,425 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f32aeb1a-a10b-4f60-b63b-9dd3be280ac5, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:12,426 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f32aeb1a-a10b-4f60-b63b-9dd3be280ac5, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:15,117 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:15,118 - 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:19:15,118 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:15,119 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 09d90a0e-34eb-4808-ab0b-011b2f3e146b, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:15,120 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 09d90a0e-34eb-4808-ab0b-011b2f3e146b, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:15,130 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 09d90a0e-34eb-4808-ab0b-011b2f3e146b, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:16,179 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:16,180 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:16,181 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7f07663a-5d16-4646-b0d2-ca69a16a5fa2, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:16,182 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7f07663a-5d16-4646-b0d2-ca69a16a5fa2, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:16,183 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7f07663a-5d16-4646-b0d2-ca69a16a5fa2, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:16,627 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:16,628 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:16,629 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 321aaf4a-dae0-46c3-89a3-bb4500f61b14, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:16,630 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 321aaf4a-dae0-46c3-89a3-bb4500f61b14, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:16,631 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 321aaf4a-dae0-46c3-89a3-bb4500f61b14, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:17,114 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:17,115 - 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:19:17,116 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:17,117 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb4e13c3-f6cd-47b7-bc30-ef8decf05ce8, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:17,118 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb4e13c3-f6cd-47b7-bc30-ef8decf05ce8, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:17,119 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb4e13c3-f6cd-47b7-bc30-ef8decf05ce8, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:18,428 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:18,429 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:18,430 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f6ef5e44-dab3-4ec8-bb1c-0dc74e348f13, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:18,431 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f6ef5e44-dab3-4ec8-bb1c-0dc74e348f13, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:18,433 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f6ef5e44-dab3-4ec8-bb1c-0dc74e348f13, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:18,918 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:18,919 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:18,920 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bfc237fb-50de-41e1-a5f5-badb6c9bce39, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:18,921 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bfc237fb-50de-41e1-a5f5-badb6c9bce39, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:18,923 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bfc237fb-50de-41e1-a5f5-badb6c9bce39, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:19,561 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:19,562 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 303146, 'Portions': 188}}, blobs: {'__DEFAULT': {'Portions': 185, 'BlobSize': 4857608, 'BlobCount': 384}}
2025-10-16 17:19:19,563 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:19,563 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 060ce6fe-562c-409d-a0f9-37d4d0b7546b, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:19,564 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 060ce6fe-562c-409d-a0f9-37d4d0b7546b, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:19,566 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 060ce6fe-562c-409d-a0f9-37d4d0b7546b, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:20,656 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:20,657 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:20,657 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8ff0d252-80a5-42d8-ad85-8df920708cb0, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:20,658 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8ff0d252-80a5-42d8-ad85-8df920708cb0, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:20,663 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8ff0d252-80a5-42d8-ad85-8df920708cb0, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:21,189 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:21,190 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:21,190 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 077b49a6-de79-4b4f-ad19-c7bae7647124, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:21,191 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 077b49a6-de79-4b4f-ad19-c7bae7647124, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:21,193 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 077b49a6-de79-4b4f-ad19-c7bae7647124, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:21,732 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:21,733 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 245}}, blobs: {'__DEFAULT': {'Portions': 245, 'BlobSize': 6476352, 'BlobCount': 508}}
2025-10-16 17:19:21,733 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:21,734 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c47a5581-3df6-46d3-9b63-de135e486afa, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:21,735 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c47a5581-3df6-46d3-9b63-de135e486afa, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:21,741 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c47a5581-3df6-46d3-9b63-de135e486afa, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:22,934 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:22,935 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:22,936 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 37337545-6fb5-4f32-9662-fe7f034a4936, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:22,937 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 37337545-6fb5-4f32-9662-fe7f034a4936, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:22,939 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 37337545-6fb5-4f32-9662-fe7f034a4936, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:23,287 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:23,288 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:23,289 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 035ba350-2c98-47eb-8721-81cdfce62595, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:23,290 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 035ba350-2c98-47eb-8721-81cdfce62595, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:23,292 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 035ba350-2c98-47eb-8721-81cdfce62595, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:23,745 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:23,746 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 300}}, blobs: {'__DEFAULT': {'Portions': 300, 'BlobSize': 8094728, 'BlobCount': 624}}
2025-10-16 17:19:23,747 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:23,748 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ff48461a-2b03-435f-b3e4-ae1aaf67e8a7, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:23,751 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ff48461a-2b03-435f-b3e4-ae1aaf67e8a7, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:23,753 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ff48461a-2b03-435f-b3e4-ae1aaf67e8a7, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:25,093 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:25,094 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:25,094 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2d57beb0-9907-4f52-8bb6-753bcaab3f1f, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:25,095 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2d57beb0-9907-4f52-8bb6-753bcaab3f1f, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:25,097 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2d57beb0-9907-4f52-8bb6-753bcaab3f1f, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:25,628 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:25,629 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:25,629 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a7a15c49-1df3-4368-9a20-d7675e453fb5, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:25,630 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a7a15c49-1df3-4368-9a20-d7675e453fb5, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:25,634 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a7a15c49-1df3-4368-9a20-d7675e453fb5, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:26,359 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:26,359 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 607692, 'Portions': 361}}, blobs: {'__DEFAULT': {'Portions': 356, 'BlobSize': 9712776, 'BlobCount': 740}}
2025-10-16 17:19:26,360 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:26,361 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 68ad8f5d-a6dd-4e05-8b75-8231f27f6612, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:26,361 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 68ad8f5d-a6dd-4e05-8b75-8231f27f6612, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:26,369 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 68ad8f5d-a6dd-4e05-8b75-8231f27f6612, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:27,562 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:27,563 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:27,563 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 750ebb92-992d-4ede-8a1f-bd2e7f3528d0, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:27,564 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 750ebb92-992d-4ede-8a1f-bd2e7f3528d0, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:27,566 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 750ebb92-992d-4ede-8a1f-bd2e7f3528d0, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:27,887 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:27,888 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:27,889 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b0db7031-2b32-4423-895c-49f15d4ae8d3, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:27,890 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b0db7031-2b32-4423-895c-49f15d4ae8d3, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:27,895 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b0db7031-2b32-4423-895c-49f15d4ae8d3, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:28,503 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:28,504 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 410}}, blobs: {'__DEFAULT': {'Portions': 410, 'BlobSize': 11330528, 'BlobCount': 852}}
2025-10-16 17:19:28,504 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:28,505 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 292e26c7-057d-4d1d-81a9-7061f4084b2a, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:28,506 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 292e26c7-057d-4d1d-81a9-7061f4084b2a, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:28,508 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 292e26c7-057d-4d1d-81a9-7061f4084b2a, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:29,969 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:29,969 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:29,970 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2ff6653a-5af0-42fc-958e-6fbcb3354635, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:29,971 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2ff6653a-5af0-42fc-958e-6fbcb3354635, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:29,973 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2ff6653a-5af0-42fc-958e-6fbcb3354635, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:30,644 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:30,645 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:30,646 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 671e2de5-dbee-4132-bf18-9e5e1ced8e4b, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:30,647 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 671e2de5-dbee-4132-bf18-9e5e1ced8e4b, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:30,663 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 671e2de5-dbee-4132-bf18-9e5e1ced8e4b, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:31,505 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:31,506 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 821654, 'Portions': 464}}, blobs: {'__DEFAULT': {'Portions': 445, 'BlobSize': 13122360, 'BlobCount': 934}}
2025-10-16 17:19:31,507 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:31,507 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4e1fe703-e0fc-4acd-8af4-58557cba0fb9, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:31,509 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4e1fe703-e0fc-4acd-8af4-58557cba0fb9, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:31,511 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4e1fe703-e0fc-4acd-8af4-58557cba0fb9, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:32,794 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:32,795 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:32,795 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ced2af01-0fd9-4dbd-bfb2-487fc6663b8e, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:32,796 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ced2af01-0fd9-4dbd-bfb2-487fc6663b8e, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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,798 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ced2af01-0fd9-4dbd-bfb2-487fc6663b8e, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:33,090 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:33,091 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:33,092 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0e67961e-cf50-4298-9e26-0c1ee28c6929, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:33,093 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0e67961e-cf50-4298-9e26-0c1ee28c6929, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:33,094 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0e67961e-cf50-4298-9e26-0c1ee28c6929, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:33,850 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:33,851 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 488}}, blobs: {'__DEFAULT': {'Portions': 488, 'BlobSize': 14562920, 'BlobCount': 1024}}
2025-10-16 17:19:33,852 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:33,852 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b5a18066-d73c-433c-bf46-6350f56f5bd0, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:33,853 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b5a18066-d73c-433c-bf46-6350f56f5bd0, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:33,855 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b5a18066-d73c-433c-bf46-6350f56f5bd0, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:35,487 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:35,487 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:35,488 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ce8c3dca-42f7-4ea8-8bdc-b358cd9fc6ab, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:35,489 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ce8c3dca-42f7-4ea8-8bdc-b358cd9fc6ab, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:35,490 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ce8c3dca-42f7-4ea8-8bdc-b358cd9fc6ab, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:35,841 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:35,842 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:35,843 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ba4deca1-99fe-4419-8599-d7105b80fe74, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:35,844 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ba4deca1-99fe-4419-8599-d7105b80fe74, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:35,845 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ba4deca1-99fe-4419-8599-d7105b80fe74, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:36,577 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:36,578 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 528}}, blobs: {'__DEFAULT': {'Portions': 528, 'BlobSize': 16178784, 'BlobCount': 1110}}
2025-10-16 17:19:36,578 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:36,579 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b14d54f0-1a35-411f-a862-8cece87a4564, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:36,580 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b14d54f0-1a35-411f-a862-8cece87a4564, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:19:36,586 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b14d54f0-1a35-411f-a862-8cece87a4564, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:37,961 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:37,962 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:37,962 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4b8109d8-2af4-4076-a56c-3e6b893d0e88, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:37,963 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4b8109d8-2af4-4076-a56c-3e6b893d0e88, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:37,964 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4b8109d8-2af4-4076-a56c-3e6b893d0e88, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:38,374 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:38,375 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:38,375 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 464145ca-12ee-4d39-b1c5-ee4b02b030d6, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:38,376 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 464145ca-12ee-4d39-b1c5-ee4b02b030d6, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:38,378 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 464145ca-12ee-4d39-b1c5-ee4b02b030d6, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:38,844 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:38,845 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:38,846 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb9ff042-e174-4ff6-8f86-b15d51b63b3a, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:38,847 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb9ff042-e174-4ff6-8f86-b15d51b63b3a, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:38,850 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb9ff042-e174-4ff6-8f86-b15d51b63b3a, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:44,521 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:54,583 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:54,584 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 23731740-5928-4ac3-9709-8627e1f949a3, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:54,585 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 23731740-5928-4ac3-9709-8627e1f949a3, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:54,586 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 23731740-5928-4ac3-9709-8627e1f949a3, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:58,608 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:58,639 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8076032)
2025-10-16 17:19:58,640 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:19:58,641 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 33f5299c-afb7-402e-86a5-a187a751f61b, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:19:58,642 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 33f5299c-afb7-402e-86a5-a187a751f61b, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:58,644 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 33f5299c-afb7-402e-86a5-a187a751f61b, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:03,469 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:13,502 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:13,503 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 922bb992-bd46-4db8-bbda-f30e6f345068, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:13,504 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 922bb992-bd46-4db8-bbda-f30e6f345068, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:20:13,506 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 922bb992-bd46-4db8-bbda-f30e6f345068, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:15,683 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:25,696 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:25,698 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 905d94ea-1877-4fd1-a979-f32c9852190b, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:25,699 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 905d94ea-1877-4fd1-a979-f32c9852190b, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:20:25,700 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 905d94ea-1877-4fd1-a979-f32c9852190b, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:27,497 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:31,196 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:20:31,197 - 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:20:31,217 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-16 17:20:31,217 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 0728dfca-23fb-40e4-8a07-262f81090188, localhost:12444): creating call state
2025-10-16 17:20:31,218 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 0728dfca-23fb-40e4-8a07-262f81090188, localhost:12444): request = { database: "/Root" }
2025-10-16 17:20:31,229 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 0728dfca-23fb-40e4-8a07-262f81090188, localhost:12444): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 12444 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:20:31,230 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:12444, location 1, ssl: False>]>
2025-10-16 17:20:31,231 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:12444
2025-10-16 17:20:37,509 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:37,510 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fb2ec26c-0638-4288-9523-7b07ef91c9e4, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:37,511 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fb2ec26c-0638-4288-9523-7b07ef91c9e4, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:20:37,512 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fb2ec26c-0638-4288-9523-7b07ef91c9e4, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:38,946 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:38,968 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8028080)
2025-10-16 17:20:38,969 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:38,969 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 061289a9-7b81-4ddb-8b6c-52ed1eb26b7f, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:38,970 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 061289a9-7b81-4ddb-8b6c-52ed1eb26b7f, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" 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:20:38,971 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 061289a9-7b81-4ddb-8b6c-52ed1eb26b7f, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:39,339 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:39,341 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_2_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 2])
2025-10-16 17:20:39,342 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:39,343 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9b9a4f62-2233-4814-9f43-e24e2ca43bdf, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:39,345 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9b9a4f62-2233-4814-9f43-e24e2ca43bdf, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_2_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 2)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:39,346 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9b9a4f62-2233-4814-9f43-e24e2ca43bdf, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:44,735 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:44,736 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_2_compression created
2025-10-16 17:20:44,737 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:44,737 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9a4126aa-bcd7-4c51-ab73-fee35bee5b34, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:44,738 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9a4126aa-bcd7-4c51-ab73-fee35bee5b34, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_2_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:44,739 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9a4126aa-bcd7-4c51-ab73-fee35bee5b34, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:45,843 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:45,844 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:45,845 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 827012d1-de41-466d-a33c-311b08f23936, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:45,846 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 827012d1-de41-466d-a33c-311b08f23936, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:45,847 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 827012d1-de41-466d-a33c-311b08f23936, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:47,506 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:47,506 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:47,507 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1b2620a8-4bc9-4749-8438-12e37dd33786, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:47,508 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1b2620a8-4bc9-4749-8438-12e37dd33786, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:47,509 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1b2620a8-4bc9-4749-8438-12e37dd33786, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:48,461 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:48,462 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:48,463 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ad78e9e0-5497-4130-a3ca-1f8fe057680c, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:48,464 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ad78e9e0-5497-4130-a3ca-1f8fe057680c, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:48,468 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ad78e9e0-5497-4130-a3ca-1f8fe057680c, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:50,801 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:50,802 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318880, 'BlobCount': 128}}
2025-10-16 17:20:50,803 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:50,803 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 876dfed1-859a-4cb0-b3c6-8d155a6ee9e5, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:50,807 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 876dfed1-859a-4cb0-b3c6-8d155a6ee9e5, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:50,808 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 876dfed1-859a-4cb0-b3c6-8d155a6ee9e5, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:52,314 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:52,315 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:52,315 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e8c95b86-88b4-4f8b-8bbd-9e5a0dd24f75, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:52,316 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e8c95b86-88b4-4f8b-8bbd-9e5a0dd24f75, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:52,324 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e8c95b86-88b4-4f8b-8bbd-9e5a0dd24f75, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:52,697 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:52,698 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:52,699 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6b380852-5020-4d6f-b1d7-0d993fde0897, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:52,700 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6b380852-5020-4d6f-b1d7-0d993fde0897, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:52,702 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6b380852-5020-4d6f-b1d7-0d993fde0897, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:53,146 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:53,147 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638656, 'BlobCount': 256}}
2025-10-16 17:20:53,148 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:53,148 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f027657e-9092-4ab8-8945-1cb244518560, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:53,149 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f027657e-9092-4ab8-8945-1cb244518560, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:53,161 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f027657e-9092-4ab8-8945-1cb244518560, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:54,765 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:54,766 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:54,767 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d7b2100a-399e-4c64-8bfc-cac49b987011, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:54,768 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d7b2100a-399e-4c64-8bfc-cac49b987011, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:54,769 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d7b2100a-399e-4c64-8bfc-cac49b987011, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:55,153 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:55,154 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:55,155 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 87a29009-f633-4fc0-ac4d-0482a53d2d21, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:55,156 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 87a29009-f633-4fc0-ac4d-0482a53d2d21, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:55,163 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 87a29009-f633-4fc0-ac4d-0482a53d2d21, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:55,825 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:55,826 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 184}}, blobs: {'__DEFAULT': {'Portions': 184, 'BlobSize': 959272, 'BlobCount': 384}}
2025-10-16 17:20:55,827 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:55,828 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5fc22585-f3b0-4604-8ba6-50ba4ddbf939, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:55,829 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5fc22585-f3b0-4604-8ba6-50ba4ddbf939, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:55,830 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5fc22585-f3b0-4604-8ba6-50ba4ddbf939, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:57,080 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:57,081 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:57,081 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a0194c59-80b4-49bc-adbd-89f06ae6fb71, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:57,082 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a0194c59-80b4-49bc-adbd-89f06ae6fb71, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:57,084 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a0194c59-80b4-49bc-adbd-89f06ae6fb71, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:57,470 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:57,471 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:57,472 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 34354df4-c430-4a37-9aea-95267914fe16, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:57,473 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 34354df4-c430-4a37-9aea-95267914fe16, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:57,475 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 34354df4-c430-4a37-9aea-95267914fe16, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:58,177 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:58,178 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 244}}, blobs: {'__DEFAULT': {'Portions': 244, 'BlobSize': 1278640, 'BlobCount': 508}}
2025-10-16 17:20:58,179 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:58,179 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2edcec59-a3e6-4ec5-8f70-7d27f5beafec, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:58,181 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2edcec59-a3e6-4ec5-8f70-7d27f5beafec, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:58,182 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2edcec59-a3e6-4ec5-8f70-7d27f5beafec, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:59,794 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:59,795 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:20:59,796 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a9183f86-5843-460b-876f-d07f572d5038, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:20:59,797 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a9183f86-5843-460b-876f-d07f572d5038, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:59,799 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a9183f86-5843-460b-876f-d07f572d5038, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:00,252 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:00,253 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:00,254 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 97ada78a-f41e-41d4-b128-bd8cc87e8fbe, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:00,255 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 97ada78a-f41e-41d4-b128-bd8cc87e8fbe, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:00,257 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 97ada78a-f41e-41d4-b128-bd8cc87e8fbe, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:01,094 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:01,095 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 305}}, blobs: {'__DEFAULT': {'Portions': 305, 'BlobSize': 1597576, 'BlobCount': 632}}
2025-10-16 17:21:01,096 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:01,097 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d6f39f48-1128-4cff-ac67-5c667f26a76c, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:01,098 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d6f39f48-1128-4cff-ac67-5c667f26a76c, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:01,105 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d6f39f48-1128-4cff-ac67-5c667f26a76c, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:02,401 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:02,401 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:02,402 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5c4e3b8f-2ec5-4d38-9891-080e52966cfc, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:02,403 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5c4e3b8f-2ec5-4d38-9891-080e52966cfc, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:02,405 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5c4e3b8f-2ec5-4d38-9891-080e52966cfc, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:02,736 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:02,737 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:02,738 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f8ff297d-b56b-4f3e-a4b5-742773bbe8ee, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:02,739 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f8ff297d-b56b-4f3e-a4b5-742773bbe8ee, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:02,741 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f8ff297d-b56b-4f3e-a4b5-742773bbe8ee, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:03,347 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:03,348 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 349}}, blobs: {'__DEFAULT': {'Portions': 349, 'BlobSize': 1912800, 'BlobCount': 730}}
2025-10-16 17:21:03,349 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:03,349 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e6d9f8ae-1615-4ffc-944d-f50f72b7d7ea, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:03,350 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e6d9f8ae-1615-4ffc-944d-f50f72b7d7ea, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:03,352 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e6d9f8ae-1615-4ffc-944d-f50f72b7d7ea, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:04,865 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:04,866 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:04,867 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 42407daf-5054-4cfc-b6b8-8e4bced65410, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:04,868 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 42407daf-5054-4cfc-b6b8-8e4bced65410, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:04,870 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 42407daf-5054-4cfc-b6b8-8e4bced65410, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:05,246 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:05,247 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:05,247 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4189c6b7-2221-46ac-8a7b-550dbb205ec9, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:05,248 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4189c6b7-2221-46ac-8a7b-550dbb205ec9, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:05,250 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4189c6b7-2221-46ac-8a7b-550dbb205ec9, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:06,498 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:06,499 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 403}}, blobs: {'__DEFAULT': {'Portions': 403, 'BlobSize': 2230096, 'BlobCount': 842}}
2025-10-16 17:21:06,499 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:06,500 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0a045914-9d81-4242-8b8f-0b16b23fcd5c, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:06,501 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0a045914-9d81-4242-8b8f-0b16b23fcd5c, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:06,504 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0a045914-9d81-4242-8b8f-0b16b23fcd5c, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:08,126 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:08,126 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:08,127 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8426fd35-a9b1-448e-a15f-b23bf577dad8, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:08,128 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8426fd35-a9b1-448e-a15f-b23bf577dad8, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:08,130 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8426fd35-a9b1-448e-a15f-b23bf577dad8, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:08,453 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:08,454 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:08,455 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e1186f0e-3960-48e2-803c-0dc40e0072bd, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:08,456 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e1186f0e-3960-48e2-803c-0dc40e0072bd, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:08,458 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e1186f0e-3960-48e2-803c-0dc40e0072bd, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:09,381 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:09,382 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 449}}, blobs: {'__DEFAULT': {'Portions': 450, 'BlobSize': 2578552, 'BlobCount': 944}}
2025-10-16 17:21:09,382 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:09,383 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b58fbeb5-671a-474c-8bc6-d33318dc37da, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:09,385 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b58fbeb5-671a-474c-8bc6-d33318dc37da, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:09,387 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b58fbeb5-671a-474c-8bc6-d33318dc37da, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:11,296 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:11,296 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:11,297 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c6879c86-fd88-487a-9699-4607a1418f05, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:11,298 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c6879c86-fd88-487a-9699-4607a1418f05, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:11,302 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c6879c86-fd88-487a-9699-4607a1418f05, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:11,641 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:11,642 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:11,642 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 20ba73ae-ef18-4252-b608-11dfc94d09f2, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:11,643 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 20ba73ae-ef18-4252-b608-11dfc94d09f2, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:11,645 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 20ba73ae-ef18-4252-b608-11dfc94d09f2, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:12,956 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:12,956 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 910921, 'Portions': 502}}, blobs: {'__DEFAULT': {'Portions': 495, 'BlobSize': 2859968, 'BlobCount': 1038}}
2025-10-16 17:21:12,957 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:12,958 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e7bb857-9f96-4681-a0dd-cb6e041991c2, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:12,958 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e7bb857-9f96-4681-a0dd-cb6e041991c2, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:12,965 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e7bb857-9f96-4681-a0dd-cb6e041991c2, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:14,422 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:14,422 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:14,423 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 89cb49bf-2cc0-4d50-b607-2ffff607abfc, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:14,424 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 89cb49bf-2cc0-4d50-b607-2ffff607abfc, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:14,425 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 89cb49bf-2cc0-4d50-b607-2ffff607abfc, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:14,898 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:14,899 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:14,900 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a4463d59-675a-4e92-a993-1dd59cbc3c66, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:14,902 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a4463d59-675a-4e92-a993-1dd59cbc3c66, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:14,903 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a4463d59-675a-4e92-a993-1dd59cbc3c66, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:15,877 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:15,877 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 514}}, blobs: {'__DEFAULT': {'Portions': 514, 'BlobSize': 3167600, 'BlobCount': 1088}}
2025-10-16 17:21:15,878 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:15,879 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd80382f-1f94-48bc-8100-ca94387ff69f, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:15,880 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd80382f-1f94-48bc-8100-ca94387ff69f, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_2_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:15,881 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd80382f-1f94-48bc-8100-ca94387ff69f, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:17,231 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:17,232 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:17,232 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d68e2472-9f46-4d75-a281-8790ffbd535b, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:17,233 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d68e2472-9f46-4d75-a281-8790ffbd535b, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:17,235 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d68e2472-9f46-4d75-a281-8790ffbd535b, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:17,565 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:17,566 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:17,566 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 33745532-d640-4f6e-9a4f-64ed3df1ee8c, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:17,567 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 33745532-d640-4f6e-9a4f-64ed3df1ee8c, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:17,577 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 33745532-d640-4f6e-9a4f-64ed3df1ee8c, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:17,890 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:17,891 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:17,891 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 34c4c2ee-824e-47f0-b4da-7a4ad0e394f6, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:17,892 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 34c4c2ee-824e-47f0-b4da-7a4ad0e394f6, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:17,894 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 34c4c2ee-824e-47f0-b4da-7a4ad0e394f6, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:21,564 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:31,601 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:31,601 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 62f42892-d580-4497-a9d4-2af6eb2994c7, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:31,603 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 62f42892-d580-4497-a9d4-2af6eb2994c7, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:31,604 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 62f42892-d580-4497-a9d4-2af6eb2994c7, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:33,691 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:21:33,691 - 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:33,721 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-16 17:21:33,722 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 43da6476-6b43-42c9-a9cd-e25ed1dee4ec, localhost:12444): creating call state
2025-10-16 17:21:33,722 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 43da6476-6b43-42c9-a9cd-e25ed1dee4ec, localhost:12444): request = { database: "/Root" }
2025-10-16 17:21:33,736 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 43da6476-6b43-42c9-a9cd-e25ed1dee4ec, localhost:12444): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 12444 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:33,737 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:12444, location 1, ssl: False>]>
2025-10-16 17:21:33,738 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:12444
2025-10-16 17:21:34,873 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:34,913 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_2_compression`, volumes `value` (8000000, 1568848)
2025-10-16 17:21:34,914 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:34,915 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b1d7a33c-9e7a-47c1-bf7c-fb279138a32c, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:34,915 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b1d7a33c-9e7a-47c1-bf7c-fb279138a32c, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:34,918 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b1d7a33c-9e7a-47c1-bf7c-fb279138a32c, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:35,318 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:35,319 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:35,320 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a388d94e-18a7-47f8-a11e-5cce71cf9291, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:35,321 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a388d94e-18a7-47f8-a11e-5cce71cf9291, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:35,325 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a388d94e-18a7-47f8-a11e-5cce71cf9291, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:39,300 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:49,344 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:49,345 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ce2f9c84-ed73-4bc2-b69b-53be4114f345, ghrun-2kszfvc6cm.auto.internal:12444): creating call state
2025-10-16 17:21:49,346 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ce2f9c84-ed73-4bc2-b69b-53be4114f345, ghrun-2kszfvc6cm.auto.internal:12444): request = { session_id: "ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:21:49,347 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ce2f9c84-ed73-4bc2-b69b-53be4114f345, ghrun-2kszfvc6cm.auto.internal:12444): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:51,250 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzA3MGU3NTItYzQwZjM2OTktNTY2ODNkM2MtNzMxZmUzZWU=
2025-10-16 17:21:51,270 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_2_compression`, volumes `value` (8000000, 1502584)
2025-10-16 17:21:51,270 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_2_compression` 8076032 / 1502584: 5.374762409289597
2025-10-16 17:21:51,273 - INFO - ya.test - pytest_runtest_teardown: Test teardown
