2025-11-05 12:21:58,521 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-05 12:21:58,522 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[lz4_compression-COMPRESSION = "lz4"]
2025-11-05 12:21:58,522 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-05 12:21:58,522 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-05 12:21:58,525 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-05 12:21:58,525 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-05 12:21:58,526 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/c688/002802/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-05 12:21:58,528 - DEBUG - ya.test - execute: Command pid: 2470612
2025-11-05 12:21:59,313 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) rc: 0
2025-11-05 12:21:59,313 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) elapsed time (sec): 0.7837786674499512
2025-11-05 12:21:59,314 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) idrss: 0
2025-11-05 12:21:59,314 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) inblock: 0
2025-11-05 12:21:59,314 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) isrss: 0
2025-11-05 12:21:59,314 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) ixrss: 0
2025-11-05 12:21:59,315 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) majflt: 0
2025-11-05 12:21:59,315 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) maxrss: 491876
2025-11-05 12:21:59,315 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) minflt: 45369
2025-11-05 12:21:59,316 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) msgrcv: 0
2025-11-05 12:21:59,316 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) msgsnd: 0
2025-11-05 12:21:59,316 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) nivcsw: 79
2025-11-05 12:21:59,317 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) nsignals: 0
2025-11-05 12:21:59,317 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) nswap: 0
2025-11-05 12:21:59,317 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) nvcsw: 14
2025-11-05 12:21:59,317 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) oublock: 8
2025-11-05 12:21:59,317 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) stime: 0.191708
2025-11-05 12:21:59,318 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) utime: 0.526388
2025-11-05 12:21:59,318 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) wtime: 0.786
2025-11-05 12:21:59,318 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) 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:21:59,319 - DEBUG - ya.test - _save_outputs: Command (pid 2470612) errors:

2025-11-05 12:21:59,319 - 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:21:59,440 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/c688/002802/ydb/apps/ydbd/ydbd
2025-11-05 12:21:59,441 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11634
2025-11-05 12:21:59,444 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/c688/002802/r3tmp/kikimr_cluster_nz3dh6ev
2025-11-05 12:21:59,488 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/c688/002802/ydb/apps/ydbd/ydbd
2025-11-05 12:21:59,489 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16411
2025-11-05 12:21:59,490 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11317
2025-11-05 12:21:59,490 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6521
2025-11-05 12:21:59,491 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21929
2025-11-05 12:21:59,492 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/c688/002802/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/kikimr_configs"
2025-11-05 12:21:59,492 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/c688/002802/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/002802/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1/logfile_ikb37mfa.log --grpc-port=16411 --mon-port=11317 --ic-port=11634
2025-11-05 12:21:59,493 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:16411/1, disk_size 68719476736
2025-11-05 12:21:59,493 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/c688/002802/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/kikimr_configs"
2025-11-05 12:21:59,494 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/c688/002802/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/002802/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1/logfile_ikb37mfa.log --grpc-port=16411 --mon-port=11317 --ic-port=11634
2025-11-05 12:21:59,495 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/c688/002802/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/c688/002802/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/c688/002802/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1/logfile_ikb37mfa.log', '--grpc-port=16411', '--mon-port=11317', '--ic-port=11634')' in '/home/runner/.ya/build/build_root/c688/002802/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1' (no wait)
2025-11-05 12:21:59,498 - DEBUG - ya.test - execute: Command pid: 2471486
2025-11-05 12:21:59,499 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:16411/1
2025-11-05 12:21:59,501 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-05 12:21:59,501 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-05 12:21:59,502 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/c688/002802/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-05 12:21:59,503 - DEBUG - ya.test - execute: Command pid: 2471487
2025-11-05 12:22:00,278 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) rc: 0
2025-11-05 12:22:00,278 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) elapsed time (sec): 0.7735581398010254
2025-11-05 12:22:00,279 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) idrss: 0
2025-11-05 12:22:00,279 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) inblock: 0
2025-11-05 12:22:00,280 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) isrss: 0
2025-11-05 12:22:00,280 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) ixrss: 0
2025-11-05 12:22:00,280 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) majflt: 0
2025-11-05 12:22:00,280 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) maxrss: 501076
2025-11-05 12:22:00,281 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) minflt: 45406
2025-11-05 12:22:00,281 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) msgrcv: 0
2025-11-05 12:22:00,281 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) msgsnd: 0
2025-11-05 12:22:00,281 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) nivcsw: 74
2025-11-05 12:22:00,282 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) nsignals: 0
2025-11-05 12:22:00,282 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) nswap: 0
2025-11-05 12:22:00,282 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) nvcsw: 14
2025-11-05 12:22:00,282 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) oublock: 8
2025-11-05 12:22:00,282 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) stime: 0.188442
2025-11-05 12:22:00,283 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) utime: 0.503097
2025-11-05 12:22:00,283 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) wtime: 0.775
2025-11-05 12:22:00,283 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) 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:00,283 - DEBUG - ya.test - _save_outputs: Command (pid 2471487) errors:

2025-11-05 12:22:00,284 - 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:03,014 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/c688/002802/ydb/apps/ydbd/ydbd
2025-11-05 12:22:03,014 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/c688/002802/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:16411', '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: 11634\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-05 12:22:03,016 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-05 12:22:03,016 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-05 12:22:03,017 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/c688/002802/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:16411', '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: 11634\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-05 12:22:03,023 - DEBUG - ya.test - execute: Command pid: 2473332
2025-11-05 12:22:03,914 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) rc: 0
2025-11-05 12:22:03,914 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) elapsed time (sec): 0.8902149200439453
2025-11-05 12:22:03,914 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) idrss: 0
2025-11-05 12:22:03,915 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) inblock: 0
2025-11-05 12:22:03,915 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) isrss: 0
2025-11-05 12:22:03,915 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) ixrss: 0
2025-11-05 12:22:03,916 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) majflt: 0
2025-11-05 12:22:03,916 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) maxrss: 575756
2025-11-05 12:22:03,916 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) minflt: 51583
2025-11-05 12:22:03,916 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) msgrcv: 0
2025-11-05 12:22:03,917 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) msgsnd: 0
2025-11-05 12:22:03,917 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) nivcsw: 83
2025-11-05 12:22:03,917 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) nsignals: 0
2025-11-05 12:22:03,917 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) nswap: 0
2025-11-05 12:22:03,918 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) nvcsw: 605
2025-11-05 12:22:03,918 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) oublock: 8
2025-11-05 12:22:03,918 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) stime: 0.298857
2025-11-05 12:22:03,919 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) utime: 0.5528259999999999
2025-11-05 12:22:03,919 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) wtime: 0.896
2025-11-05 12:22:03,919 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-05 12:22:03,919 - DEBUG - ya.test - _save_outputs: Command (pid 2473332) errors:

2025-11-05 12:22:03,921 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/c688/002802/ydb/apps/ydbd/ydbd
2025-11-05 12:22:03,921 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/c688/002802/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:16411', '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:03,923 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-05 12:22:03,923 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-05 12:22:03,924 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/c688/002802/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:16411', '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:03,925 - DEBUG - ya.test - execute: Command pid: 2473417
2025-11-05 12:22:04,756 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) rc: 0
2025-11-05 12:22:04,757 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) elapsed time (sec): 0.8297011852264404
2025-11-05 12:22:04,757 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) idrss: 0
2025-11-05 12:22:04,757 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) inblock: 0
2025-11-05 12:22:04,757 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) isrss: 0
2025-11-05 12:22:04,758 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) ixrss: 0
2025-11-05 12:22:04,758 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) majflt: 0
2025-11-05 12:22:04,758 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) maxrss: 576768
2025-11-05 12:22:04,758 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) minflt: 51590
2025-11-05 12:22:04,759 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) msgrcv: 0
2025-11-05 12:22:04,759 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) msgsnd: 0
2025-11-05 12:22:04,759 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) nivcsw: 63
2025-11-05 12:22:04,759 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) nsignals: 0
2025-11-05 12:22:04,760 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) nswap: 0
2025-11-05 12:22:04,760 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) nvcsw: 568
2025-11-05 12:22:04,760 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) oublock: 8
2025-11-05 12:22:04,761 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) stime: 0.226675
2025-11-05 12:22:04,761 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) utime: 0.546387
2025-11-05 12:22:04,761 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) wtime: 0.831
2025-11-05 12:22:04,763 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-05 12:22:04,764 - DEBUG - ya.test - _save_outputs: Command (pid 2473417) errors:

2025-11-05 12:22:04,764 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-05 12:22:04,817 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-05 12:22:04,850 - 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:04,854 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-05 12:22:04,855 - 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:04,875 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-05 12:22:04,876 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, f296599d-57db-42b2-be32-327d74429a90, localhost:16411): creating call state
2025-11-05 12:22:04,876 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, f296599d-57db-42b2-be32-327d74429a90, localhost:16411): request = { database: "/Root" }
2025-11-05 12:22:04,884 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, f296599d-57db-42b2-be32-327d74429a90, localhost:16411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-anwhr6cmha.auto.internal" port: 16411 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:04,885 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-anwhr6cmha.auto.internal:16411, location 1, ssl: False>]>
2025-11-05 12:22:04,885 - 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:04,897 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:16411
2025-11-05 12:22:04,898 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-05 12:22:04,899 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, eadf7bc5-0b85-4622-ab22-b2a8092644f7, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:04,900 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, eadf7bc5-0b85-4622-ab22-b2a8092644f7, ghrun-anwhr6cmha.auto.internal:16411): request = {  }
2025-11-05 12:22:04,908 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, eadf7bc5-0b85-4622-ab22-b2a8092644f7, ghrun-anwhr6cmha.auto.internal:16411): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" node_id: 1 }
2025-11-05 12:22:04,909 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 2d43d1ee-eb4f-44f6-bd0d-5357f2f073f7, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:04,910 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 2d43d1ee-eb4f-44f6-bd0d-5357f2f073f7, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" }
2025-11-05 12:22:04,913 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 2d43d1ee-eb4f-44f6-bd0d-5357f2f073f7, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:04,916 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:04,917 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, de364be3-2317-4e8f-b7d1-fd8edd16e9b2, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:04,918 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, de364be3-2317-4e8f-b7d1-fd8edd16e9b2, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:04,920 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, de364be3-2317-4e8f-b7d1-fd8edd16e9b2, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:07,179 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:07,179 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-05 12:22:07,180 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:07,181 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d0a7bdf0-e56b-4944-9cea-d6b0070bc6f0, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:07,182 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d0a7bdf0-e56b-4944-9cea-d6b0070bc6f0, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:07,183 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d0a7bdf0-e56b-4944-9cea-d6b0070bc6f0, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:08,126 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:08,127 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:08,128 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 70679763-41de-4e47-862f-695d92971c87, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:08,129 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 70679763-41de-4e47-862f-695d92971c87, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:08,130 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 70679763-41de-4e47-862f-695d92971c87, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:09,256 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:09,257 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:09,258 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 93338273-b781-407b-ab43-3979e041e3a8, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:09,259 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 93338273-b781-407b-ab43-3979e041e3a8, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:09,260 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 93338273-b781-407b-ab43-3979e041e3a8, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:10,022 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:10,023 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:10,023 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6763f2cd-98ec-4d6c-b664-233b5d21560f, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:10,024 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6763f2cd-98ec-4d6c-b664-233b5d21560f, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:10,027 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6763f2cd-98ec-4d6c-b664-233b5d21560f, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:11,458 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:11,459 - 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:11,459 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:11,460 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 644fab13-1c25-40ce-8340-f485a5be31bd, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:11,461 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 644fab13-1c25-40ce-8340-f485a5be31bd, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:11,462 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 644fab13-1c25-40ce-8340-f485a5be31bd, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:12,355 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:12,356 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:12,356 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 18894519-f0b7-4459-991e-d97878487298, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:12,357 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 18894519-f0b7-4459-991e-d97878487298, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:12,359 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 18894519-f0b7-4459-991e-d97878487298, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:12,563 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:12,564 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:12,565 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b15e14ad-4ac4-4f2f-8aa6-e2e57a339dde, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:12,565 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b15e14ad-4ac4-4f2f-8aa6-e2e57a339dde, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:12,567 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b15e14ad-4ac4-4f2f-8aa6-e2e57a339dde, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:12,888 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:12,889 - 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:12,889 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:12,890 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 50206229-bf89-4e71-9280-8aaaa08f8ecf, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:12,891 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 50206229-bf89-4e71-9280-8aaaa08f8ecf, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:12,893 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 50206229-bf89-4e71-9280-8aaaa08f8ecf, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:13,866 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:13,867 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:13,867 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 42e1ef93-40c1-4aa7-8db2-c835bc180266, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:13,868 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 42e1ef93-40c1-4aa7-8db2-c835bc180266, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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,871 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 42e1ef93-40c1-4aa7-8db2-c835bc180266, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:14,148 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:14,149 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:14,150 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dbcabfb8-1be2-4d38-b443-cf899c8117fb, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:14,150 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dbcabfb8-1be2-4d38-b443-cf899c8117fb, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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,152 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dbcabfb8-1be2-4d38-b443-cf899c8117fb, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:14,639 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:14,640 - 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:14,640 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:14,641 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6c0fdc67-39a5-48c3-9943-ac05337e3768, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:14,642 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6c0fdc67-39a5-48c3-9943-ac05337e3768, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:14,644 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6c0fdc67-39a5-48c3-9943-ac05337e3768, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:15,574 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:15,575 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:15,576 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 753f1e4f-889a-462e-b137-6637237f9fd1, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:15,577 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 753f1e4f-889a-462e-b137-6637237f9fd1, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:15,580 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 753f1e4f-889a-462e-b137-6637237f9fd1, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:15,917 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:15,918 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:15,919 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 98cb3a0b-e276-4d31-9d6b-8de9dc3427cb, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:15,920 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 98cb3a0b-e276-4d31-9d6b-8de9dc3427cb, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:15,921 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 98cb3a0b-e276-4d31-9d6b-8de9dc3427cb, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:16,907 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:16,908 - 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:16,908 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:16,909 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b8bf62e8-c36f-43e7-b92c-b21fbf2cddcc, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:16,910 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b8bf62e8-c36f-43e7-b92c-b21fbf2cddcc, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:16,912 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b8bf62e8-c36f-43e7-b92c-b21fbf2cddcc, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:17,851 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:17,851 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:17,852 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 515724dd-4891-425d-8026-1fdb3b053ee8, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:17,853 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 515724dd-4891-425d-8026-1fdb3b053ee8, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:17,855 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 515724dd-4891-425d-8026-1fdb3b053ee8, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:18,089 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:18,090 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:18,091 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8f9d51ee-8256-4fdd-bfd2-cf3b8646697b, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:18,092 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8f9d51ee-8256-4fdd-bfd2-cf3b8646697b, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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,093 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8f9d51ee-8256-4fdd-bfd2-cf3b8646697b, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:18,504 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:18,505 - 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:18,505 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:18,506 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 353300d7-c81f-4028-ab36-1ecbdd1c5aa2, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:18,507 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 353300d7-c81f-4028-ab36-1ecbdd1c5aa2, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:18,508 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 353300d7-c81f-4028-ab36-1ecbdd1c5aa2, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:19,450 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:19,451 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:19,452 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1280efea-87d6-4384-99aa-bc5dc4307047, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:19,453 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1280efea-87d6-4384-99aa-bc5dc4307047, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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,454 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1280efea-87d6-4384-99aa-bc5dc4307047, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:19,671 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:19,672 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:19,673 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 81445a6d-461e-40cc-8785-37b89d3156fd, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:19,674 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 81445a6d-461e-40cc-8785-37b89d3156fd, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:19,676 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 81445a6d-461e-40cc-8785-37b89d3156fd, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:20,182 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:20,183 - 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:20,184 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:20,185 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bdb62a16-d188-4411-8b66-c806c8c44fcd, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:20,186 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bdb62a16-d188-4411-8b66-c806c8c44fcd, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:20,188 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bdb62a16-d188-4411-8b66-c806c8c44fcd, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:21,092 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:21,093 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:21,094 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2195c112-7f82-40f9-a992-0c5b276e7a96, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:21,095 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2195c112-7f82-40f9-a992-0c5b276e7a96, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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,096 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2195c112-7f82-40f9-a992-0c5b276e7a96, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:21,382 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:21,383 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:21,384 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 08eab38c-6a67-40a0-b426-f676090169aa, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:21,385 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 08eab38c-6a67-40a0-b426-f676090169aa, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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,394 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 08eab38c-6a67-40a0-b426-f676090169aa, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:21,904 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:21,905 - 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:21,905 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:21,906 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d4302320-3674-46f6-a24b-b2505c9e5856, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:21,907 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d4302320-3674-46f6-a24b-b2505c9e5856, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:21,909 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d4302320-3674-46f6-a24b-b2505c9e5856, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:22,935 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:22,936 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:22,936 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a7f6c14f-3e4a-4203-bb0a-eb75808a80fe, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:22,938 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a7f6c14f-3e4a-4203-bb0a-eb75808a80fe, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:22,939 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a7f6c14f-3e4a-4203-bb0a-eb75808a80fe, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:23,179 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:23,180 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:23,181 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c0d97408-852e-476e-a5bc-d21f3b344f72, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:23,182 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c0d97408-852e-476e-a5bc-d21f3b344f72, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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,188 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c0d97408-852e-476e-a5bc-d21f3b344f72, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:23,715 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:23,716 - 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:23,716 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:23,717 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ca81ec27-da6e-49b2-9343-5c710cf43b96, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:23,718 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ca81ec27-da6e-49b2-9343-5c710cf43b96, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:23,719 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ca81ec27-da6e-49b2-9343-5c710cf43b96, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:24,728 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:24,729 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:24,730 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 687b062e-19f8-4e9e-995f-1f7d4871ecb5, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:24,730 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 687b062e-19f8-4e9e-995f-1f7d4871ecb5, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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,732 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 687b062e-19f8-4e9e-995f-1f7d4871ecb5, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:25,004 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:25,005 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:25,006 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2e96250e-fa40-4eac-9f78-9681082fe396, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:25,007 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2e96250e-fa40-4eac-9f78-9681082fe396, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:25,009 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2e96250e-fa40-4eac-9f78-9681082fe396, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:25,517 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:25,517 - 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:25,518 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:25,519 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a2bb4dda-4f0e-4f40-aa65-74958db681e8, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:25,520 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a2bb4dda-4f0e-4f40-aa65-74958db681e8, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:25,521 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a2bb4dda-4f0e-4f40-aa65-74958db681e8, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:26,502 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:26,503 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:26,503 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0f06d8a4-9aab-4dc6-bc90-d1e69c3dcb4d, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:26,504 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0f06d8a4-9aab-4dc6-bc90-d1e69c3dcb4d, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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,506 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0f06d8a4-9aab-4dc6-bc90-d1e69c3dcb4d, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:26,791 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:26,792 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:26,792 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f626df73-3d58-4997-ad1a-55d5b0e6fd3c, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:26,793 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f626df73-3d58-4997-ad1a-55d5b0e6fd3c, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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,799 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f626df73-3d58-4997-ad1a-55d5b0e6fd3c, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:27,392 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:27,393 - 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:27,393 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:27,394 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 744568f4-aa32-478a-bc06-00801146c389, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:27,395 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 744568f4-aa32-478a-bc06-00801146c389, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:27,396 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 744568f4-aa32-478a-bc06-00801146c389, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:28,295 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:28,296 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:28,297 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 462d327e-aa8d-4f47-a037-30f226358ba9, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:28,297 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 462d327e-aa8d-4f47-a037-30f226358ba9, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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,299 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 462d327e-aa8d-4f47-a037-30f226358ba9, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:28,547 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:28,548 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:28,549 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3facee99-2e0b-4945-8d17-d73168116126, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:28,550 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3facee99-2e0b-4945-8d17-d73168116126, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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,551 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3facee99-2e0b-4945-8d17-d73168116126, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:28,789 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:28,790 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:28,790 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 86282d46-90ba-4c43-9007-8b1739cfb9a6, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:28,791 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 86282d46-90ba-4c43-9007-8b1739cfb9a6, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:28,792 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 86282d46-90ba-4c43-9007-8b1739cfb9a6, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:32,551 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:42,607 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:42,608 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9aa40550-ebc3-40b0-b66e-4fe649a3cd9a, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:42,609 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9aa40550-ebc3-40b0-b66e-4fe649a3cd9a, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:42,610 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9aa40550-ebc3-40b0-b66e-4fe649a3cd9a, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:45,878 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:45,917 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-05 12:22:45,919 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:22:45,919 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2c4cc265-87e7-4ac5-b345-aa51955634f8, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:22:45,920 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2c4cc265-87e7-4ac5-b345-aa51955634f8, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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,922 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2c4cc265-87e7-4ac5-b345-aa51955634f8, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:50,229 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:00,267 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:00,268 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 138935d0-0bc6-4d3a-9485-c0805a6f3b97, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:00,269 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 138935d0-0bc6-4d3a-9485-c0805a6f3b97, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:00,272 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 138935d0-0bc6-4d3a-9485-c0805a6f3b97, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:06,300 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:06,358 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-05 12:23:06,359 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:06,360 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 73354002-b076-46ab-984f-c476c47ce106, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:06,364 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 73354002-b076-46ab-984f-c476c47ce106, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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:06,366 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 73354002-b076-46ab-984f-c476c47ce106, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:08,078 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:08,081 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[lz4_compression-COMPRESSION = "lz4"])
2025-11-05 12:23:08,082 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:08,083 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 250099be-a18d-41a5-a54e-9a36115364e0, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:08,084 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 250099be-a18d-41a5-a54e-9a36115364e0, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/lz4_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"lz4\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:08,096 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 250099be-a18d-41a5-a54e-9a36115364e0, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:13,535 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:13,535 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/lz4_compression created
2025-11-05 12:23:13,536 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:13,537 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f4709067-f813-4959-b2ec-b169ffa57135, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:13,537 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f4709067-f813-4959-b2ec-b169ffa57135, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/lz4_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:13,541 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f4709067-f813-4959-b2ec-b169ffa57135, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:14,892 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:14,893 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:14,894 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9dbb35bd-db8a-4c2a-87b6-97ace0f72da2, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:14,895 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9dbb35bd-db8a-4c2a-87b6-97ace0f72da2, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:14,903 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9dbb35bd-db8a-4c2a-87b6-97ace0f72da2, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:16,787 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:16,788 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:16,788 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1f4e8650-80c4-42b4-8515-79f8d0204d90, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:16,790 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1f4e8650-80c4-42b4-8515-79f8d0204d90, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:16,791 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1f4e8650-80c4-42b4-8515-79f8d0204d90, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:18,335 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:18,336 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:18,337 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7865f808-f3c3-43fd-8af5-849842fa8d67, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:18,337 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7865f808-f3c3-43fd-8af5-849842fa8d67, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_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:18,339 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7865f808-f3c3-43fd-8af5-849842fa8d67, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:21,095 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:21,096 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 872992, 'BlobCount': 128}}
2025-11-05 12:23:21,096 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:21,097 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f1f35d93-ad22-4865-b91e-5559e7464122, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:21,098 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f1f35d93-ad22-4865-b91e-5559e7464122, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:21,107 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f1f35d93-ad22-4865-b91e-5559e7464122, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:23,469 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:23,470 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:23,470 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b00c4192-b33d-4918-bd94-57b956624543, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:23,471 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b00c4192-b33d-4918-bd94-57b956624543, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:23,473 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b00c4192-b33d-4918-bd94-57b956624543, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:24,392 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:24,393 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:24,393 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8db286d1-bd7f-4b71-86eb-d1b500d6354a, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:24,397 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8db286d1-bd7f-4b71-86eb-d1b500d6354a, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_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:24,399 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8db286d1-bd7f-4b71-86eb-d1b500d6354a, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:25,503 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:25,503 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 1747088, 'BlobCount': 256}}
2025-11-05 12:23:25,504 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:25,505 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8d1cddb9-5df5-4965-9079-f9b711e94fdf, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:25,506 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8d1cddb9-5df5-4965-9079-f9b711e94fdf, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:25,511 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8d1cddb9-5df5-4965-9079-f9b711e94fdf, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:27,063 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:27,064 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:27,064 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 38ec3444-8d34-4b8e-b456-399824f33945, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:27,065 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 38ec3444-8d34-4b8e-b456-399824f33945, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:27,067 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 38ec3444-8d34-4b8e-b456-399824f33945, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:27,528 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:27,529 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:27,530 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8738c733-fbcc-479b-a2a2-d02281b42ac6, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:27,531 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8738c733-fbcc-479b-a2a2-d02281b42ac6, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_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:27,532 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8738c733-fbcc-479b-a2a2-d02281b42ac6, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:28,331 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:28,332 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 2620992, 'BlobCount': 384}}
2025-11-05 12:23:28,332 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:28,333 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3ff87c90-e435-42f3-afbe-cdef5c5bba59, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:28,334 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3ff87c90-e435-42f3-afbe-cdef5c5bba59, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:28,337 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3ff87c90-e435-42f3-afbe-cdef5c5bba59, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:30,070 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:30,070 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:30,071 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4a472ca4-d91e-483a-b2af-ca885a13fe6e, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:30,072 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4a472ca4-d91e-483a-b2af-ca885a13fe6e, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:30,073 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4a472ca4-d91e-483a-b2af-ca885a13fe6e, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:30,690 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:30,691 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:30,692 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c5a24a36-9163-4897-a6e4-7d34b7504c38, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:30,693 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c5a24a36-9163-4897-a6e4-7d34b7504c38, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_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:30,694 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c5a24a36-9163-4897-a6e4-7d34b7504c38, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:31,643 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:31,644 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 3495088, 'BlobCount': 512}}
2025-11-05 12:23:31,644 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:31,645 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 73c39680-2c92-4e68-95c6-3fadedf26ca6, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:31,645 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 73c39680-2c92-4e68-95c6-3fadedf26ca6, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:31,647 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 73c39680-2c92-4e68-95c6-3fadedf26ca6, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:33,718 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:33,718 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:33,719 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e8bba0e9-d394-4f85-a5f7-99924ef703fe, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:33,720 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e8bba0e9-d394-4f85-a5f7-99924ef703fe, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:33,722 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e8bba0e9-d394-4f85-a5f7-99924ef703fe, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:34,465 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:34,466 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:34,467 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7993195b-c394-4ce6-8ba4-d14fdf0943b3, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:34,468 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7993195b-c394-4ce6-8ba4-d14fdf0943b3, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_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:34,470 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7993195b-c394-4ce6-8ba4-d14fdf0943b3, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:35,509 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:35,510 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 4368976, 'BlobCount': 640}}
2025-11-05 12:23:35,511 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:35,512 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8973dbe8-873c-47ca-949e-a69f08c9de15, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:35,513 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8973dbe8-873c-47ca-949e-a69f08c9de15, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:35,516 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8973dbe8-873c-47ca-949e-a69f08c9de15, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:37,341 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:37,342 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:37,343 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bc496057-8e46-4ff0-bf7b-c0205f910148, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:37,343 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bc496057-8e46-4ff0-bf7b-c0205f910148, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:37,345 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bc496057-8e46-4ff0-bf7b-c0205f910148, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:38,287 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:38,288 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:38,289 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e342963-8213-430c-8fb6-f7b0e31ecf49, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:38,290 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e342963-8213-430c-8fb6-f7b0e31ecf49, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_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:38,300 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e342963-8213-430c-8fb6-f7b0e31ecf49, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:39,292 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:39,292 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 5242960, 'BlobCount': 768}}
2025-11-05 12:23:39,293 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:39,294 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b46af9d8-7d91-46e5-ac60-04aec8a61ea5, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:39,299 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b46af9d8-7d91-46e5-ac60-04aec8a61ea5, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:39,301 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b46af9d8-7d91-46e5-ac60-04aec8a61ea5, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:41,031 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:41,031 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:41,032 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4cd8e8bb-22e8-4ea1-b52b-8fb628af48a8, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:41,033 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4cd8e8bb-22e8-4ea1-b52b-8fb628af48a8, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:41,034 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4cd8e8bb-22e8-4ea1-b52b-8fb628af48a8, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:41,619 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:41,620 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:41,621 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dc1896fd-52e9-4a26-b472-03b85dbe4e3e, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:41,622 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dc1896fd-52e9-4a26-b472-03b85dbe4e3e, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_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:41,629 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dc1896fd-52e9-4a26-b472-03b85dbe4e3e, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:43,239 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:43,240 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 6117040, 'BlobCount': 896}}
2025-11-05 12:23:43,240 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:43,241 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e50da14e-8653-42db-b14c-64af3e07902f, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:43,242 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e50da14e-8653-42db-b14c-64af3e07902f, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:43,246 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e50da14e-8653-42db-b14c-64af3e07902f, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:45,199 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:45,200 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:45,200 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 88140b00-5096-41d9-acb4-42a6c983814d, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:45,201 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 88140b00-5096-41d9-acb4-42a6c983814d, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:45,204 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 88140b00-5096-41d9-acb4-42a6c983814d, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:45,743 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:45,744 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:45,745 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d958a9c0-54c0-4d14-81a7-77084dff5d62, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:45,746 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d958a9c0-54c0-4d14-81a7-77084dff5d62, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_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,748 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d958a9c0-54c0-4d14-81a7-77084dff5d62, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:47,043 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:47,044 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 6991184, 'BlobCount': 1024}}
2025-11-05 12:23:47,044 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:47,045 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 876b051f-4265-4396-93ae-dac2f8fa6686, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:47,047 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 876b051f-4265-4396-93ae-dac2f8fa6686, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:47,048 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 876b051f-4265-4396-93ae-dac2f8fa6686, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:49,058 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:49,058 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:49,059 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ded025a7-63bf-43a5-b02a-c39e2a44bfd0, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:49,060 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ded025a7-63bf-43a5-b02a-c39e2a44bfd0, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:49,062 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ded025a7-63bf-43a5-b02a-c39e2a44bfd0, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:49,811 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:49,811 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:49,812 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b5eec471-32b4-4573-a438-137509e70e50, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:49,813 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b5eec471-32b4-4573-a438-137509e70e50, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_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,815 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b5eec471-32b4-4573-a438-137509e70e50, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:51,140 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:51,141 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 7865088, 'BlobCount': 1152}}
2025-11-05 12:23:51,141 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:51,142 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7af81ddc-09e9-43f3-977a-581a01d12a4b, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:51,143 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7af81ddc-09e9-43f3-977a-581a01d12a4b, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:51,145 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7af81ddc-09e9-43f3-977a-581a01d12a4b, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:53,035 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:53,035 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:53,036 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 33253d58-de09-47cb-89b7-95f885424378, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:53,037 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 33253d58-de09-47cb-89b7-95f885424378, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:53,040 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 33253d58-de09-47cb-89b7-95f885424378, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:53,636 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:53,637 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:53,638 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 27396f19-b33a-4673-89ed-b93ec34dee4b, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:53,639 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 27396f19-b33a-4673-89ed-b93ec34dee4b, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_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:53,640 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 27396f19-b33a-4673-89ed-b93ec34dee4b, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:55,220 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:55,221 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 8739264, 'BlobCount': 1280}}
2025-11-05 12:23:55,222 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:55,222 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eadbac94-37b0-486f-85cc-7f2430c8180e, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:55,223 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eadbac94-37b0-486f-85cc-7f2430c8180e, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/lz4_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:55,224 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eadbac94-37b0-486f-85cc-7f2430c8180e, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:57,679 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:57,680 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:57,681 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b92efa07-538c-4b1f-8f66-9a46c9156af0, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:57,682 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b92efa07-538c-4b1f-8f66-9a46c9156af0, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:57,684 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b92efa07-538c-4b1f-8f66-9a46c9156af0, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:58,288 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:58,289 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:58,290 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ca4ef1fe-d3b5-4bef-8cc0-daa7a4a5e509, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:58,291 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ca4ef1fe-d3b5-4bef-8cc0-daa7a4a5e509, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:58,295 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ca4ef1fe-d3b5-4bef-8cc0-daa7a4a5e509, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:58,944 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:58,945 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:23:58,946 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, de258bbb-3cae-4319-bcf7-3a59f3c61183, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:23:58,946 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, de258bbb-3cae-4319-bcf7-3a59f3c61183, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:58,963 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, de258bbb-3cae-4319-bcf7-3a59f3c61183, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:59,899 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-05 12:23:59,899 - 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:59,930 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-05 12:23:59,931 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, b208aa0c-75e1-4ccc-a90f-a3d89e899049, localhost:16411): creating call state
2025-11-05 12:23:59,932 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, b208aa0c-75e1-4ccc-a90f-a3d89e899049, localhost:16411): request = { database: "/Root" }
2025-11-05 12:24:00,265 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, b208aa0c-75e1-4ccc-a90f-a3d89e899049, localhost:16411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-anwhr6cmha.auto.internal" port: 16411 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:24:00,266 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-anwhr6cmha.auto.internal:16411, location 1, ssl: False>]>
2025-11-05 12:24:00,267 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:16411
2025-11-05 12:24:06,948 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:24:17,112 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:24:17,113 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7346c792-9fd2-4107-9acc-3dec5e918f09, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:24:17,114 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7346c792-9fd2-4107-9acc-3dec5e918f09, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:17,116 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7346c792-9fd2-4107-9acc-3dec5e918f09, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:22,024 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:24:32,051 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:24:32,052 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f77d3342-be57-4c96-a7ff-24b05b10e82f, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:24:32,053 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f77d3342-be57-4c96-a7ff-24b05b10e82f, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:32,058 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f77d3342-be57-4c96-a7ff-24b05b10e82f, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:33,182 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:24:43,186 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:24:43,187 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fc14411b-f430-48d8-9b3b-005dbdbb41d9, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:24:43,188 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fc14411b-f430-48d8-9b3b-005dbdbb41d9, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:43,189 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fc14411b-f430-48d8-9b3b-005dbdbb41d9, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:44,368 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:24:44,372 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4258592)
2025-11-05 12:24:44,373 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:24:44,374 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, caa369e1-7259-4f1e-a34f-931059de22e1, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:24:44,374 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, caa369e1-7259-4f1e-a34f-931059de22e1, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:44,377 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, caa369e1-7259-4f1e-a34f-931059de22e1, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:45,033 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:24:45,034 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:24:45,035 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 226db1e2-1525-42e4-a017-5eec1eb7669c, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:24:45,036 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 226db1e2-1525-42e4-a017-5eec1eb7669c, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:45,047 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 226db1e2-1525-42e4-a017-5eec1eb7669c, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:46,229 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:24:56,234 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:24:56,235 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ef569951-ffdc-4c1f-9a5b-dcd47eb6f613, ghrun-anwhr6cmha.auto.internal:16411): creating call state
2025-11-05 12:24:56,236 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ef569951-ffdc-4c1f-9a5b-dcd47eb6f613, ghrun-anwhr6cmha.auto.internal:16411): request = { session_id: "ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:56,237 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ef569951-ffdc-4c1f-9a5b-dcd47eb6f613, ghrun-anwhr6cmha.auto.internal:16411): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:57,457 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEzYzVjODAtYmZkMjJhMDUtZjEwMmE1YmYtZTc3MjFkOTA=
2025-11-05 12:24:57,461 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4258592)
2025-11-05 12:24:57,462 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/lz4_compression` 8092160 / 4258592: 1.900196121159294
2025-11-05 12:24:57,465 - INFO - ya.test - pytest_runtest_teardown: Test teardown
