2025-10-16 17:18:35,527 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:18:35,527 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[lz4_compression-COMPRESSION = "lz4"]
2025-10-16 17:18:35,528 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:18:35,528 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-16 17:18:35,530 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-16 17:18:35,531 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-16 17:18:35,532 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0016c0/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 17:18:35,551 - DEBUG - ya.test - execute: Command pid: 1015030
2025-10-16 17:18:36,650 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) rc: 0
2025-10-16 17:18:36,651 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) elapsed time (sec): 1.0982837677001953
2025-10-16 17:18:36,651 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) idrss: 0
2025-10-16 17:18:36,652 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) inblock: 0
2025-10-16 17:18:36,652 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) isrss: 0
2025-10-16 17:18:36,652 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) ixrss: 0
2025-10-16 17:18:36,653 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) majflt: 0
2025-10-16 17:18:36,653 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) maxrss: 486492
2025-10-16 17:18:36,654 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) minflt: 45091
2025-10-16 17:18:36,654 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) msgrcv: 0
2025-10-16 17:18:36,654 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) msgsnd: 0
2025-10-16 17:18:36,654 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) nivcsw: 182
2025-10-16 17:18:36,655 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) nsignals: 0
2025-10-16 17:18:36,655 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) nswap: 0
2025-10-16 17:18:36,655 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) nvcsw: 14
2025-10-16 17:18:36,656 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) oublock: 8
2025-10-16 17:18:36,656 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) stime: 0.200398
2025-10-16 17:18:36,656 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) utime: 0.539852
2025-10-16 17:18:36,656 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) wtime: 1.118
2025-10-16 17:18:36,657 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) output:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a

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

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

2025-10-16 17:18:36,657 - DEBUG - ya.test - _save_outputs: Command (pid 1015030) errors:

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

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

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

2025-10-16 17:18:36,788 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0016c0/ydb/apps/ydbd/ydbd
2025-10-16 17:18:36,789 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28037
2025-10-16 17:18:36,791 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/b9t0/0016c0/r3tmp/kikimr_cluster_0fgfwsec
2025-10-16 17:18:36,831 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0016c0/ydb/apps/ydbd/ydbd
2025-10-16 17:18:36,832 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3963
2025-10-16 17:18:36,833 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21449
2025-10-16 17:18:36,834 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15201
2025-10-16 17:18:36,834 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1376
2025-10-16 17:18:36,835 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/b9t0/0016c0/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-16 17:18:36,836 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/b9t0/0016c0/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/b9t0/0016c0/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_gobwbdki.log --grpc-port=3963 --mon-port=21449 --ic-port=28037
2025-10-16 17:18:36,836 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:3963/1, disk_size 68719476736
2025-10-16 17:18:36,837 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/b9t0/0016c0/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-16 17:18:36,837 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/b9t0/0016c0/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/b9t0/0016c0/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_gobwbdki.log --grpc-port=3963 --mon-port=21449 --ic-port=28037
2025-10-16 17:18:36,838 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/b9t0/0016c0/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/b9t0/0016c0/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/b9t0/0016c0/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_gobwbdki.log', '--grpc-port=3963', '--mon-port=21449', '--ic-port=28037')' in '/home/runner/.ya/build/build_root/b9t0/0016c0/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-16 17:18:36,845 - DEBUG - ya.test - execute: Command pid: 1015097
2025-10-16 17:18:36,846 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:3963/1
2025-10-16 17:18:36,847 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-16 17:18:36,848 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-16 17:18:36,848 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0016c0/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 17:18:36,856 - DEBUG - ya.test - execute: Command pid: 1015099
2025-10-16 17:18:37,796 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) rc: 0
2025-10-16 17:18:37,797 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) elapsed time (sec): 0.938776969909668
2025-10-16 17:18:37,797 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) idrss: 0
2025-10-16 17:18:37,798 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) inblock: 0
2025-10-16 17:18:37,798 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) isrss: 0
2025-10-16 17:18:37,798 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) ixrss: 0
2025-10-16 17:18:37,799 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) majflt: 0
2025-10-16 17:18:37,799 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) maxrss: 490608
2025-10-16 17:18:37,799 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) minflt: 45092
2025-10-16 17:18:37,799 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) msgrcv: 0
2025-10-16 17:18:37,800 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) msgsnd: 0
2025-10-16 17:18:37,801 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) nivcsw: 183
2025-10-16 17:18:37,801 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) nsignals: 0
2025-10-16 17:18:37,802 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) nswap: 0
2025-10-16 17:18:37,802 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) nvcsw: 15
2025-10-16 17:18:37,802 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) oublock: 8
2025-10-16 17:18:37,803 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) stime: 0.207151
2025-10-16 17:18:37,803 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) utime: 0.5191589999999999
2025-10-16 17:18:37,804 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) wtime: 0.947
2025-10-16 17:18:37,804 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) output:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a

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

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

2025-10-16 17:18:37,805 - DEBUG - ya.test - _save_outputs: Command (pid 1015099) errors:

2025-10-16 17:18:37,805 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a
2025-10-16 17:18:43,369 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0016c0/ydb/apps/ydbd/ydbd
2025-10-16 17:18:43,370 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/b9t0/0016c0/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:3963', '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: 28037\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-16 17:18:43,371 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-16 17:18:43,372 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-16 17:18:43,373 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0016c0/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:3963', '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: 28037\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-16 17:18:43,379 - DEBUG - ya.test - execute: Command pid: 1015643
2025-10-16 17:18:44,527 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) rc: 0
2025-10-16 17:18:44,528 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) elapsed time (sec): 1.1465599536895752
2025-10-16 17:18:44,528 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) idrss: 0
2025-10-16 17:18:44,528 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) inblock: 0
2025-10-16 17:18:44,529 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) isrss: 0
2025-10-16 17:18:44,529 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) ixrss: 0
2025-10-16 17:18:44,529 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) majflt: 0
2025-10-16 17:18:44,530 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) maxrss: 584756
2025-10-16 17:18:44,530 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) minflt: 51318
2025-10-16 17:18:44,530 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) msgrcv: 0
2025-10-16 17:18:44,530 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) msgsnd: 0
2025-10-16 17:18:44,531 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) nivcsw: 198
2025-10-16 17:18:44,531 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) nsignals: 0
2025-10-16 17:18:44,531 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) nswap: 0
2025-10-16 17:18:44,532 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) nvcsw: 898
2025-10-16 17:18:44,533 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) oublock: 8
2025-10-16 17:18:44,533 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) stime: 0.268207
2025-10-16 17:18:44,533 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) utime: 0.581598
2025-10-16 17:18:44,533 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) wtime: 1.154
2025-10-16 17:18:44,534 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-16 17:18:44,534 - DEBUG - ya.test - _save_outputs: Command (pid 1015643) errors:

2025-10-16 17:18:44,536 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/0016c0/ydb/apps/ydbd/ydbd
2025-10-16 17:18:44,536 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/b9t0/0016c0/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:3963', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-10-16 17:18:44,537 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-16 17:18:44,538 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-16 17:18:44,539 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/0016c0/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:3963', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-16 17:18:44,554 - DEBUG - ya.test - execute: Command pid: 1015746
2025-10-16 17:18:46,544 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) rc: 0
2025-10-16 17:18:46,545 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) elapsed time (sec): 1.988945484161377
2025-10-16 17:18:46,545 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) idrss: 0
2025-10-16 17:18:46,546 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) inblock: 0
2025-10-16 17:18:46,546 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) isrss: 0
2025-10-16 17:18:46,546 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) ixrss: 0
2025-10-16 17:18:46,547 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) majflt: 0
2025-10-16 17:18:46,547 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) maxrss: 585596
2025-10-16 17:18:46,547 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) minflt: 51375
2025-10-16 17:18:46,547 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) msgrcv: 0
2025-10-16 17:18:46,548 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) msgsnd: 0
2025-10-16 17:18:46,556 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) nivcsw: 211
2025-10-16 17:18:46,557 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) nsignals: 0
2025-10-16 17:18:46,557 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) nswap: 0
2025-10-16 17:18:46,557 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) nvcsw: 429
2025-10-16 17:18:46,557 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) oublock: 8
2025-10-16 17:18:46,558 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) stime: 0.27042499999999997
2025-10-16 17:18:46,558 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) utime: 0.557138
2025-10-16 17:18:46,558 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) wtime: 2.005
2025-10-16 17:18:46,559 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-16 17:18:46,559 - DEBUG - ya.test - _save_outputs: Command (pid 1015746) errors:

2025-10-16 17:18:46,560 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-16 17:18:46,686 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-16 17:18:46,735 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 17:18:46,742 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:18:46,743 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 17:18:46,805 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-16 17:18:46,806 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 59215e83-8da8-486d-b667-6c9a21526439, localhost:3963): creating call state
2025-10-16 17:18:46,807 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 59215e83-8da8-486d-b667-6c9a21526439, localhost:3963): request = { database: "/Root" }
2025-10-16 17:18:46,821 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 59215e83-8da8-486d-b667-6c9a21526439, localhost:3963): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 3963 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-10-16 17:18:46,822 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:3963, location 1, ssl: False>]>
2025-10-16 17:18:46,823 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 17:18:46,861 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:3963
2025-10-16 17:18:46,862 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-10-16 17:18:46,864 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 808fda3f-64d0-4e6a-9216-549fce253b9d, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:18:46,865 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 808fda3f-64d0-4e6a-9216-549fce253b9d, ghrun-2kszfvc6cm.auto.internal:3963): request = {  }
2025-10-16 17:18:46,874 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 808fda3f-64d0-4e6a-9216-549fce253b9d, ghrun-2kszfvc6cm.auto.internal:3963): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" node_id: 1 }
2025-10-16 17:18:46,875 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, c8be03a2-7075-4d1b-b986-ea321d81bbe9, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:18:46,876 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, c8be03a2-7075-4d1b-b986-ea321d81bbe9, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" }
2025-10-16 17:18:46,892 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, c8be03a2-7075-4d1b-b986-ea321d81bbe9, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:46,917 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:46,918 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8d589dd7-6532-4ac1-b3e0-41c66c8b5d58, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:18:46,920 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8d589dd7-6532-4ac1-b3e0-41c66c8b5d58, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:46,930 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8d589dd7-6532-4ac1-b3e0-41c66c8b5d58, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:50,729 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:50,730 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-10-16 17:18:50,730 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:50,731 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, df832248-672d-402d-8d72-d3ebf5066e12, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:18:50,732 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, df832248-672d-402d-8d72-d3ebf5066e12, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:50,738 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, df832248-672d-402d-8d72-d3ebf5066e12, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:52,013 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:52,014 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:52,015 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c8a39f41-34e5-4028-a5f0-c965af9a8d4b, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:18:52,016 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c8a39f41-34e5-4028-a5f0-c965af9a8d4b, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:52,023 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c8a39f41-34e5-4028-a5f0-c965af9a8d4b, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:53,323 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:53,324 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:53,325 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cce40563-4758-4972-808e-a3c79fddf2e1, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:18:53,326 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cce40563-4758-4972-808e-a3c79fddf2e1, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:53,331 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cce40563-4758-4972-808e-a3c79fddf2e1, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:54,267 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:54,268 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:54,269 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 65b4875b-5199-4da0-b934-13ff27b2912c, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:18:54,270 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 65b4875b-5199-4da0-b934-13ff27b2912c, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:54,272 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 65b4875b-5199-4da0-b934-13ff27b2912c, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:56,393 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:56,394 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 1618432, 'BlobCount': 128}}
2025-10-16 17:18:56,395 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:56,395 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0a15a488-8656-4fda-a720-d3245051cab9, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:18:56,405 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0a15a488-8656-4fda-a720-d3245051cab9, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:56,407 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0a15a488-8656-4fda-a720-d3245051cab9, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:57,603 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:57,604 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:57,605 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 26b51136-02ba-4cad-9681-fb7145659c11, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:18:57,606 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 26b51136-02ba-4cad-9681-fb7145659c11, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:57,613 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 26b51136-02ba-4cad-9681-fb7145659c11, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:57,914 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:57,915 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:57,916 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fd7a9543-f18e-4747-8b98-194def6303ec, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:18:57,917 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fd7a9543-f18e-4747-8b98-194def6303ec, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:57,919 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fd7a9543-f18e-4747-8b98-194def6303ec, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:58,355 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:58,356 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 3236864, 'BlobCount': 256}}
2025-10-16 17:18:58,356 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:58,357 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5b20b5b8-1992-4c64-90a8-8a423b3eb6c1, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:18:58,358 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5b20b5b8-1992-4c64-90a8-8a423b3eb6c1, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:58,360 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5b20b5b8-1992-4c64-90a8-8a423b3eb6c1, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:59,638 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:59,639 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:59,639 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 573e44c5-f343-4f4b-903a-2c6159fed089, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:18:59,640 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 573e44c5-f343-4f4b-903a-2c6159fed089, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:59,642 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 573e44c5-f343-4f4b-903a-2c6159fed089, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:59,952 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:59,952 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:18:59,953 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5b985eb4-5c60-4c77-a92d-68fcb890752b, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:18:59,954 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5b985eb4-5c60-4c77-a92d-68fcb890752b, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:59,960 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5b985eb4-5c60-4c77-a92d-68fcb890752b, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:00,422 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:00,423 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 303071, 'Portions': 186}}, blobs: {'__DEFAULT': {'Portions': 184, 'BlobSize': 4857944, 'BlobCount': 384}}
2025-10-16 17:19:00,423 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:00,424 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6d2ac2e4-cffe-4f42-a5cb-9d6e16651180, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:00,425 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6d2ac2e4-cffe-4f42-a5cb-9d6e16651180, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:00,426 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6d2ac2e4-cffe-4f42-a5cb-9d6e16651180, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:02,073 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:02,073 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:02,074 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9d7fe8f1-0f51-4493-8335-22073689e3a4, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:02,075 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9d7fe8f1-0f51-4493-8335-22073689e3a4, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:02,080 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9d7fe8f1-0f51-4493-8335-22073689e3a4, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:02,634 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:02,635 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:02,636 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 10dbb4aa-e548-4f81-925e-1a9846353e3f, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:02,637 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 10dbb4aa-e548-4f81-925e-1a9846353e3f, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:02,646 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 10dbb4aa-e548-4f81-925e-1a9846353e3f, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:03,216 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:03,217 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 242}}, blobs: {'__DEFAULT': {'Portions': 242, 'BlobSize': 6476856, 'BlobCount': 506}}
2025-10-16 17:19:03,217 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:03,222 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a5822f07-2fc9-452c-b049-fd42ee765e68, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:03,223 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a5822f07-2fc9-452c-b049-fd42ee765e68, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:03,229 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a5822f07-2fc9-452c-b049-fd42ee765e68, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:04,837 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:04,838 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:04,839 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2d5e38df-00f3-459e-a17d-8b8c6674232f, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:04,839 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2d5e38df-00f3-459e-a17d-8b8c6674232f, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:04,841 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2d5e38df-00f3-459e-a17d-8b8c6674232f, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:05,360 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:05,361 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:05,361 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 67be963d-92ee-4e79-9d50-dd3ad7cbb504, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:05,362 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 67be963d-92ee-4e79-9d50-dd3ad7cbb504, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:05,364 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 67be963d-92ee-4e79-9d50-dd3ad7cbb504, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:06,400 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:06,401 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 300}}, blobs: {'__DEFAULT': {'Portions': 300, 'BlobSize': 8095256, 'BlobCount': 626}}
2025-10-16 17:19:06,401 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:06,402 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0033a991-29fd-485d-aace-d394523d012e, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:06,403 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0033a991-29fd-485d-aace-d394523d012e, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:06,409 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0033a991-29fd-485d-aace-d394523d012e, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:08,401 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:08,401 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:08,402 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c5a38829-7e3d-4efb-ad13-4edd52b599b5, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:08,403 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c5a38829-7e3d-4efb-ad13-4edd52b599b5, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:08,404 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c5a38829-7e3d-4efb-ad13-4edd52b599b5, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:08,840 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:08,841 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:08,842 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aba974ab-2346-42e2-a063-a766cc4300da, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:08,843 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aba974ab-2346-42e2-a063-a766cc4300da, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:08,846 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aba974ab-2346-42e2-a063-a766cc4300da, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:09,571 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:09,571 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 356}}, blobs: {'__DEFAULT': {'Portions': 356, 'BlobSize': 9713312, 'BlobCount': 742}}
2025-10-16 17:19:09,572 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:09,573 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 94e7c892-52d0-4074-a66e-ab9f6381ab28, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:09,574 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 94e7c892-52d0-4074-a66e-ab9f6381ab28, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:09,576 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 94e7c892-52d0-4074-a66e-ab9f6381ab28, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:10,878 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:10,879 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:10,879 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 993dac04-6173-4f0e-a914-f5aaecd6a92e, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:10,880 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 993dac04-6173-4f0e-a914-f5aaecd6a92e, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:10,882 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 993dac04-6173-4f0e-a914-f5aaecd6a92e, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:11,224 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:11,225 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:11,226 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 009949d7-a616-4886-9852-55cd0d449c26, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:11,227 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 009949d7-a616-4886-9852-55cd0d449c26, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:11,233 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 009949d7-a616-4886-9852-55cd0d449c26, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:12,030 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:12,031 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 420}}, blobs: {'__DEFAULT': {'Portions': 420, 'BlobSize': 11331744, 'BlobCount': 870}}
2025-10-16 17:19:12,032 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:12,032 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1b1ac33e-95c5-4b86-86b8-1611051205b7, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:12,033 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1b1ac33e-95c5-4b86-86b8-1611051205b7, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:12,041 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1b1ac33e-95c5-4b86-86b8-1611051205b7, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:13,306 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:13,307 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:13,308 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dce03a3a-270e-4376-92e5-1af585730515, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:13,309 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dce03a3a-270e-4376-92e5-1af585730515, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:13,311 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dce03a3a-270e-4376-92e5-1af585730515, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:13,937 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:13,938 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:13,939 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 34bce4c7-84ae-47f4-b91b-48f5e91a6112, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:13,940 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 34bce4c7-84ae-47f4-b91b-48f5e91a6112, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:13,947 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 34bce4c7-84ae-47f4-b91b-48f5e91a6112, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:14,637 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:14,638 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 821767, 'Portions': 474}}, blobs: {'__DEFAULT': {'Portions': 460, 'BlobSize': 12948112, 'BlobCount': 958}}
2025-10-16 17:19:14,639 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:14,639 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c3aed41b-1ee6-42e9-8240-bca4dcc45b62, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:14,641 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c3aed41b-1ee6-42e9-8240-bca4dcc45b62, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:14,642 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c3aed41b-1ee6-42e9-8240-bca4dcc45b62, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:16,043 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:16,044 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:16,044 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 031e82dc-c3d3-4cf1-9db0-8b78c2db1f7d, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:16,045 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 031e82dc-c3d3-4cf1-9db0-8b78c2db1f7d, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:16,048 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 031e82dc-c3d3-4cf1-9db0-8b78c2db1f7d, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:16,420 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:16,421 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:16,422 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b2f56064-f048-4fa6-a876-ab8bf0154543, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:16,423 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b2f56064-f048-4fa6-a876-ab8bf0154543, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:16,432 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b2f56064-f048-4fa6-a876-ab8bf0154543, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:17,430 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:17,430 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 912361, 'Portions': 511}}, blobs: {'__DEFAULT': {'Portions': 503, 'BlobSize': 14564464, 'BlobCount': 1050}}
2025-10-16 17:19:17,431 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:17,432 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 19a4c62f-db59-4bb5-89fa-edbd0c1ce88a, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:17,437 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 19a4c62f-db59-4bb5-89fa-edbd0c1ce88a, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:17,439 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 19a4c62f-db59-4bb5-89fa-edbd0c1ce88a, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:19,023 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:19,024 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:19,025 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cb159f7f-03dc-41d2-b8e2-d7176d242f31, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:19,025 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cb159f7f-03dc-41d2-b8e2-d7176d242f31, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:19,027 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cb159f7f-03dc-41d2-b8e2-d7176d242f31, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:19,525 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:19,526 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:19,526 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 49c112a5-6fce-44df-9da0-f4a737060acb, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:19,527 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 49c112a5-6fce-44df-9da0-f4a737060acb, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:19,533 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 49c112a5-6fce-44df-9da0-f4a737060acb, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:20,259 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:20,260 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 559}}, blobs: {'__DEFAULT': {'Portions': 544, 'BlobSize': 16407552, 'BlobCount': 1140}}
2025-10-16 17:19:20,260 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:20,261 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a4d1a433-8a4a-438e-a322-0f0e586b3877, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:20,262 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a4d1a433-8a4a-438e-a322-0f0e586b3877, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:20,269 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a4d1a433-8a4a-438e-a322-0f0e586b3877, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:21,497 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:21,498 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:21,499 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8bc72d3e-d5b3-485b-95a6-1691b23cb6b2, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:21,499 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8bc72d3e-d5b3-485b-95a6-1691b23cb6b2, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:21,505 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8bc72d3e-d5b3-485b-95a6-1691b23cb6b2, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:21,882 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:21,883 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:21,884 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f9b44124-0758-46ba-8e6f-3ab3aaa5a95f, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:21,884 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f9b44124-0758-46ba-8e6f-3ab3aaa5a95f, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:21,888 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f9b44124-0758-46ba-8e6f-3ab3aaa5a95f, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:22,145 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:22,146 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:22,147 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 733b1381-0986-43f0-a982-6f65a9736103, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:22,148 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 733b1381-0986-43f0-a982-6f65a9736103, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:22,149 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 733b1381-0986-43f0-a982-6f65a9736103, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:27,416 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:37,463 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:37,464 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0815a484-b5ed-4d63-bd13-351983b1777f, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:37,465 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0815a484-b5ed-4d63-bd13-351983b1777f, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:37,467 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0815a484-b5ed-4d63-bd13-351983b1777f, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:42,168 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:42,243 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8077040)
2025-10-16 17:19:42,244 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:42,245 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 32293242-ffe0-41e9-9123-3cad23175996, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:42,246 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 32293242-ffe0-41e9-9123-3cad23175996, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:42,261 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 32293242-ffe0-41e9-9123-3cad23175996, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:46,799 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:56,860 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:56,861 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d968a4e9-f5a4-47e5-b155-d763d597e5e1, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:56,862 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d968a4e9-f5a4-47e5-b155-d763d597e5e1, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:56,864 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d968a4e9-f5a4-47e5-b155-d763d597e5e1, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:58,937 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:58,949 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8027792)
2025-10-16 17:19:58,950 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:58,951 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c20fc7a9-4a2b-4477-bb1e-f599617beb68, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:58,952 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c20fc7a9-4a2b-4477-bb1e-f599617beb68, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:58,957 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c20fc7a9-4a2b-4477-bb1e-f599617beb68, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:59,281 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:59,284 - 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-16 17:19:59,285 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:19:59,286 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b820caf4-e180-40c0-bbd0-bf6171046864, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:19:59,287 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b820caf4-e180-40c0-bbd0-bf6171046864, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:19:59,293 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b820caf4-e180-40c0-bbd0-bf6171046864, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:04,315 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:04,316 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/lz4_compression created
2025-10-16 17:20:04,317 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:04,317 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6630aa43-9c0c-49d9-912d-95ff463fc707, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:04,318 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6630aa43-9c0c-49d9-912d-95ff463fc707, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:04,321 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6630aa43-9c0c-49d9-912d-95ff463fc707, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:05,406 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:05,407 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:05,407 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 37c822be-4b12-4d7a-bef9-f64f664fa3d1, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:05,408 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 37c822be-4b12-4d7a-bef9-f64f664fa3d1, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:05,414 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 37c822be-4b12-4d7a-bef9-f64f664fa3d1, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:06,998 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:06,999 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:07,000 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d2ea3a65-69ec-49bb-9dcc-1e5a7afa0a05, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:07,001 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d2ea3a65-69ec-49bb-9dcc-1e5a7afa0a05, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:07,002 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d2ea3a65-69ec-49bb-9dcc-1e5a7afa0a05, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:08,537 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:08,538 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:08,538 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2515c7a8-3277-4bdc-bd11-88a308eaf092, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:08,539 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2515c7a8-3277-4bdc-bd11-88a308eaf092, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:08,541 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2515c7a8-3277-4bdc-bd11-88a308eaf092, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:11,843 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:11,844 - 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-16 17:20:11,845 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:11,845 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8b97c9e7-4c77-412a-b4c1-a7b016759c38, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:11,846 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8b97c9e7-4c77-412a-b4c1-a7b016759c38, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:11,852 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8b97c9e7-4c77-412a-b4c1-a7b016759c38, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:13,308 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:13,309 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:13,310 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 43610f42-e75f-4f06-bd6f-7807abbec365, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:13,312 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 43610f42-e75f-4f06-bd6f-7807abbec365, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:13,322 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 43610f42-e75f-4f06-bd6f-7807abbec365, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:13,836 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:13,837 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:13,838 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4d665d1a-6fd4-4d7d-9b1b-07055782a7d6, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:13,839 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4d665d1a-6fd4-4d7d-9b1b-07055782a7d6, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:13,841 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4d665d1a-6fd4-4d7d-9b1b-07055782a7d6, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:14,321 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:14,322 - 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-16 17:20:14,322 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:14,323 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1fbe9453-7f55-489c-8f2f-34c6786f5e55, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:14,324 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1fbe9453-7f55-489c-8f2f-34c6786f5e55, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:14,326 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1fbe9453-7f55-489c-8f2f-34c6786f5e55, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:16,441 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:16,442 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:16,443 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0553d160-0e75-4e54-bb22-6dce052d688b, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:16,444 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0553d160-0e75-4e54-bb22-6dce052d688b, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:16,445 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0553d160-0e75-4e54-bb22-6dce052d688b, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:17,061 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:17,062 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:17,063 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 448a583f-3ba7-40c6-b35b-8e2e29150790, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:17,065 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 448a583f-3ba7-40c6-b35b-8e2e29150790, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:17,067 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 448a583f-3ba7-40c6-b35b-8e2e29150790, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:17,733 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:17,734 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 303050, 'Portions': 189}}, blobs: {'__DEFAULT': {'Portions': 182, 'BlobSize': 2623136, 'BlobCount': 384}}
2025-10-16 17:20:17,735 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:17,735 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0e079bcd-4ad9-4870-bbea-cb6eaea927d7, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:17,737 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0e079bcd-4ad9-4870-bbea-cb6eaea927d7, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:17,739 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0e079bcd-4ad9-4870-bbea-cb6eaea927d7, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:19,338 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:19,339 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:19,339 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cc71920f-e688-4f0c-9742-a5790b317c15, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:19,340 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cc71920f-e688-4f0c-9742-a5790b317c15, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:19,342 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cc71920f-e688-4f0c-9742-a5790b317c15, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:19,842 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:19,843 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:19,844 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9d450b7d-c1d4-4dfd-a3ae-8020589fb33d, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:19,845 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9d450b7d-c1d4-4dfd-a3ae-8020589fb33d, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:19,849 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9d450b7d-c1d4-4dfd-a3ae-8020589fb33d, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:20,450 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:20,451 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 240}}, blobs: {'__DEFAULT': {'Portions': 240, 'BlobSize': 3497056, 'BlobCount': 506}}
2025-10-16 17:20:20,452 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:20,453 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4c363ca3-5d5b-471f-aea9-4ce923eea068, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:20,455 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4c363ca3-5d5b-471f-aea9-4ce923eea068, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:20,457 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4c363ca3-5d5b-471f-aea9-4ce923eea068, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:21,988 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:21,989 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:21,990 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9dea3e55-aff9-4a74-80b9-b4bbc5687563, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:21,991 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9dea3e55-aff9-4a74-80b9-b4bbc5687563, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:21,993 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9dea3e55-aff9-4a74-80b9-b4bbc5687563, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:22,298 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:22,299 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:22,300 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e71b1456-af67-4d4c-9d05-0b6dacaab676, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:22,301 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e71b1456-af67-4d4c-9d05-0b6dacaab676, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:22,302 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e71b1456-af67-4d4c-9d05-0b6dacaab676, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:22,946 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:22,947 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 298}}, blobs: {'__DEFAULT': {'Portions': 298, 'BlobSize': 4370256, 'BlobCount': 626}}
2025-10-16 17:20:22,948 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:22,948 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c5f2cbf0-ffa6-412f-81b2-c97e2cd5f57b, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:22,949 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c5f2cbf0-ffa6-412f-81b2-c97e2cd5f57b, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:22,951 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c5f2cbf0-ffa6-412f-81b2-c97e2cd5f57b, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:24,489 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:24,490 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:24,491 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 957d4de7-8321-4bae-9515-69e8fb07af9e, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:24,492 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 957d4de7-8321-4bae-9515-69e8fb07af9e, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:24,498 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 957d4de7-8321-4bae-9515-69e8fb07af9e, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:24,886 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:24,887 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:24,888 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9d8f4f18-bb45-4759-84d0-951d582d544d, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:24,889 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9d8f4f18-bb45-4759-84d0-951d582d544d, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:24,904 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9d8f4f18-bb45-4759-84d0-951d582d544d, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:25,569 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:25,570 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 346}}, blobs: {'__DEFAULT': {'Portions': 346, 'BlobSize': 5241776, 'BlobCount': 730}}
2025-10-16 17:20:25,570 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:25,571 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5763581b-99d0-4d71-9055-c541d04db021, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:25,572 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5763581b-99d0-4d71-9055-c541d04db021, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:25,578 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5763581b-99d0-4d71-9055-c541d04db021, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:26,420 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:20:26,421 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 17:20:26,448 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-16 17:20:26,449 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, d533c6d8-d36d-4631-9632-f51ab703dc35, localhost:3963): creating call state
2025-10-16 17:20:26,450 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, d533c6d8-d36d-4631-9632-f51ab703dc35, localhost:3963): request = { database: "/Root" }
2025-10-16 17:20:26,459 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, d533c6d8-d36d-4631-9632-f51ab703dc35, localhost:3963): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 3963 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-10-16 17:20:26,460 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:3963, location 1, ssl: False>]>
2025-10-16 17:20:26,461 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:3963
2025-10-16 17:20:26,895 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:26,896 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:26,897 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f4cdf8da-0a6a-4b26-8bbb-63b841ac190a, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:26,899 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f4cdf8da-0a6a-4b26-8bbb-63b841ac190a, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:26,901 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f4cdf8da-0a6a-4b26-8bbb-63b841ac190a, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:27,473 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:27,474 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:27,475 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 97a69deb-5cd6-4834-a7e1-54bfca99ddef, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:27,481 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 97a69deb-5cd6-4834-a7e1-54bfca99ddef, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:27,485 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 97a69deb-5cd6-4834-a7e1-54bfca99ddef, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:28,263 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:28,264 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 395}}, blobs: {'__DEFAULT': {'Portions': 395, 'BlobSize': 6113424, 'BlobCount': 834}}
2025-10-16 17:20:28,265 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:28,266 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d0867284-dc44-4e67-8f04-fe8666c7a14d, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:28,267 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d0867284-dc44-4e67-8f04-fe8666c7a14d, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:28,269 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d0867284-dc44-4e67-8f04-fe8666c7a14d, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:29,669 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:29,670 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:29,671 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d153e26a-06a3-4537-9432-adf44da77a6c, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:29,672 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d153e26a-06a3-4537-9432-adf44da77a6c, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:29,673 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d153e26a-06a3-4537-9432-adf44da77a6c, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:29,985 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:29,986 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:29,987 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fd0c4aaf-8750-4f90-8639-ec64339b1313, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:29,988 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fd0c4aaf-8750-4f90-8639-ec64339b1313, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:29,991 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fd0c4aaf-8750-4f90-8639-ec64339b1313, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:30,690 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:30,691 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 811119, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 441, 'BlobSize': 6984312, 'BlobCount': 932}}
2025-10-16 17:20:30,692 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:30,693 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ae8c1c83-9b3b-41cf-aafb-fedbc29ed602, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:30,694 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ae8c1c83-9b3b-41cf-aafb-fedbc29ed602, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:30,695 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ae8c1c83-9b3b-41cf-aafb-fedbc29ed602, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:32,161 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:32,162 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:32,163 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6b17da34-8457-455e-88dc-9c2f03d154f4, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:32,164 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6b17da34-8457-455e-88dc-9c2f03d154f4, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:32,173 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6b17da34-8457-455e-88dc-9c2f03d154f4, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:32,749 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:32,750 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:32,751 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 95e7a514-514f-4446-9792-403e1d8fd9d9, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:32,752 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 95e7a514-514f-4446-9792-403e1d8fd9d9, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:32,754 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 95e7a514-514f-4446-9792-403e1d8fd9d9, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:33,805 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:33,806 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 505}}, blobs: {'__DEFAULT': {'Portions': 493, 'BlobSize': 8071584, 'BlobCount': 1044}}
2025-10-16 17:20:33,807 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:33,807 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 122e3ddf-3386-4a52-95f0-6e506df227f1, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:33,809 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 122e3ddf-3386-4a52-95f0-6e506df227f1, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:33,810 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 122e3ddf-3386-4a52-95f0-6e506df227f1, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:35,357 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:35,358 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:35,358 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9b048724-2918-45f0-b97d-7cdda81fbeff, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:35,359 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9b048724-2918-45f0-b97d-7cdda81fbeff, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:35,362 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9b048724-2918-45f0-b97d-7cdda81fbeff, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:35,710 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:35,711 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:35,712 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cf0b0171-ca81-4735-95f1-93ca690c70bd, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:35,713 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cf0b0171-ca81-4735-95f1-93ca690c70bd, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:35,715 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cf0b0171-ca81-4735-95f1-93ca690c70bd, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:36,679 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:36,680 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 1014118, 'Portions': 526}}, blobs: {'__DEFAULT': {'Portions': 517, 'BlobSize': 8722008, 'BlobCount': 1098}}
2025-10-16 17:20:36,680 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:36,681 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a400ce92-c9cd-4777-a410-4bf3d2573dab, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:36,682 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a400ce92-c9cd-4777-a410-4bf3d2573dab, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:36,683 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a400ce92-c9cd-4777-a410-4bf3d2573dab, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:38,151 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:38,152 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:38,153 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0c3db32e-4484-4490-9317-0ebabe905240, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:38,175 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0c3db32e-4484-4490-9317-0ebabe905240, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:38,181 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0c3db32e-4484-4490-9317-0ebabe905240, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:38,620 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:38,621 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:38,622 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b9585c63-7654-4f34-b587-d2c10d389c12, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:38,622 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b9585c63-7654-4f34-b587-d2c10d389c12, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:38,624 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b9585c63-7654-4f34-b587-d2c10d389c12, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:39,102 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:39,103 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:39,104 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5bed81a5-fb52-4d19-acf9-f6f0d7801462, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:39,104 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5bed81a5-fb52-4d19-acf9-f6f0d7801462, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:39,106 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5bed81a5-fb52-4d19-acf9-f6f0d7801462, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:44,163 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:54,213 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:54,214 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 009f38b7-abc3-488a-a6f8-eca0a8457643, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:54,215 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 009f38b7-abc3-488a-a6f8-eca0a8457643, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:54,216 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 009f38b7-abc3-488a-a6f8-eca0a8457643, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:58,526 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:58,586 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4345752)
2025-10-16 17:20:58,587 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:58,588 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ed41a723-dcf1-4aa3-a999-72eb329264be, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:58,589 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ed41a723-dcf1-4aa3-a999-72eb329264be, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:58,597 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ed41a723-dcf1-4aa3-a999-72eb329264be, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:59,101 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:59,102 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:20:59,102 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 470dc477-9659-4f6a-bde2-d108734fffdb, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:20:59,103 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 470dc477-9659-4f6a-bde2-d108734fffdb, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:20:59,107 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 470dc477-9659-4f6a-bde2-d108734fffdb, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:03,664 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:13,700 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:13,701 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 077c3b44-6749-424d-9508-55c98d5aa23d, ghrun-2kszfvc6cm.auto.internal:3963): creating call state
2025-10-16 17:21:13,702 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 077c3b44-6749-424d-9508-55c98d5aa23d, ghrun-2kszfvc6cm.auto.internal:3963): request = { session_id: "ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=" 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-16 17:21:13,703 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 077c3b44-6749-424d-9508-55c98d5aa23d, ghrun-2kszfvc6cm.auto.internal:3963): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:21:16,243 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWRkODczMDEtNWZhYTg5Y2ItOWU5ZWRjMzAtZTEyOTBiZjI=
2025-10-16 17:21:16,258 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4290216)
2025-10-16 17:21:16,259 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/lz4_compression` 8077040 / 4290216: 1.8826651152296294
2025-10-16 17:21:16,261 - INFO - ya.test - pytest_runtest_teardown: Test teardown
