2025-11-05 12:22:02,400 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-05 12:22:02,401 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_6_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 6]
2025-11-05 12:22:02,401 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-05 12:22:02,401 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-05 12:22:02,404 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-05 12:22:02,405 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-05 12:22:02,405 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/c688/0027fe/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-05 12:22:02,407 - DEBUG - ya.test - execute: Command pid: 2473222
2025-11-05 12:22:03,223 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) rc: 0
2025-11-05 12:22:03,223 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) elapsed time (sec): 0.8142597675323486
2025-11-05 12:22:03,224 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) idrss: 0
2025-11-05 12:22:03,224 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) inblock: 0
2025-11-05 12:22:03,224 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) isrss: 0
2025-11-05 12:22:03,225 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) ixrss: 0
2025-11-05 12:22:03,225 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) majflt: 0
2025-11-05 12:22:03,225 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) maxrss: 494836
2025-11-05 12:22:03,225 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) minflt: 45392
2025-11-05 12:22:03,226 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) msgrcv: 0
2025-11-05 12:22:03,226 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) msgsnd: 0
2025-11-05 12:22:03,227 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) nivcsw: 84
2025-11-05 12:22:03,228 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) nsignals: 0
2025-11-05 12:22:03,228 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) nswap: 0
2025-11-05 12:22:03,228 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) nvcsw: 14
2025-11-05 12:22:03,229 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) oublock: 8
2025-11-05 12:22:03,229 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) stime: 0.185019
2025-11-05 12:22:03,229 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) utime: 0.569553
2025-11-05 12:22:03,229 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) wtime: 0.817
2025-11-05 12:22:03,230 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) output:
Git info:
    Commit: bb8d7ee9685d84658f39bc9c7238597c599c6433
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge c59276c0e0e506b21793b800196e5a8048164207 into f38187482f0084fe7bc5ab264dbd7817a983fef9

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-anwhr6cmha
    Host information: 
        Linux ghrun-anwhr6cmha 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-11-05 12:22:03,230 - DEBUG - ya.test - _save_outputs: Command (pid 2473222) errors:

2025-11-05 12:22:03,231 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: bb8d7ee9685d84658f39bc9c7238597c599c6433
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge c59276c0e0e506b21793b800196e5a8048164207 into f38187482f0084fe7bc5ab264dbd7817a983fef9

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-anwhr6cmha
    Host information: 
        Linux ghrun-anwhr6cmha 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-11-05 12:22:03,350 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/c688/0027fe/ydb/apps/ydbd/ydbd
2025-11-05 12:22:03,352 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14389
2025-11-05 12:22:03,354 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/c688/0027fe/r3tmp/kikimr_cluster_18p1x7lc
2025-11-05 12:22:03,396 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/c688/0027fe/ydb/apps/ydbd/ydbd
2025-11-05 12:22:03,397 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12711
2025-11-05 12:22:03,397 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2832
2025-11-05 12:22:03,398 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29034
2025-11-05 12:22:03,398 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63216
2025-11-05 12:22:03,399 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/c688/0027fe/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/kikimr_configs"
2025-11-05 12:22:03,400 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/c688/0027fe/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/c688/0027fe/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/node_1/logfile_xt70rgwz.log --grpc-port=12711 --mon-port=2832 --ic-port=14389
2025-11-05 12:22:03,400 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:12711/1, disk_size 68719476736
2025-11-05 12:22:03,401 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/c688/0027fe/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/kikimr_configs"
2025-11-05 12:22:03,401 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/c688/0027fe/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/c688/0027fe/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/node_1/logfile_xt70rgwz.log --grpc-port=12711 --mon-port=2832 --ic-port=14389
2025-11-05 12:22:03,402 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/c688/0027fe/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/c688/0027fe/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/c688/0027fe/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/node_1/logfile_xt70rgwz.log', '--grpc-port=12711', '--mon-port=2832', '--ic-port=14389')' in '/home/runner/.ya/build/build_root/c688/0027fe/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/node_1' (no wait)
2025-11-05 12:22:03,405 - DEBUG - ya.test - execute: Command pid: 2473363
2025-11-05 12:22:03,406 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:12711/1
2025-11-05 12:22:03,408 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-05 12:22:03,408 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-05 12:22:03,409 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/c688/0027fe/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-05 12:22:03,410 - DEBUG - ya.test - execute: Command pid: 2473364
2025-11-05 12:22:04,137 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) rc: 0
2025-11-05 12:22:04,138 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) elapsed time (sec): 0.7262527942657471
2025-11-05 12:22:04,138 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) idrss: 0
2025-11-05 12:22:04,139 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) inblock: 0
2025-11-05 12:22:04,139 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) isrss: 0
2025-11-05 12:22:04,139 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) ixrss: 0
2025-11-05 12:22:04,140 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) majflt: 0
2025-11-05 12:22:04,140 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) maxrss: 504176
2025-11-05 12:22:04,140 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) minflt: 45382
2025-11-05 12:22:04,140 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) msgrcv: 0
2025-11-05 12:22:04,141 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) msgsnd: 0
2025-11-05 12:22:04,141 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) nivcsw: 27
2025-11-05 12:22:04,141 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) nsignals: 0
2025-11-05 12:22:04,142 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) nswap: 0
2025-11-05 12:22:04,142 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) nvcsw: 15
2025-11-05 12:22:04,142 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) oublock: 8
2025-11-05 12:22:04,147 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) stime: 0.19139699999999998
2025-11-05 12:22:04,147 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) utime: 0.487832
2025-11-05 12:22:04,148 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) wtime: 0.728
2025-11-05 12:22:04,148 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) output:
Git info:
    Commit: bb8d7ee9685d84658f39bc9c7238597c599c6433
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge c59276c0e0e506b21793b800196e5a8048164207 into f38187482f0084fe7bc5ab264dbd7817a983fef9

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-anwhr6cmha
    Host information: 
        Linux ghrun-anwhr6cmha 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-11-05 12:22:04,148 - DEBUG - ya.test - _save_outputs: Command (pid 2473364) errors:

2025-11-05 12:22:04,149 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: bb8d7ee9685d84658f39bc9c7238597c599c6433
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge c59276c0e0e506b21793b800196e5a8048164207 into f38187482f0084fe7bc5ab264dbd7817a983fef9
2025-11-05 12:22:06,769 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/c688/0027fe/ydb/apps/ydbd/ydbd
2025-11-05 12:22:06,770 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/c688/0027fe/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:12711', '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: 14389\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-05 12:22:06,771 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-05 12:22:06,771 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-05 12:22:06,772 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/c688/0027fe/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:12711', '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: 14389\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-05 12:22:06,773 - DEBUG - ya.test - execute: Command pid: 2474116
2025-11-05 12:22:07,698 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) rc: 0
2025-11-05 12:22:07,699 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) elapsed time (sec): 0.924140214920044
2025-11-05 12:22:07,699 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) idrss: 0
2025-11-05 12:22:07,700 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) inblock: 0
2025-11-05 12:22:07,700 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) isrss: 0
2025-11-05 12:22:07,700 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) ixrss: 0
2025-11-05 12:22:07,700 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) majflt: 1
2025-11-05 12:22:07,701 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) maxrss: 578084
2025-11-05 12:22:07,701 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) minflt: 51758
2025-11-05 12:22:07,701 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) msgrcv: 0
2025-11-05 12:22:07,702 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) msgsnd: 0
2025-11-05 12:22:07,702 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) nivcsw: 230
2025-11-05 12:22:07,702 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) nsignals: 0
2025-11-05 12:22:07,702 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) nswap: 0
2025-11-05 12:22:07,703 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) nvcsw: 528
2025-11-05 12:22:07,703 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) oublock: 8
2025-11-05 12:22:07,703 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) stime: 0.23868199999999998
2025-11-05 12:22:07,704 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) utime: 0.611341
2025-11-05 12:22:07,704 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) wtime: 0.926
2025-11-05 12:22:07,704 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-05 12:22:07,705 - DEBUG - ya.test - _save_outputs: Command (pid 2474116) errors:

2025-11-05 12:22:07,706 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/c688/0027fe/ydb/apps/ydbd/ydbd
2025-11-05 12:22:07,706 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/c688/0027fe/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:12711', '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-11-05 12:22:07,708 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-05 12:22:07,709 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-05 12:22:07,709 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/c688/0027fe/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:12711', '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-11-05 12:22:07,710 - DEBUG - ya.test - execute: Command pid: 2474424
2025-11-05 12:22:08,594 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) rc: 0
2025-11-05 12:22:08,595 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) elapsed time (sec): 0.8827145099639893
2025-11-05 12:22:08,595 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) idrss: 0
2025-11-05 12:22:08,595 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) inblock: 0
2025-11-05 12:22:08,596 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) isrss: 0
2025-11-05 12:22:08,596 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) ixrss: 0
2025-11-05 12:22:08,596 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) majflt: 0
2025-11-05 12:22:08,597 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) maxrss: 578228
2025-11-05 12:22:08,597 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) minflt: 51552
2025-11-05 12:22:08,597 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) msgrcv: 0
2025-11-05 12:22:08,597 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) msgsnd: 0
2025-11-05 12:22:08,598 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) nivcsw: 94
2025-11-05 12:22:08,598 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) nsignals: 0
2025-11-05 12:22:08,598 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) nswap: 0
2025-11-05 12:22:08,598 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) nvcsw: 806
2025-11-05 12:22:08,599 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) oublock: 8
2025-11-05 12:22:08,599 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) stime: 0.212136
2025-11-05 12:22:08,599 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) utime: 0.5445559999999999
2025-11-05 12:22:08,600 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) wtime: 0.884
2025-11-05 12:22:08,600 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-05 12:22:08,600 - DEBUG - ya.test - _save_outputs: Command (pid 2474424) errors:

2025-11-05 12:22:08,601 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-05 12:22:08,687 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-05 12:22:08,744 - 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-11-05 12:22:08,747 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-05 12:22:08,748 - 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-11-05 12:22:08,768 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-05 12:22:08,769 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, cf248729-ef6c-44b3-b988-12c0e3e6fc00, localhost:12711): creating call state
2025-11-05 12:22:08,770 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, cf248729-ef6c-44b3-b988-12c0e3e6fc00, localhost:12711): request = { database: "/Root" }
2025-11-05 12:22:08,781 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, cf248729-ef6c-44b3-b988-12c0e3e6fc00, localhost:12711): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-anwhr6cmha.auto.internal" port: 12711 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-11-05 12:22:08,782 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-anwhr6cmha.auto.internal:12711, location 1, ssl: False>]>
2025-11-05 12:22:08,783 - 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-11-05 12:22:08,825 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:12711
2025-11-05 12:22:08,827 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-05 12:22:08,828 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 2e04dad5-25e7-448e-9526-bf206ce426f8, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:08,829 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 2e04dad5-25e7-448e-9526-bf206ce426f8, ghrun-anwhr6cmha.auto.internal:12711): request = {  }
2025-11-05 12:22:08,837 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 2e04dad5-25e7-448e-9526-bf206ce426f8, ghrun-anwhr6cmha.auto.internal:12711): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" node_id: 1 }
2025-11-05 12:22:08,838 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 62ab40f8-8f98-4311-a45d-17b69bbf8ee8, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:08,838 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 62ab40f8-8f98-4311-a45d-17b69bbf8ee8, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" }
2025-11-05 12:22:08,841 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 62ab40f8-8f98-4311-a45d-17b69bbf8ee8, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:08,866 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:08,867 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, afaf85cc-5373-4bfc-bf60-577bf576c8dc, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:08,868 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, afaf85cc-5373-4bfc-bf60-577bf576c8dc, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:08,870 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, afaf85cc-5373-4bfc-bf60-577bf576c8dc, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:11,412 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:11,412 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-05 12:22:11,413 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:11,414 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 01870967-9bf5-4296-9456-cfdf41caa56e, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:11,414 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 01870967-9bf5-4296-9456-cfdf41caa56e, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:11,416 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 01870967-9bf5-4296-9456-cfdf41caa56e, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:12,327 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:12,328 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:12,328 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f8b018c3-9774-45e2-b794-f7e4afbb91a1, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:12,329 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f8b018c3-9774-45e2-b794-f7e4afbb91a1, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:12,331 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f8b018c3-9774-45e2-b794-f7e4afbb91a1, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:13,453 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:13,454 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:13,454 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 58edf0d1-fdf8-45d3-84e4-872640113fca, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:13,455 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 58edf0d1-fdf8-45d3-84e4-872640113fca, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:13,456 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 58edf0d1-fdf8-45d3-84e4-872640113fca, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:14,332 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:14,333 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:14,334 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6f2919e3-ea63-4387-a2e0-d25d47b7074c, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:14,335 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6f2919e3-ea63-4387-a2e0-d25d47b7074c, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:14,336 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6f2919e3-ea63-4387-a2e0-d25d47b7074c, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:15,768 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:15,769 - 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-11-05 12:22:15,769 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:15,770 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fc3a709c-caaa-482d-b2e5-1de35c470a85, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:15,771 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fc3a709c-caaa-482d-b2e5-1de35c470a85, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:15,772 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fc3a709c-caaa-482d-b2e5-1de35c470a85, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:16,856 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:16,856 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:16,857 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d4e4730c-8877-49e2-b10e-69bacc32bb95, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:16,858 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d4e4730c-8877-49e2-b10e-69bacc32bb95, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:16,859 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d4e4730c-8877-49e2-b10e-69bacc32bb95, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:17,073 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:17,074 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:17,074 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8eea5ea1-0929-4bd5-ba6d-87cea1239ffe, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:17,075 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8eea5ea1-0929-4bd5-ba6d-87cea1239ffe, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:17,076 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8eea5ea1-0929-4bd5-ba6d-87cea1239ffe, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:17,353 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:17,354 - 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-11-05 12:22:17,355 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:17,355 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 98b6fd42-bdec-4bc5-9b26-1101b243200e, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:17,356 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 98b6fd42-bdec-4bc5-9b26-1101b243200e, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:17,358 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 98b6fd42-bdec-4bc5-9b26-1101b243200e, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:18,223 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:18,224 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:18,224 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4d29a135-2ef2-4bd8-909a-5e89078f5e9d, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:18,225 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4d29a135-2ef2-4bd8-909a-5e89078f5e9d, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:18,226 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4d29a135-2ef2-4bd8-909a-5e89078f5e9d, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:18,479 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:18,480 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:18,480 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, afbf235b-c5d9-4228-af3d-f34120daedcd, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:18,481 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, afbf235b-c5d9-4228-af3d-f34120daedcd, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:18,483 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, afbf235b-c5d9-4228-af3d-f34120daedcd, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:18,854 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:18,855 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 4855296, 'BlobCount': 384}}
2025-11-05 12:22:18,855 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:18,856 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5704203d-737a-4793-87c5-46ec38fd4145, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:18,856 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5704203d-737a-4793-87c5-46ec38fd4145, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:18,858 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5704203d-737a-4793-87c5-46ec38fd4145, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:19,867 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:19,867 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:19,868 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 129c73e6-209f-4c0e-8281-0cb2da48f965, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:19,869 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 129c73e6-209f-4c0e-8281-0cb2da48f965, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:19,870 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 129c73e6-209f-4c0e-8281-0cb2da48f965, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:20,137 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:20,138 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:20,138 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 23b814e7-4940-4c44-af3a-5fb2d5b9c0b9, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:20,139 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 23b814e7-4940-4c44-af3a-5fb2d5b9c0b9, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:20,140 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 23b814e7-4940-4c44-af3a-5fb2d5b9c0b9, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:20,536 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:20,537 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 6473728, 'BlobCount': 512}}
2025-11-05 12:22:20,537 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:20,538 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8e4d1b64-dfd1-4851-b75f-11d87ec40521, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:20,539 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8e4d1b64-dfd1-4851-b75f-11d87ec40521, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:20,541 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8e4d1b64-dfd1-4851-b75f-11d87ec40521, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:21,493 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:21,494 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:21,495 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f946e617-12e4-422d-8e43-9bada5fc32b7, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:21,496 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f946e617-12e4-422d-8e43-9bada5fc32b7, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:21,497 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f946e617-12e4-422d-8e43-9bada5fc32b7, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:21,727 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:21,728 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:21,728 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 754f5d3f-6049-4809-95ae-a6b50f26e2ac, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:21,729 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 754f5d3f-6049-4809-95ae-a6b50f26e2ac, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:21,730 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 754f5d3f-6049-4809-95ae-a6b50f26e2ac, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:22,130 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:22,131 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 8092160, 'BlobCount': 640}}
2025-11-05 12:22:22,131 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:22,132 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ac2ffd13-6e71-4b69-88bd-d2e96335395e, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:22,133 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ac2ffd13-6e71-4b69-88bd-d2e96335395e, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:22,134 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ac2ffd13-6e71-4b69-88bd-d2e96335395e, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:23,024 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:23,025 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:23,025 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f10bf60a-4406-41d1-b3f4-fe2b6ff43367, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:23,026 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f10bf60a-4406-41d1-b3f4-fe2b6ff43367, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:23,027 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f10bf60a-4406-41d1-b3f4-fe2b6ff43367, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:23,265 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:23,266 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:23,267 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d65fc987-9a45-48fd-a429-d4687f17b098, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:23,268 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d65fc987-9a45-48fd-a429-d4687f17b098, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:23,271 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d65fc987-9a45-48fd-a429-d4687f17b098, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:23,716 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:23,717 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 9710592, 'BlobCount': 768}}
2025-11-05 12:22:23,717 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:23,718 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1b10c3cd-c409-48cb-a8c5-3ef7786d1813, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:23,719 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1b10c3cd-c409-48cb-a8c5-3ef7786d1813, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:23,720 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1b10c3cd-c409-48cb-a8c5-3ef7786d1813, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:24,687 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:24,688 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:24,689 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd98fa83-555d-48dd-9e7f-d2ccfbcb45a6, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:24,690 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd98fa83-555d-48dd-9e7f-d2ccfbcb45a6, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:24,691 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd98fa83-555d-48dd-9e7f-d2ccfbcb45a6, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:24,916 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:24,917 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:24,917 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bc23b2b7-3f9e-4b48-aff8-d27905b6104c, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:24,918 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bc23b2b7-3f9e-4b48-aff8-d27905b6104c, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:24,920 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bc23b2b7-3f9e-4b48-aff8-d27905b6104c, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:25,398 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:25,399 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 11329024, 'BlobCount': 896}}
2025-11-05 12:22:25,399 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:25,400 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d91eeb7a-daed-40d2-a916-e342a60ee759, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:25,401 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d91eeb7a-daed-40d2-a916-e342a60ee759, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:25,402 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d91eeb7a-daed-40d2-a916-e342a60ee759, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:26,370 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:26,370 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:26,371 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1b132c84-f257-4e07-a9c7-627bc1ba372c, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:26,372 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1b132c84-f257-4e07-a9c7-627bc1ba372c, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:26,373 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1b132c84-f257-4e07-a9c7-627bc1ba372c, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:26,583 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:26,584 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:26,585 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd0b9495-bf61-4305-85fb-91697bab6713, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:26,586 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd0b9495-bf61-4305-85fb-91697bab6713, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:26,588 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd0b9495-bf61-4305-85fb-91697bab6713, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:27,132 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:27,133 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 12947456, 'BlobCount': 1024}}
2025-11-05 12:22:27,133 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:27,134 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ae11266b-6b5f-416b-9d78-48e50beca578, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:27,134 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ae11266b-6b5f-416b-9d78-48e50beca578, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:27,135 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ae11266b-6b5f-416b-9d78-48e50beca578, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:28,107 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:28,107 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:28,108 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1a2a85cb-b406-403c-ae55-173006ded726, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:28,109 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1a2a85cb-b406-403c-ae55-173006ded726, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:28,110 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1a2a85cb-b406-403c-ae55-173006ded726, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:28,379 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:28,379 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:28,380 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e8a04e74-201c-4c54-b9aa-78a4ee6d326b, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:28,381 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e8a04e74-201c-4c54-b9aa-78a4ee6d326b, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:28,382 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e8a04e74-201c-4c54-b9aa-78a4ee6d326b, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:28,942 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:28,943 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 14565888, 'BlobCount': 1152}}
2025-11-05 12:22:28,944 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:28,944 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8fb50abd-04cd-4dd1-816c-6f3990a92066, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:28,945 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8fb50abd-04cd-4dd1-816c-6f3990a92066, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:28,947 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8fb50abd-04cd-4dd1-816c-6f3990a92066, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:29,940 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:29,941 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:29,942 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1ad138e0-e516-48e2-93d1-7ef2689fb9de, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:29,942 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1ad138e0-e516-48e2-93d1-7ef2689fb9de, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:29,944 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1ad138e0-e516-48e2-93d1-7ef2689fb9de, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:30,208 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:30,209 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:30,210 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 77754a96-1507-417b-94a7-e31921d1ff4d, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:30,211 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 77754a96-1507-417b-94a7-e31921d1ff4d, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:30,212 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 77754a96-1507-417b-94a7-e31921d1ff4d, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:30,814 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:30,816 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 16184320, 'BlobCount': 1280}}
2025-11-05 12:22:30,816 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:30,817 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5407f851-94ab-4e50-b9a3-64daddc21452, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:30,818 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5407f851-94ab-4e50-b9a3-64daddc21452, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:30,823 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5407f851-94ab-4e50-b9a3-64daddc21452, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:31,852 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:31,853 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:31,854 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 462254b3-297f-4a22-bca4-4052d8d6bcf5, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:31,855 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 462254b3-297f-4a22-bca4-4052d8d6bcf5, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:31,857 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 462254b3-297f-4a22-bca4-4052d8d6bcf5, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:32,057 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:32,058 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:32,058 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 030d3848-535b-420e-9315-69859a31e546, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:32,059 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 030d3848-535b-420e-9315-69859a31e546, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:32,060 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 030d3848-535b-420e-9315-69859a31e546, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:32,308 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:32,308 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:32,309 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 03f3d314-62b8-4d8a-89ad-4154ec32feae, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:32,310 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 03f3d314-62b8-4d8a-89ad-4154ec32feae, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:32,312 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 03f3d314-62b8-4d8a-89ad-4154ec32feae, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:35,650 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:45,723 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:45,724 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1ddbcb15-a6fd-4d2c-b505-6ad4949862d7, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:45,725 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1ddbcb15-a6fd-4d2c-b505-6ad4949862d7, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:45,726 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1ddbcb15-a6fd-4d2c-b505-6ad4949862d7, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:49,652 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:49,692 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-05 12:22:49,693 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:22:49,694 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8f26aae8-27c1-42f1-b63e-3c418c0367fe, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:22:49,695 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8f26aae8-27c1-42f1-b63e-3c418c0367fe, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:22:49,696 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8f26aae8-27c1-42f1-b63e-3c418c0367fe, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:53,672 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:03,710 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:03,711 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2b8be8b1-8987-4514-b684-a2730354a8c6, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:03,712 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2b8be8b1-8987-4514-b684-a2730354a8c6, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:23:03,713 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2b8be8b1-8987-4514-b684-a2730354a8c6, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:09,409 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-05 12:23:09,410 - 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-11-05 12:23:09,450 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-05 12:23:09,451 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 6ddd9005-2700-4fe5-97b5-b815b7940305, localhost:12711): creating call state
2025-11-05 12:23:09,452 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 6ddd9005-2700-4fe5-97b5-b815b7940305, localhost:12711): request = { database: "/Root" }
2025-11-05 12:23:09,478 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 6ddd9005-2700-4fe5-97b5-b815b7940305, localhost:12711): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-anwhr6cmha.auto.internal" port: 12711 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-11-05 12:23:09,480 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-anwhr6cmha.auto.internal:12711, location 1, ssl: False>]>
2025-11-05 12:23:09,480 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:12711
2025-11-05 12:23:10,744 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:10,797 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-05 12:23:10,798 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:10,799 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 657a06f8-1667-4ff7-80c7-4168c75488fc, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:10,800 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 657a06f8-1667-4ff7-80c7-4168c75488fc, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" 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-11-05 12:23:10,802 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 657a06f8-1667-4ff7-80c7-4168c75488fc, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:11,632 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:11,636 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_6_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 6])
2025-11-05 12:23:11,641 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:11,642 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b14e8275-82e4-4f9b-97ad-2e444a605b6c, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:11,644 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b14e8275-82e4-4f9b-97ad-2e444a605b6c, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_6_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 6)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:11,645 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b14e8275-82e4-4f9b-97ad-2e444a605b6c, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:17,391 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:17,391 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_6_compression created
2025-11-05 12:23:17,392 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:17,393 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 69e149aa-5103-47bb-91c8-a12a4fbd5beb, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:17,394 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 69e149aa-5103-47bb-91c8-a12a4fbd5beb, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_6_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:17,400 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 69e149aa-5103-47bb-91c8-a12a4fbd5beb, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:19,089 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:19,089 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:19,090 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0249054a-42a9-4a46-b301-9d432a1bcc3e, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:19,091 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0249054a-42a9-4a46-b301-9d432a1bcc3e, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:19,093 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0249054a-42a9-4a46-b301-9d432a1bcc3e, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:21,512 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:21,512 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:21,513 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2a607c44-0a52-4ed5-9148-489fc570810d, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:21,514 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2a607c44-0a52-4ed5-9148-489fc570810d, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:21,515 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2a607c44-0a52-4ed5-9148-489fc570810d, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:23,044 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:23,045 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:23,046 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 88400d8a-38a1-47d0-adb9-c1949f1f9248, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:23,047 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 88400d8a-38a1-47d0-adb9-c1949f1f9248, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:23,051 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 88400d8a-38a1-47d0-adb9-c1949f1f9248, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:27,291 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:27,291 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318016, 'BlobCount': 128}}
2025-11-05 12:23:27,292 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:27,293 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd793bcd-c5c4-46c7-8fcd-a9047f1ac075, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:27,293 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd793bcd-c5c4-46c7-8fcd-a9047f1ac075, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:27,298 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd793bcd-c5c4-46c7-8fcd-a9047f1ac075, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:29,003 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:29,003 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:29,004 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, df41c974-06b4-4fe0-a4e4-366d0e64daf4, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:29,005 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, df41c974-06b4-4fe0-a4e4-366d0e64daf4, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:29,010 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, df41c974-06b4-4fe0-a4e4-366d0e64daf4, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:29,515 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:29,516 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:29,517 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8ce244c2-2ec3-42aa-ac92-33c0f538e5ad, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:29,518 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8ce244c2-2ec3-42aa-ac92-33c0f538e5ad, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:29,519 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8ce244c2-2ec3-42aa-ac92-33c0f538e5ad, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:30,438 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:30,439 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637312, 'BlobCount': 256}}
2025-11-05 12:23:30,439 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:30,440 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5bae74b9-79cb-4caf-a3e6-c4f9fea81d35, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:30,441 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5bae74b9-79cb-4caf-a3e6-c4f9fea81d35, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:30,446 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5bae74b9-79cb-4caf-a3e6-c4f9fea81d35, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:32,307 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:32,307 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:32,308 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c952fb46-5567-483c-966e-fada8b57d953, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:32,309 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c952fb46-5567-483c-966e-fada8b57d953, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:32,313 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c952fb46-5567-483c-966e-fada8b57d953, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:32,933 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:32,934 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:32,935 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 27c67c16-09e6-4f52-afb7-9d282290e56a, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:32,936 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 27c67c16-09e6-4f52-afb7-9d282290e56a, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:32,937 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 27c67c16-09e6-4f52-afb7-9d282290e56a, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:34,397 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:34,397 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956352, 'BlobCount': 384}}
2025-11-05 12:23:34,398 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:34,399 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, edcc0f87-b841-4f37-8731-d51744c04ef2, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:34,407 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, edcc0f87-b841-4f37-8731-d51744c04ef2, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:34,408 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, edcc0f87-b841-4f37-8731-d51744c04ef2, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:36,632 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:36,633 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:36,634 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ffc575ca-de6a-40ba-b5e1-06f4f25edaba, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:36,635 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ffc575ca-de6a-40ba-b5e1-06f4f25edaba, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:36,637 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ffc575ca-de6a-40ba-b5e1-06f4f25edaba, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:37,559 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:37,560 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:37,561 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f6941e07-0db7-48c3-ab5d-b4f8594d9a93, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:37,562 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f6941e07-0db7-48c3-ab5d-b4f8594d9a93, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:37,567 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f6941e07-0db7-48c3-ab5d-b4f8594d9a93, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:38,933 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:38,933 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275712, 'BlobCount': 512}}
2025-11-05 12:23:38,934 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:38,935 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6fe895ee-4fe3-4ea2-843f-c182836d1890, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:38,936 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6fe895ee-4fe3-4ea2-843f-c182836d1890, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:38,938 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6fe895ee-4fe3-4ea2-843f-c182836d1890, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:40,967 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:40,968 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:40,969 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 57570352-c8ee-4e75-bf9f-1e3fbab9b8d9, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:40,969 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 57570352-c8ee-4e75-bf9f-1e3fbab9b8d9, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:40,971 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 57570352-c8ee-4e75-bf9f-1e3fbab9b8d9, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:42,027 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:42,028 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:42,028 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b5da8317-f2e9-469f-b704-f61e39935bf0, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:42,029 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b5da8317-f2e9-469f-b704-f61e39935bf0, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:42,031 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b5da8317-f2e9-469f-b704-f61e39935bf0, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:43,044 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:43,044 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594672, 'BlobCount': 640}}
2025-11-05 12:23:43,045 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:43,045 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c0f47dc9-b973-4353-a18b-404ed08f27fe, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:43,046 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c0f47dc9-b973-4353-a18b-404ed08f27fe, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:43,051 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c0f47dc9-b973-4353-a18b-404ed08f27fe, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:45,368 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:45,369 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:45,370 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d6fd9d1-93d2-4c51-b6c5-5c4ffa0810cd, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:45,371 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d6fd9d1-93d2-4c51-b6c5-5c4ffa0810cd, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:45,372 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d6fd9d1-93d2-4c51-b6c5-5c4ffa0810cd, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:45,888 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:45,889 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:45,889 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 794bf7d2-ad3e-4de7-aef3-3c517a6cf849, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:45,890 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 794bf7d2-ad3e-4de7-aef3-3c517a6cf849, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:45,892 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 794bf7d2-ad3e-4de7-aef3-3c517a6cf849, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:46,816 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:46,817 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913856, 'BlobCount': 768}}
2025-11-05 12:23:46,818 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:46,818 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a2a1165f-e1ef-49c1-b5b1-99d91c33d6b4, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:46,819 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a2a1165f-e1ef-49c1-b5b1-99d91c33d6b4, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:46,828 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a2a1165f-e1ef-49c1-b5b1-99d91c33d6b4, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:48,659 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:48,660 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:48,660 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d9b341f4-8571-471a-ae81-f743dd288f83, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:48,661 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d9b341f4-8571-471a-ae81-f743dd288f83, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:48,666 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d9b341f4-8571-471a-ae81-f743dd288f83, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:49,355 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:49,356 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:49,356 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 057954df-a4da-4dca-8c30-5f30fdbfff6e, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:49,357 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 057954df-a4da-4dca-8c30-5f30fdbfff6e, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:49,372 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 057954df-a4da-4dca-8c30-5f30fdbfff6e, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:50,372 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:50,373 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2232992, 'BlobCount': 896}}
2025-11-05 12:23:50,374 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:50,374 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f952a111-211f-4f50-96b9-aaee2273aae7, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:50,375 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f952a111-211f-4f50-96b9-aaee2273aae7, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:50,377 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f952a111-211f-4f50-96b9-aaee2273aae7, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:52,282 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:52,282 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:52,283 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8d345c1a-67b6-4e6b-a90a-fbf14cdadeb7, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:52,284 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8d345c1a-67b6-4e6b-a90a-fbf14cdadeb7, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:52,285 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8d345c1a-67b6-4e6b-a90a-fbf14cdadeb7, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:52,684 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:52,685 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:52,685 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 44f12a06-5c74-4610-8042-21eba1ec9038, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:52,686 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 44f12a06-5c74-4610-8042-21eba1ec9038, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:52,688 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 44f12a06-5c74-4610-8042-21eba1ec9038, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:53,684 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:53,685 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552480, 'BlobCount': 1024}}
2025-11-05 12:23:53,685 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:53,686 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5efed5f6-5802-488f-b28f-57a1454de079, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:53,687 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5efed5f6-5802-488f-b28f-57a1454de079, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:53,688 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5efed5f6-5802-488f-b28f-57a1454de079, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:55,419 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:55,419 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:55,420 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 117a24d9-9c5c-4589-98e9-d9df2c24545c, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:55,421 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 117a24d9-9c5c-4589-98e9-d9df2c24545c, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:55,422 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 117a24d9-9c5c-4589-98e9-d9df2c24545c, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:56,004 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:56,005 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:56,006 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b8ca30d7-4ca4-41f8-91af-a4da89afbfa9, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:56,007 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b8ca30d7-4ca4-41f8-91af-a4da89afbfa9, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:56,009 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b8ca30d7-4ca4-41f8-91af-a4da89afbfa9, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:58,056 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:58,057 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871488, 'BlobCount': 1152}}
2025-11-05 12:23:58,057 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:58,058 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1c84804f-6034-42b3-9e82-4eed07799843, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:58,060 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1c84804f-6034-42b3-9e82-4eed07799843, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:58,061 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1c84804f-6034-42b3-9e82-4eed07799843, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:59,930 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:59,931 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:23:59,932 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7f25e7cd-6536-4abe-976f-0caef2e6f896, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:23:59,933 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7f25e7cd-6536-4abe-976f-0caef2e6f896, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:59,934 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7f25e7cd-6536-4abe-976f-0caef2e6f896, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:00,607 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:00,608 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:00,609 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4b78619f-06fd-4cfc-a452-a159bcef0f51, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:24:00,610 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4b78619f-06fd-4cfc-a452-a159bcef0f51, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:00,627 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4b78619f-06fd-4cfc-a452-a159bcef0f51, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:02,416 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:02,417 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3190992, 'BlobCount': 1280}}
2025-11-05 12:24:02,418 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:02,418 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 16e2b95e-497c-4aa7-9905-de101d0e6db1, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:24:02,419 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 16e2b95e-497c-4aa7-9905-de101d0e6db1, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_6_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:02,423 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 16e2b95e-497c-4aa7-9905-de101d0e6db1, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:04,511 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:04,512 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:04,513 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2ebac397-0a8b-4bc3-8409-9513661b1b59, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:24:04,514 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2ebac397-0a8b-4bc3-8409-9513661b1b59, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:04,516 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2ebac397-0a8b-4bc3-8409-9513661b1b59, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:04,963 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:04,964 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:04,964 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 219883d2-8e18-4367-b15c-6602789e581e, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:24:04,965 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 219883d2-8e18-4367-b15c-6602789e581e, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:04,967 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 219883d2-8e18-4367-b15c-6602789e581e, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:05,510 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:05,511 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:05,511 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 73cdb71e-12b6-49f2-990e-246872c7db33, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:24:05,512 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 73cdb71e-12b6-49f2-990e-246872c7db33, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:05,513 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 73cdb71e-12b6-49f2-990e-246872c7db33, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:12,088 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:22,160 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:22,161 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 62686990-895e-4976-8f77-a63a1cfd5d31, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:24:22,162 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 62686990-895e-4976-8f77-a63a1cfd5d31, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:22,164 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 62686990-895e-4976-8f77-a63a1cfd5d31, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:26,439 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:36,483 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:36,484 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1e439a2c-1b8d-4a84-92f1-006828aedf6c, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:24:36,485 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1e439a2c-1b8d-4a84-92f1-006828aedf6c, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:36,494 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1e439a2c-1b8d-4a84-92f1-006828aedf6c, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:37,804 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:47,810 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:47,811 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9c9c7957-9037-4be1-88f1-861478752faa, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:24:47,812 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9c9c7957-9037-4be1-88f1-861478752faa, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:47,813 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9c9c7957-9037-4be1-88f1-861478752faa, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:48,844 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:48,848 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_6_compression`, volumes `value` (8000000, 1466256)
2025-11-05 12:24:48,848 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:48,849 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a6c59943-f59d-406c-8bab-5ced36b6ccd2, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:24:48,850 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a6c59943-f59d-406c-8bab-5ced36b6ccd2, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:48,852 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a6c59943-f59d-406c-8bab-5ced36b6ccd2, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:49,350 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:49,351 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:24:49,351 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 725a5170-48fc-4deb-8ab8-baffa4909bb7, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:24:49,352 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 725a5170-48fc-4deb-8ab8-baffa4909bb7, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:49,354 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 725a5170-48fc-4deb-8ab8-baffa4909bb7, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:50,552 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:25:00,582 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:25:00,583 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f0a3484f-87fa-4159-84ea-f634431a206a, ghrun-anwhr6cmha.auto.internal:12711): creating call state
2025-11-05 12:25:00,584 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f0a3484f-87fa-4159-84ea-f634431a206a, ghrun-anwhr6cmha.auto.internal:12711): request = { session_id: "ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:00,586 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f0a3484f-87fa-4159-84ea-f634431a206a, ghrun-anwhr6cmha.auto.internal:12711): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:02,032 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTRlYzA3MDEtOTUyNzViOTYtZGQ0NTBjZGUtNzVkZWE2NjE=
2025-11-05 12:25:02,039 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_6_compression`, volumes `value` (8000000, 1466256)
2025-11-05 12:25:02,040 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_6_compression` 8092160 / 1466256: 5.518927117774795
2025-11-05 12:25:02,044 - INFO - ya.test - pytest_runtest_teardown: Test teardown
