2025-10-31 14:31:56,529 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 14:31:56,530 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[lz4_compression-COMPRESSION = "lz4"]
2025-10-31 14:31:56,530 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 14:31:56,530 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 14:31:56,533 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-31 14:31:56,534 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-31 14:31:56,534 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/zwqj/0014b3/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-31 14:31:56,546 - DEBUG - ya.test - execute: Command pid: 1050945
2025-10-31 14:31:57,511 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) rc: 0
2025-10-31 14:31:57,511 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) elapsed time (sec): 0.9633646011352539
2025-10-31 14:31:57,512 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) idrss: 0
2025-10-31 14:31:57,512 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) inblock: 0
2025-10-31 14:31:57,512 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) isrss: 0
2025-10-31 14:31:57,513 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) ixrss: 0
2025-10-31 14:31:57,513 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) majflt: 0
2025-10-31 14:31:57,513 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) maxrss: 480204
2025-10-31 14:31:57,514 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) minflt: 45328
2025-10-31 14:31:57,514 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) msgrcv: 0
2025-10-31 14:31:57,514 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) msgsnd: 0
2025-10-31 14:31:57,515 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) nivcsw: 212
2025-10-31 14:31:57,515 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) nsignals: 0
2025-10-31 14:31:57,515 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) nswap: 0
2025-10-31 14:31:57,516 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) nvcsw: 14
2025-10-31 14:31:57,516 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) oublock: 8
2025-10-31 14:31:57,517 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) stime: 0.2445
2025-10-31 14:31:57,518 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) utime: 0.5000359999999999
2025-10-31 14:31:57,518 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) wtime: 0.976
2025-10-31 14:31:57,518 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) output:
Git info:
    Commit: 81e21063d590ae7636d4475c58381f90e065d70f
    Branch: 
    Author: xyliganSereja <matva0509@gmail.com>
    Summary: Merge 4453e1bb1feb0e82a9049e95374936e29fc264d7 into d79725158e2f111172387151aa3fd9735997d399

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-uk32wjuvr4
    Host information: 
        Linux ghrun-uk32wjuvr4 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-10-31 14:31:57,519 - DEBUG - ya.test - _save_outputs: Command (pid 1050945) errors:

2025-10-31 14:31:57,520 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: 81e21063d590ae7636d4475c58381f90e065d70f
    Branch: 
    Author: xyliganSereja <matva0509@gmail.com>
    Summary: Merge 4453e1bb1feb0e82a9049e95374936e29fc264d7 into d79725158e2f111172387151aa3fd9735997d399

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-uk32wjuvr4
    Host information: 
        Linux ghrun-uk32wjuvr4 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-10-31 14:31:57,660 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/zwqj/0014b3/ydb/apps/ydbd/ydbd
2025-10-31 14:31:57,662 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4362
2025-10-31 14:31:57,672 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/zwqj/0014b3/r3tmp/kikimr_cluster_euru9srb
2025-10-31 14:31:57,725 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/zwqj/0014b3/ydb/apps/ydbd/ydbd
2025-10-31 14:31:57,726 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16348
2025-10-31 14:31:57,727 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29871
2025-10-31 14:31:57,728 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/32461
2025-10-31 14:31:57,729 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2747
2025-10-31 14:31:57,730 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/zwqj/0014b3/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-10-31 14:31:57,730 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/zwqj/0014b3/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/zwqj/0014b3/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_imu9h_l2.log --grpc-port=16348 --mon-port=29871 --ic-port=4362
2025-10-31 14:31:57,731 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:16348/1, disk_size 68719476736
2025-10-31 14:31:57,731 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/zwqj/0014b3/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-10-31 14:31:57,732 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/zwqj/0014b3/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/zwqj/0014b3/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_imu9h_l2.log --grpc-port=16348 --mon-port=29871 --ic-port=4362
2025-10-31 14:31:57,734 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/zwqj/0014b3/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/zwqj/0014b3/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/zwqj/0014b3/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_imu9h_l2.log', '--grpc-port=16348', '--mon-port=29871', '--ic-port=4362')' in '/home/runner/.ya/build/build_root/zwqj/0014b3/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-10-31 14:31:57,737 - DEBUG - ya.test - execute: Command pid: 1051269
2025-10-31 14:31:57,738 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:16348/1
2025-10-31 14:31:57,740 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-31 14:31:57,740 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-31 14:31:57,741 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/zwqj/0014b3/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-31 14:31:57,757 - DEBUG - ya.test - execute: Command pid: 1051271
2025-10-31 14:31:58,651 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) rc: 0
2025-10-31 14:31:58,652 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) elapsed time (sec): 0.8926193714141846
2025-10-31 14:31:58,652 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) idrss: 0
2025-10-31 14:31:58,656 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) inblock: 0
2025-10-31 14:31:58,657 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) isrss: 0
2025-10-31 14:31:58,657 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) ixrss: 0
2025-10-31 14:31:58,657 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) majflt: 0
2025-10-31 14:31:58,657 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) maxrss: 481192
2025-10-31 14:31:58,658 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) minflt: 45338
2025-10-31 14:31:58,658 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) msgrcv: 0
2025-10-31 14:31:58,658 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) msgsnd: 0
2025-10-31 14:31:58,659 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) nivcsw: 196
2025-10-31 14:31:58,659 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) nsignals: 0
2025-10-31 14:31:58,659 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) nswap: 0
2025-10-31 14:31:58,660 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) nvcsw: 15
2025-10-31 14:31:58,660 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) oublock: 8
2025-10-31 14:31:58,662 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) stime: 0.21451499999999998
2025-10-31 14:31:58,663 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) utime: 0.539206
2025-10-31 14:31:58,663 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) wtime: 0.91
2025-10-31 14:31:58,664 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) output:
Git info:
    Commit: 81e21063d590ae7636d4475c58381f90e065d70f
    Branch: 
    Author: xyliganSereja <matva0509@gmail.com>
    Summary: Merge 4453e1bb1feb0e82a9049e95374936e29fc264d7 into d79725158e2f111172387151aa3fd9735997d399

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-uk32wjuvr4
    Host information: 
        Linux ghrun-uk32wjuvr4 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-10-31 14:31:58,664 - DEBUG - ya.test - _save_outputs: Command (pid 1051271) errors:

2025-10-31 14:31:58,665 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 81e21063d590ae7636d4475c58381f90e065d70f
    Branch: 
    Author: xyliganSereja <matva0509@gmail.com>
    Summary: Merge 4453e1bb1feb0e82a9049e95374936e29fc264d7 into d79725158e2f111172387151aa3fd9735997d399
2025-10-31 14:32:04,611 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/zwqj/0014b3/ydb/apps/ydbd/ydbd
2025-10-31 14:32:04,611 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/zwqj/0014b3/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:16348', '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: 4362\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-31 14:32:04,613 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-31 14:32:04,614 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-31 14:32:04,614 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/zwqj/0014b3/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:16348', '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: 4362\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-31 14:32:04,625 - DEBUG - ya.test - execute: Command pid: 1053749
2025-10-31 14:32:05,935 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) rc: 0
2025-10-31 14:32:05,936 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) elapsed time (sec): 1.309288501739502
2025-10-31 14:32:05,936 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) idrss: 0
2025-10-31 14:32:05,937 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) inblock: 0
2025-10-31 14:32:05,937 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) isrss: 0
2025-10-31 14:32:05,937 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) ixrss: 0
2025-10-31 14:32:05,937 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) majflt: 0
2025-10-31 14:32:05,938 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) maxrss: 585380
2025-10-31 14:32:05,938 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) minflt: 51704
2025-10-31 14:32:05,938 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) msgrcv: 0
2025-10-31 14:32:05,939 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) msgsnd: 0
2025-10-31 14:32:05,942 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) nivcsw: 269
2025-10-31 14:32:05,942 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) nsignals: 0
2025-10-31 14:32:05,942 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) nswap: 0
2025-10-31 14:32:05,942 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) nvcsw: 490
2025-10-31 14:32:05,943 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) oublock: 8
2025-10-31 14:32:05,943 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) stime: 0.29717499999999997
2025-10-31 14:32:05,943 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) utime: 0.5807939999999999
2025-10-31 14:32:05,944 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) wtime: 1.32
2025-10-31 14:32:05,944 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-31 14:32:05,944 - DEBUG - ya.test - _save_outputs: Command (pid 1053749) errors:

2025-10-31 14:32:05,948 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/zwqj/0014b3/ydb/apps/ydbd/ydbd
2025-10-31 14:32:05,948 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/zwqj/0014b3/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:16348', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-10-31 14:32:05,961 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-31 14:32:05,962 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-31 14:32:05,962 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/zwqj/0014b3/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:16348', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-31 14:32:05,981 - DEBUG - ya.test - execute: Command pid: 1054188
2025-10-31 14:32:07,616 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) rc: 0
2025-10-31 14:32:07,617 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) elapsed time (sec): 1.633619785308838
2025-10-31 14:32:07,617 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) idrss: 0
2025-10-31 14:32:07,618 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) inblock: 0
2025-10-31 14:32:07,618 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) isrss: 0
2025-10-31 14:32:07,618 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) ixrss: 0
2025-10-31 14:32:07,619 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) majflt: 0
2025-10-31 14:32:07,619 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) maxrss: 586224
2025-10-31 14:32:07,619 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) minflt: 51636
2025-10-31 14:32:07,620 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) msgrcv: 0
2025-10-31 14:32:07,620 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) msgsnd: 0
2025-10-31 14:32:07,620 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) nivcsw: 228
2025-10-31 14:32:07,625 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) nsignals: 0
2025-10-31 14:32:07,625 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) nswap: 0
2025-10-31 14:32:07,626 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) nvcsw: 738
2025-10-31 14:32:07,626 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) oublock: 8
2025-10-31 14:32:07,626 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) stime: 0.197161
2025-10-31 14:32:07,627 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) utime: 0.630199
2025-10-31 14:32:07,627 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) wtime: 1.651
2025-10-31 14:32:07,627 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-31 14:32:07,628 - DEBUG - ya.test - _save_outputs: Command (pid 1054188) errors:

2025-10-31 14:32:07,628 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-31 14:32:07,886 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-31 14:32:07,923 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-31 14:32:07,948 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 14:32:07,949 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-31 14:32:07,994 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 14:32:07,995 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 6fb27f82-fe41-49e9-ae4c-8344170dc6df, localhost:16348): creating call state
2025-10-31 14:32:07,996 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 6fb27f82-fe41-49e9-ae4c-8344170dc6df, localhost:16348): request = { database: "/Root" }
2025-10-31 14:32:08,019 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 6fb27f82-fe41-49e9-ae4c-8344170dc6df, localhost:16348): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-uk32wjuvr4.auto.internal" port: 16348 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-10-31 14:32:08,020 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-uk32wjuvr4.auto.internal:16348, location 1, ssl: False>]>
2025-10-31 14:32:08,022 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-31 14:32:08,042 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:16348
2025-10-31 14:32:08,045 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-10-31 14:32:08,047 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a05fa7d2-2aee-47a1-a9f5-49795ebad6ae, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:08,048 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a05fa7d2-2aee-47a1-a9f5-49795ebad6ae, ghrun-uk32wjuvr4.auto.internal:16348): request = {  }
2025-10-31 14:32:08,056 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a05fa7d2-2aee-47a1-a9f5-49795ebad6ae, ghrun-uk32wjuvr4.auto.internal:16348): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" node_id: 1 }
2025-10-31 14:32:08,057 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 479b74f2-86e6-4b69-a447-18098d29f1b4, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:08,058 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 479b74f2-86e6-4b69-a447-18098d29f1b4, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" }
2025-10-31 14:32:08,062 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 479b74f2-86e6-4b69-a447-18098d29f1b4, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:08,076 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:08,078 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ca6e690f-b9cb-4e3e-aafc-f76caa4a78aa, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:08,079 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ca6e690f-b9cb-4e3e-aafc-f76caa4a78aa, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:08,081 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ca6e690f-b9cb-4e3e-aafc-f76caa4a78aa, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:12,861 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:12,862 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-10-31 14:32:12,863 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:12,864 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b81bee14-c4d3-4f5e-ac24-93c8eca09dd4, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:12,865 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b81bee14-c4d3-4f5e-ac24-93c8eca09dd4, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:12,866 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b81bee14-c4d3-4f5e-ac24-93c8eca09dd4, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:14,261 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:14,262 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:14,263 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ef467ec1-3b8f-43b5-84d9-1f9a46f6dbf7, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:14,264 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ef467ec1-3b8f-43b5-84d9-1f9a46f6dbf7, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:14,282 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ef467ec1-3b8f-43b5-84d9-1f9a46f6dbf7, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:15,694 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:15,695 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:15,696 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 36589a39-bec9-476f-81d6-fd3c7becddd8, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:15,697 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 36589a39-bec9-476f-81d6-fd3c7becddd8, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:15,723 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 36589a39-bec9-476f-81d6-fd3c7becddd8, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:17,100 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:17,103 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:17,104 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5d99239f-3d15-4bfe-94ac-dab1fb348a07, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:17,105 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5d99239f-3d15-4bfe-94ac-dab1fb348a07, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:17,106 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5d99239f-3d15-4bfe-94ac-dab1fb348a07, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:18,941 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:18,942 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 1618432, 'BlobCount': 128}}
2025-10-31 14:32:18,943 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:18,944 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 54f5c58e-0bbd-4cc1-8d60-e619abc6e7e4, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:18,961 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 54f5c58e-0bbd-4cc1-8d60-e619abc6e7e4, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:18,973 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 54f5c58e-0bbd-4cc1-8d60-e619abc6e7e4, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:20,333 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:20,334 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:20,335 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8642a19f-edf7-4173-a0b4-43e953643402, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:20,335 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8642a19f-edf7-4173-a0b4-43e953643402, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:20,341 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8642a19f-edf7-4173-a0b4-43e953643402, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:20,948 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:20,949 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:20,950 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9be9b616-6dca-4af4-983e-4286dcb3af88, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:20,951 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9be9b616-6dca-4af4-983e-4286dcb3af88, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:20,955 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9be9b616-6dca-4af4-983e-4286dcb3af88, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:21,702 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:21,703 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 3236864, 'BlobCount': 256}}
2025-10-31 14:32:21,704 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:21,705 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bc240a90-c3f4-48a7-a3dd-a65de09d289b, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:21,706 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bc240a90-c3f4-48a7-a3dd-a65de09d289b, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:21,725 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bc240a90-c3f4-48a7-a3dd-a65de09d289b, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:23,157 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:23,158 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:23,159 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 32bba64d-ef9c-4a34-ab58-8eaaff26480b, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:23,160 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 32bba64d-ef9c-4a34-ab58-8eaaff26480b, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:23,165 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 32bba64d-ef9c-4a34-ab58-8eaaff26480b, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:23,921 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:23,922 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:23,923 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8042f5f6-6e12-44bd-8e48-0ad28c28a195, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:23,924 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8042f5f6-6e12-44bd-8e48-0ad28c28a195, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:23,926 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8042f5f6-6e12-44bd-8e48-0ad28c28a195, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:24,733 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:24,734 - 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-10-31 14:32:24,734 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:24,735 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bb68c613-db6f-46b9-9195-62ace799d1ca, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:24,745 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bb68c613-db6f-46b9-9195-62ace799d1ca, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:24,747 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bb68c613-db6f-46b9-9195-62ace799d1ca, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:26,056 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:26,057 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:26,058 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8a73d2e4-f667-46b9-9638-8df5ba697729, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:26,059 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8a73d2e4-f667-46b9-9638-8df5ba697729, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:26,061 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8a73d2e4-f667-46b9-9638-8df5ba697729, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:26,671 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:26,672 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:26,673 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5d43ff2e-859a-4f43-8a20-e18e1fa1e0c9, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:26,674 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5d43ff2e-859a-4f43-8a20-e18e1fa1e0c9, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:26,675 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5d43ff2e-859a-4f43-8a20-e18e1fa1e0c9, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:27,575 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:27,576 - 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-10-31 14:32:27,577 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:27,578 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6af3ccb9-57f4-4469-b472-ad24d013701e, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:27,579 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6af3ccb9-57f4-4469-b472-ad24d013701e, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:27,581 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6af3ccb9-57f4-4469-b472-ad24d013701e, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:28,697 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:28,698 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:28,699 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bf5b432c-349b-4939-941f-511c00176a2e, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:28,700 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bf5b432c-349b-4939-941f-511c00176a2e, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:28,701 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bf5b432c-349b-4939-941f-511c00176a2e, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:29,018 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:29,019 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:29,020 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 93892481-6b70-4675-ba57-416e88821a07, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:29,021 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 93892481-6b70-4675-ba57-416e88821a07, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:29,022 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 93892481-6b70-4675-ba57-416e88821a07, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:29,739 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:29,739 - 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-10-31 14:32:29,740 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:29,741 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e464f532-cdfd-4ebc-a416-a08bbd983fec, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:29,742 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e464f532-cdfd-4ebc-a416-a08bbd983fec, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:29,750 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e464f532-cdfd-4ebc-a416-a08bbd983fec, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:31,133 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:31,133 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:31,134 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d5e86654-9e09-47a9-96a8-fb8aa8b4dba5, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:31,135 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d5e86654-9e09-47a9-96a8-fb8aa8b4dba5, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:31,137 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d5e86654-9e09-47a9-96a8-fb8aa8b4dba5, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:31,637 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:31,638 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:31,639 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 27dce0d9-35f1-4377-8bd3-5064d755966d, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:31,640 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 27dce0d9-35f1-4377-8bd3-5064d755966d, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:31,646 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 27dce0d9-35f1-4377-8bd3-5064d755966d, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:32,319 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:32,320 - 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-10-31 14:32:32,321 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:32,321 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d6dee90-0e51-4b0a-8d8e-6e58e03e3f02, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:32,323 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d6dee90-0e51-4b0a-8d8e-6e58e03e3f02, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:32,324 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d6dee90-0e51-4b0a-8d8e-6e58e03e3f02, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:33,779 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:33,780 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:33,781 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 73435948-4074-41fa-97aa-c64407b409db, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:33,782 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 73435948-4074-41fa-97aa-c64407b409db, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:33,784 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 73435948-4074-41fa-97aa-c64407b409db, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:34,362 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:34,363 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:34,364 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 11cef4f6-debb-4db0-b09d-e978bb1021f7, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:34,365 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 11cef4f6-debb-4db0-b09d-e978bb1021f7, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:34,368 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 11cef4f6-debb-4db0-b09d-e978bb1021f7, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:35,242 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:35,243 - 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-10-31 14:32:35,244 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:35,245 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 58155353-0d14-464d-bd17-ff2bba6b9c29, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:35,246 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 58155353-0d14-464d-bd17-ff2bba6b9c29, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:35,248 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 58155353-0d14-464d-bd17-ff2bba6b9c29, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:36,792 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:36,793 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:36,794 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 87bfd6f8-fd8a-4698-b63d-52e8bd8b019c, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:36,795 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 87bfd6f8-fd8a-4698-b63d-52e8bd8b019c, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:36,796 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 87bfd6f8-fd8a-4698-b63d-52e8bd8b019c, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:37,537 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:37,538 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:37,538 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d45de5e9-5e93-47d4-bc5e-ff3a97cc0e78, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:37,539 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d45de5e9-5e93-47d4-bc5e-ff3a97cc0e78, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:37,542 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d45de5e9-5e93-47d4-bc5e-ff3a97cc0e78, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:38,951 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:38,952 - 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-10-31 14:32:38,953 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:38,954 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 871554a8-4bd8-4c4d-8956-9313e4e3601d, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:38,955 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 871554a8-4bd8-4c4d-8956-9313e4e3601d, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:38,966 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 871554a8-4bd8-4c4d-8956-9313e4e3601d, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:40,561 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:40,562 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:40,563 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5a1edb0b-d084-45cd-ba5f-a24cf1b8d9ce, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:40,563 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5a1edb0b-d084-45cd-ba5f-a24cf1b8d9ce, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:40,584 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5a1edb0b-d084-45cd-ba5f-a24cf1b8d9ce, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:41,162 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:41,163 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:41,164 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 575f266f-4c19-4ba7-b889-e988a57dd4d1, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:41,165 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 575f266f-4c19-4ba7-b889-e988a57dd4d1, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:41,186 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 575f266f-4c19-4ba7-b889-e988a57dd4d1, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:42,239 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:42,240 - 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-10-31 14:32:42,241 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:42,241 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5997ed82-34c0-4bb1-badb-9633e2801405, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:42,247 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5997ed82-34c0-4bb1-badb-9633e2801405, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:42,249 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5997ed82-34c0-4bb1-badb-9633e2801405, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:43,585 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:43,586 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:43,587 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0593d2a4-c4c5-4b44-afc7-bd3e18a1296e, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:43,588 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0593d2a4-c4c5-4b44-afc7-bd3e18a1296e, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:43,598 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0593d2a4-c4c5-4b44-afc7-bd3e18a1296e, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:44,193 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:44,194 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:44,195 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bd0f3596-9b3c-4e3b-85c4-420a011a3714, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:44,196 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bd0f3596-9b3c-4e3b-85c4-420a011a3714, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:44,213 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bd0f3596-9b3c-4e3b-85c4-420a011a3714, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:45,182 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:45,183 - 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-10-31 14:32:45,184 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:45,185 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7750482c-4567-44b2-8fc3-6e11ebb376ac, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:45,186 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7750482c-4567-44b2-8fc3-6e11ebb376ac, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:45,195 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7750482c-4567-44b2-8fc3-6e11ebb376ac, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:46,814 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:46,815 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:46,816 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c6a42123-83f6-4e73-bd2d-bb41e2b5586f, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:46,817 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c6a42123-83f6-4e73-bd2d-bb41e2b5586f, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:46,819 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c6a42123-83f6-4e73-bd2d-bb41e2b5586f, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:47,238 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:47,239 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:47,240 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 731db888-3d9b-4a58-bc96-15d8011cb622, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:47,241 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 731db888-3d9b-4a58-bc96-15d8011cb622, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:47,253 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 731db888-3d9b-4a58-bc96-15d8011cb622, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:47,613 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:47,614 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:32:47,615 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f1797008-0e4a-4c4e-86fe-51f694e57c96, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:32:47,616 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f1797008-0e4a-4c4e-86fe-51f694e57c96, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:32:47,618 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f1797008-0e4a-4c4e-86fe-51f694e57c96, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:53,952 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:04,048 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:04,049 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f4707636-009f-43ba-9099-d969bbf452c5, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:33:04,050 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f4707636-009f-43ba-9099-d969bbf452c5, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:33:04,052 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f4707636-009f-43ba-9099-d969bbf452c5, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:09,698 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:09,736 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-10-31 14:33:09,737 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:09,738 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, efaf421b-ab6e-4460-92f6-c4cabaf8043f, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:33:09,739 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, efaf421b-ab6e-4460-92f6-c4cabaf8043f, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:33:09,740 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, efaf421b-ab6e-4460-92f6-c4cabaf8043f, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:15,894 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:21,405 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 14:33:21,406 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-31 14:33:21,453 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 14:33:21,454 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, c6a04d79-28d4-4923-98a3-e0c469127bad, localhost:16348): creating call state
2025-10-31 14:33:21,455 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, c6a04d79-28d4-4923-98a3-e0c469127bad, localhost:16348): request = { database: "/Root" }
2025-10-31 14:33:21,470 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, c6a04d79-28d4-4923-98a3-e0c469127bad, localhost:16348): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-uk32wjuvr4.auto.internal" port: 16348 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-10-31 14:33:21,472 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-uk32wjuvr4.auto.internal:16348, location 1, ssl: False>]>
2025-10-31 14:33:21,474 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:16348
2025-10-31 14:33:25,981 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:25,982 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 893b6e7a-7171-412c-9130-7e100b745b1e, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:33:25,983 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 893b6e7a-7171-412c-9130-7e100b745b1e, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:33:25,986 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 893b6e7a-7171-412c-9130-7e100b745b1e, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:28,673 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:38,689 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:38,690 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5187bc07-4124-4fa4-9e64-43535fef5045, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:33:38,691 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5187bc07-4124-4fa4-9e64-43535fef5045, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:33:38,695 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5187bc07-4124-4fa4-9e64-43535fef5045, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:40,116 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:50,123 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:50,124 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 265d47a7-2c24-417c-8926-7869292fb801, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:33:50,125 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 265d47a7-2c24-417c-8926-7869292fb801, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:33:50,126 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 265d47a7-2c24-417c-8926-7869292fb801, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:51,325 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:51,330 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-10-31 14:33:51,331 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:51,332 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 53ba7f61-1dbf-4e9c-8f2a-01ac97f6e7da, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:33:51,334 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 53ba7f61-1dbf-4e9c-8f2a-01ac97f6e7da, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:33:51,335 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 53ba7f61-1dbf-4e9c-8f2a-01ac97f6e7da, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:51,766 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:51,771 - 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-10-31 14:33:51,772 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:51,784 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1ab7b62b-8b52-47ca-9cbd-5f6718d8cfb2, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:33:51,786 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1ab7b62b-8b52-47ca-9cbd-5f6718d8cfb2, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:33:51,787 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1ab7b62b-8b52-47ca-9cbd-5f6718d8cfb2, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:56,320 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:56,321 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/lz4_compression created
2025-10-31 14:33:56,322 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:56,322 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cff33ef3-ac55-4e76-8413-a9ec6e17f37c, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:33:56,323 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cff33ef3-ac55-4e76-8413-a9ec6e17f37c, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:33:56,325 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cff33ef3-ac55-4e76-8413-a9ec6e17f37c, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:57,785 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:57,786 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:57,787 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 114cb4ac-4887-4b26-8832-758398e442f2, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:33:57,788 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 114cb4ac-4887-4b26-8832-758398e442f2, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:33:57,789 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 114cb4ac-4887-4b26-8832-758398e442f2, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:59,307 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:59,308 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:33:59,309 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 63629f54-7c54-47ee-a9db-0edfc72af5e7, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:33:59,310 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 63629f54-7c54-47ee-a9db-0edfc72af5e7, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:33:59,311 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 63629f54-7c54-47ee-a9db-0edfc72af5e7, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:00,351 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:00,352 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:00,353 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b55c9263-3dac-443e-b7b9-08d1052e5f59, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:00,354 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b55c9263-3dac-443e-b7b9-08d1052e5f59, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:00,355 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b55c9263-3dac-443e-b7b9-08d1052e5f59, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:02,582 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:02,583 - 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-10-31 14:34:02,584 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:02,585 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3a2bed29-0b15-4157-974a-fa385aaca1d3, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:02,586 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3a2bed29-0b15-4157-974a-fa385aaca1d3, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:02,597 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3a2bed29-0b15-4157-974a-fa385aaca1d3, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:04,212 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:04,212 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:04,213 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e6dadbe0-7b91-453e-a92e-4b7edd728199, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:04,214 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e6dadbe0-7b91-453e-a92e-4b7edd728199, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:04,215 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e6dadbe0-7b91-453e-a92e-4b7edd728199, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:04,584 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:04,585 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:04,586 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 036e15df-d7db-4e96-96ee-e58a13bc82a3, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:04,587 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 036e15df-d7db-4e96-96ee-e58a13bc82a3, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:04,589 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 036e15df-d7db-4e96-96ee-e58a13bc82a3, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:05,223 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:05,223 - 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-10-31 14:34:05,224 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:05,225 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f0bcd678-13a0-4bcf-8a30-2543e67f5d71, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:05,226 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f0bcd678-13a0-4bcf-8a30-2543e67f5d71, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:05,228 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f0bcd678-13a0-4bcf-8a30-2543e67f5d71, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:06,990 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:06,991 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:06,992 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fc2d1523-aeb3-4111-8666-218db985a554, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:07,024 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fc2d1523-aeb3-4111-8666-218db985a554, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:07,026 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fc2d1523-aeb3-4111-8666-218db985a554, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:07,740 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:07,741 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:07,742 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a1b76981-6778-43cb-bb78-ee89253654ae, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:07,743 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a1b76981-6778-43cb-bb78-ee89253654ae, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:07,746 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a1b76981-6778-43cb-bb78-ee89253654ae, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:08,261 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:08,262 - 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-10-31 14:34:08,262 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:08,263 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 09fe49c2-3bc1-4291-bb6e-6595b3b6f7c2, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:08,264 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 09fe49c2-3bc1-4291-bb6e-6595b3b6f7c2, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:08,267 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 09fe49c2-3bc1-4291-bb6e-6595b3b6f7c2, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:09,570 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:09,571 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:09,572 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d94f936a-b8d0-427b-b00c-67349a72d98f, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:09,573 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d94f936a-b8d0-427b-b00c-67349a72d98f, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:09,575 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d94f936a-b8d0-427b-b00c-67349a72d98f, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:09,915 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:09,916 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:09,917 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ab5959a6-e827-4d7d-9caa-3e2941bf58ce, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:09,918 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ab5959a6-e827-4d7d-9caa-3e2941bf58ce, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:09,929 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ab5959a6-e827-4d7d-9caa-3e2941bf58ce, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:10,869 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:10,869 - 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-10-31 14:34:10,870 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:10,871 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ca4a355a-34b9-41ea-a2b7-1ffb3e542285, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:10,872 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ca4a355a-34b9-41ea-a2b7-1ffb3e542285, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:10,874 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ca4a355a-34b9-41ea-a2b7-1ffb3e542285, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:12,553 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:12,554 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:12,555 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c8c7ad56-a4c1-446c-85ba-6a5a036063d1, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:12,556 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c8c7ad56-a4c1-446c-85ba-6a5a036063d1, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:12,558 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c8c7ad56-a4c1-446c-85ba-6a5a036063d1, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:13,249 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:13,250 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:13,251 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c142746c-a925-4a57-8deb-80d12ad76d2d, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:13,252 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c142746c-a925-4a57-8deb-80d12ad76d2d, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:13,254 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c142746c-a925-4a57-8deb-80d12ad76d2d, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:14,287 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:14,288 - 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-10-31 14:34:14,288 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:14,289 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5aa429c1-c7b6-4747-8188-352cae882ba9, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:14,290 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5aa429c1-c7b6-4747-8188-352cae882ba9, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:14,303 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5aa429c1-c7b6-4747-8188-352cae882ba9, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:15,917 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:15,918 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:15,919 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 88e0ee09-be70-4f63-b8d1-5638335ba3b3, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:15,919 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 88e0ee09-be70-4f63-b8d1-5638335ba3b3, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:15,922 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 88e0ee09-be70-4f63-b8d1-5638335ba3b3, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:16,285 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:16,286 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:16,287 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 15085d64-4774-485e-bbc6-0593c7859d18, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:16,288 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 15085d64-4774-485e-bbc6-0593c7859d18, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:16,295 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 15085d64-4774-485e-bbc6-0593c7859d18, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:17,202 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:17,204 - 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-10-31 14:34:17,204 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:17,205 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 164ac8df-6f9e-48e2-95d1-c562114a5c28, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:17,206 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 164ac8df-6f9e-48e2-95d1-c562114a5c28, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:17,213 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 164ac8df-6f9e-48e2-95d1-c562114a5c28, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:18,608 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:18,609 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:18,610 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 36bf5d62-98a8-48b9-8529-7e4155eb8ffa, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:18,611 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 36bf5d62-98a8-48b9-8529-7e4155eb8ffa, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:18,614 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 36bf5d62-98a8-48b9-8529-7e4155eb8ffa, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:19,098 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:19,099 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:19,100 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 90194ece-eb49-4cae-b7ae-717e471d3395, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:19,104 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 90194ece-eb49-4cae-b7ae-717e471d3395, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:19,105 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 90194ece-eb49-4cae-b7ae-717e471d3395, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:20,710 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:20,711 - 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-10-31 14:34:20,711 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:20,712 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5d7ce618-3490-4613-95eb-d047b0f2acd6, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:20,713 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5d7ce618-3490-4613-95eb-d047b0f2acd6, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:20,725 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5d7ce618-3490-4613-95eb-d047b0f2acd6, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:21,992 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:21,993 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:21,994 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f12ed7dc-08eb-4913-8c08-a975944499e2, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:21,995 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f12ed7dc-08eb-4913-8c08-a975944499e2, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:22,002 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f12ed7dc-08eb-4913-8c08-a975944499e2, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:22,681 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:22,682 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:22,683 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cc25d93e-d5b1-402e-aaca-7bcd6b1f157d, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:22,684 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cc25d93e-d5b1-402e-aaca-7bcd6b1f157d, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:22,691 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cc25d93e-d5b1-402e-aaca-7bcd6b1f157d, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:24,317 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:24,318 - 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-10-31 14:34:24,319 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:24,319 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 88786ca2-d101-4d2a-9cc2-286ed0c979f6, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:24,321 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 88786ca2-d101-4d2a-9cc2-286ed0c979f6, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:24,322 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 88786ca2-d101-4d2a-9cc2-286ed0c979f6, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:25,685 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 14:34:25,685 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-31 14:34:25,713 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 14:34:25,713 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 51243738-b011-4709-ac61-d51c3edb1fa0, localhost:16348): creating call state
2025-10-31 14:34:25,714 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 51243738-b011-4709-ac61-d51c3edb1fa0, localhost:16348): request = { database: "/Root" }
2025-10-31 14:34:25,723 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 51243738-b011-4709-ac61-d51c3edb1fa0, localhost:16348): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-uk32wjuvr4.auto.internal" port: 16348 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-10-31 14:34:25,724 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-uk32wjuvr4.auto.internal:16348, location 1, ssl: False>]>
2025-10-31 14:34:25,724 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:16348
2025-10-31 14:34:26,106 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:26,107 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:26,108 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 00e9bb1f-48e5-44ce-87a3-3ca635e2a300, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:26,109 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 00e9bb1f-48e5-44ce-87a3-3ca635e2a300, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:26,112 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 00e9bb1f-48e5-44ce-87a3-3ca635e2a300, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:26,585 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:26,586 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:26,587 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4b1a7dac-09da-4275-916a-68ad555ebc43, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:26,588 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4b1a7dac-09da-4275-916a-68ad555ebc43, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:26,590 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4b1a7dac-09da-4275-916a-68ad555ebc43, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:27,709 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:27,711 - 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-10-31 14:34:27,712 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:27,713 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f857514f-8f65-42ca-a3a2-b800c0d9e164, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:27,714 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f857514f-8f65-42ca-a3a2-b800c0d9e164, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:27,716 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f857514f-8f65-42ca-a3a2-b800c0d9e164, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:29,554 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:29,555 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:29,556 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e9b58900-3275-431e-b98a-eded678e0d3a, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:29,557 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e9b58900-3275-431e-b98a-eded678e0d3a, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:29,560 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e9b58900-3275-431e-b98a-eded678e0d3a, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:30,107 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:30,108 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:30,108 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b244e55f-e010-4aef-be81-9ca3e4dbe244, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:30,110 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b244e55f-e010-4aef-be81-9ca3e4dbe244, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:30,115 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b244e55f-e010-4aef-be81-9ca3e4dbe244, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:31,671 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:31,672 - 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-10-31 14:34:31,672 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:31,673 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8c953257-25da-496e-bd53-f5252b598b57, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:31,674 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8c953257-25da-496e-bd53-f5252b598b57, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:31,676 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8c953257-25da-496e-bd53-f5252b598b57, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:34,865 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:34,866 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:34,867 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d6b5c0bc-665a-4fc4-943f-1e3cba3c9690, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:34,868 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d6b5c0bc-665a-4fc4-943f-1e3cba3c9690, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:34,870 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d6b5c0bc-665a-4fc4-943f-1e3cba3c9690, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:35,475 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:35,476 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:35,477 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3b4a568d-df82-4473-80ae-5b3166d76e9f, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:35,477 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3b4a568d-df82-4473-80ae-5b3166d76e9f, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:35,486 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3b4a568d-df82-4473-80ae-5b3166d76e9f, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:35,947 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:35,948 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:35,949 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2bf646c3-1db1-4718-abb0-ef9df24c6b44, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:35,950 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2bf646c3-1db1-4718-abb0-ef9df24c6b44, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:35,951 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2bf646c3-1db1-4718-abb0-ef9df24c6b44, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:42,628 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:52,695 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:52,696 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 151720d4-3038-4527-87bb-8838890345cb, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:52,697 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 151720d4-3038-4527-87bb-8838890345cb, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:52,699 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 151720d4-3038-4527-87bb-8838890345cb, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:58,999 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:59,082 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4367904)
2025-10-31 14:34:59,083 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:59,084 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 10998a01-7ede-4f30-bf40-dee9ff4bf21f, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:59,085 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 10998a01-7ede-4f30-bf40-dee9ff4bf21f, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:59,093 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 10998a01-7ede-4f30-bf40-dee9ff4bf21f, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:59,651 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:59,652 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:34:59,652 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bb17907a-122a-483f-b5b3-bc6528d1f8a0, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:34:59,653 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bb17907a-122a-483f-b5b3-bc6528d1f8a0, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:34:59,657 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bb17907a-122a-483f-b5b3-bc6528d1f8a0, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:35:04,005 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:35:14,053 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:35:14,054 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 23c7a52c-1259-4d47-8158-60ed5ea30371, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:35:14,055 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 23c7a52c-1259-4d47-8158-60ed5ea30371, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:35:14,056 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 23c7a52c-1259-4d47-8158-60ed5ea30371, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:35:15,569 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:35:25,581 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:35:25,582 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a7b3e837-4c74-4d08-ab15-dd5bfc28bd7f, ghrun-uk32wjuvr4.auto.internal:16348): creating call state
2025-10-31 14:35:25,583 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a7b3e837-4c74-4d08-ab15-dd5bfc28bd7f, ghrun-uk32wjuvr4.auto.internal:16348): request = { session_id: "ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==" 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-10-31 14:35:25,587 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a7b3e837-4c74-4d08-ab15-dd5bfc28bd7f, ghrun-uk32wjuvr4.auto.internal:16348): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:35:26,875 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjAwNzdlMDYtNGVjNzg4MC05NmI4ZDRkNi1mMmEyZmM5Mw==
2025-10-31 14:35:26,880 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4258592)
2025-10-31 14:35:26,880 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/lz4_compression` 8092160 / 4258592: 1.900196121159294
2025-10-31 14:35:26,883 - INFO - ya.test - pytest_runtest_teardown: Test teardown
