2025-12-23 18:58:16,414 - INFO - ya.test.1602925  - pytest_runtest_setup: ####################################################################################################
2025-12-23 18:58:16,414 - INFO - ya.test.1602925  - pytest_runtest_setup: test_all_supported_compression[zstd_8_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 8]
2025-12-23 18:58:16,415 - INFO - ya.test.1602925  - pytest_runtest_setup: ####################################################################################################
2025-12-23 18:58:16,415 - INFO - ya.test.1602925  - pytest_runtest_setup: Test setup
2025-12-23 18:58:16,416 - DEBUG - ya.test.1602925  - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-23 18:58:16,417 - DEBUG - ya.test.1602925  - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-23 18:58:16,417 - DEBUG - ya.test.1602925  - execute: Executing '['/home/runner/.ya/build/build_root/8ptz/0046d2/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-23 18:58:16,418 - DEBUG - ya.test.1602925  - execute: Command pid: 1604161
2025-12-23 18:58:17,023 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) rc: 0
2025-12-23 18:58:17,024 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) elapsed time (sec): 0.6039996147155762
2025-12-23 18:58:17,024 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) idrss: 0
2025-12-23 18:58:17,024 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) inblock: 0
2025-12-23 18:58:17,024 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) isrss: 0
2025-12-23 18:58:17,025 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) ixrss: 0
2025-12-23 18:58:17,025 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) majflt: 0
2025-12-23 18:58:17,025 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) maxrss: 496916
2025-12-23 18:58:17,025 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) minflt: 45845
2025-12-23 18:58:17,025 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) msgrcv: 0
2025-12-23 18:58:17,026 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) msgsnd: 0
2025-12-23 18:58:17,026 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) nivcsw: 1
2025-12-23 18:58:17,026 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) nsignals: 0
2025-12-23 18:58:17,026 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) nswap: 0
2025-12-23 18:58:17,027 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) nvcsw: 14
2025-12-23 18:58:17,027 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) oublock: 8
2025-12-23 18:58:17,027 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) stime: 0.146763
2025-12-23 18:58:17,027 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) utime: 0.412804
2025-12-23 18:58:17,027 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) wtime: 0.606
2025-12-23 18:58:17,028 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) output:
Git info:
    Commit: 09729cc9cf0669d9f027468138c7d2dc45a65d8c
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge ff1b65d8e0d4692b69b059de261ca6f72c94cd74 into 7a46c6293674d1de550a9922546b7e0022176cfa
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ou4bsyjxme    Host information: 
        Linux ghrun-ou4bsyjxme 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-12-23 18:58:17,028 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604161) errors:

2025-12-23 18:58:17,028 - INFO - __tests__.base.1602925  - _setup_ydb: Git info:
    Commit: 09729cc9cf0669d9f027468138c7d2dc45a65d8c
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge ff1b65d8e0d4692b69b059de261ca6f72c94cd74 into 7a46c6293674d1de550a9922546b7e0022176cfa
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ou4bsyjxme    Host information: 
        Linux ghrun-ou4bsyjxme 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-12-23 18:58:17,110 - DEBUG - ya.test.1602925  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8ptz/0046d2/ydb/apps/ydbd/ydbd
2025-12-23 18:58:17,111 - DEBUG - library.python.filelock.1602925  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26861
2025-12-23 18:58:17,117 - DEBUG - ydb.tests.library.harness.kikimr_runner.1602925  - start: Working directory: /home/runner/.ya/build/build_root/8ptz/0046d2/r3tmp/kikimr_cluster__cggdqcs
2025-12-23 18:58:17,145 - DEBUG - ya.test.1602925  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8ptz/0046d2/ydb/apps/ydbd/ydbd
2025-12-23 18:58:17,145 - DEBUG - library.python.filelock.1602925  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19900
2025-12-23 18:58:17,146 - DEBUG - library.python.filelock.1602925  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8233
2025-12-23 18:58:17,146 - DEBUG - library.python.filelock.1602925  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61190
2025-12-23 18:58:17,146 - DEBUG - library.python.filelock.1602925  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4130
2025-12-23 18:58:17,147 - INFO - ydb.tests.library.harness.kikimr_runner.1602925  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/8ptz/0046d2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/kikimr_configs"
2025-12-23 18:58:17,147 - INFO - ydb.tests.library.harness.kikimr_runner.1602925  - __make_run_command: Final command: /home/runner/.ya/build/build_root/8ptz/0046d2/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/8ptz/0046d2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/node_1/logfile_cldx_ln1.log --grpc-port=19900 --mon-port=8233 --ic-port=26861
2025-12-23 18:58:17,148 - DEBUG - ydb.tests.library.harness.kikimr_runner.1602925  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:19900/1, disk_size 68719476736
2025-12-23 18:58:17,148 - INFO - ydb.tests.library.harness.kikimr_runner.1602925  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/8ptz/0046d2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/kikimr_configs"
2025-12-23 18:58:17,148 - INFO - ydb.tests.library.harness.kikimr_runner.1602925  - __make_run_command: Final command: /home/runner/.ya/build/build_root/8ptz/0046d2/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/8ptz/0046d2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/node_1/logfile_cldx_ln1.log --grpc-port=19900 --mon-port=8233 --ic-port=26861
2025-12-23 18:58:17,149 - DEBUG - ya.test.1602925  - execute: Executing '('/home/runner/.ya/build/build_root/8ptz/0046d2/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/8ptz/0046d2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/8ptz/0046d2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/node_1/logfile_cldx_ln1.log', '--grpc-port=19900', '--mon-port=8233', '--ic-port=26861')' in '/home/runner/.ya/build/build_root/8ptz/0046d2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/node_1' (no wait)
2025-12-23 18:58:17,151 - DEBUG - ya.test.1602925  - execute: Command pid: 1604424
2025-12-23 18:58:17,152 - INFO - ydb.tests.library.harness.kikimr_runner.1602925  - start: Started node localhost:19900/1
2025-12-23 18:58:17,153 - DEBUG - ya.test.1602925  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-23 18:58:17,153 - DEBUG - ya.test.1602925  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-23 18:58:17,154 - DEBUG - ya.test.1602925  - execute: Executing '['/home/runner/.ya/build/build_root/8ptz/0046d2/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-23 18:58:17,155 - DEBUG - ya.test.1602925  - execute: Command pid: 1604425
2025-12-23 18:58:17,745 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) rc: 0
2025-12-23 18:58:17,746 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) elapsed time (sec): 0.5900313854217529
2025-12-23 18:58:17,746 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) idrss: 0
2025-12-23 18:58:17,747 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) inblock: 0
2025-12-23 18:58:17,747 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) isrss: 0
2025-12-23 18:58:17,747 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) ixrss: 0
2025-12-23 18:58:17,748 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) majflt: 0
2025-12-23 18:58:17,748 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) maxrss: 507304
2025-12-23 18:58:17,748 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) minflt: 45931
2025-12-23 18:58:17,749 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) msgrcv: 0
2025-12-23 18:58:17,749 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) msgsnd: 0
2025-12-23 18:58:17,749 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) nivcsw: 2
2025-12-23 18:58:17,750 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) nsignals: 0
2025-12-23 18:58:17,750 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) nswap: 0
2025-12-23 18:58:17,750 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) nvcsw: 14
2025-12-23 18:58:17,751 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) oublock: 8
2025-12-23 18:58:17,751 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) stime: 0.178318
2025-12-23 18:58:17,751 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) utime: 0.36655
2025-12-23 18:58:17,752 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) wtime: 0.591
2025-12-23 18:58:17,752 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) output:
Git info:
    Commit: 09729cc9cf0669d9f027468138c7d2dc45a65d8c
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge ff1b65d8e0d4692b69b059de261ca6f72c94cd74 into 7a46c6293674d1de550a9922546b7e0022176cfa
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ou4bsyjxme    Host information: 
        Linux ghrun-ou4bsyjxme 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-12-23 18:58:17,753 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1604425) errors:

2025-12-23 18:58:17,753 - INFO - ydb.tests.library.harness.kikimr_runner.1602925  - start: Node 1 version:
Git info:
    Commit: 09729cc9cf0669d9f027468138c7d2dc45a65d8c
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge ff1b65d8e0d4692b69b059de261ca6f72c94cd74 into 7a46c6293674d1de550a9922546b7e0022176cfa
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ou4bsyjxme    Host information: 
        Linux ghrun-ou4bsyjxme 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-23 18:58:20,224 - DEBUG - ya.test.1602925  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8ptz/0046d2/ydb/apps/ydbd/ydbd
2025-12-23 18:58:20,224 - DEBUG - ydb.tests.library.harness.kikimr_runner.1602925  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/8ptz/0046d2/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:19900', '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: 26861\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-23 18:58:20,225 - DEBUG - ya.test.1602925  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-23 18:58:20,226 - DEBUG - ya.test.1602925  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-23 18:58:20,226 - DEBUG - ya.test.1602925  - execute: Executing '['/home/runner/.ya/build/build_root/8ptz/0046d2/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:19900', '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: 26861\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-23 18:58:20,227 - DEBUG - ya.test.1602925  - execute: Command pid: 1605351
2025-12-23 18:58:20,938 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) rc: 0
2025-12-23 18:58:20,939 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) elapsed time (sec): 0.7104980945587158
2025-12-23 18:58:20,939 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) idrss: 0
2025-12-23 18:58:20,939 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) inblock: 0
2025-12-23 18:58:20,940 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) isrss: 0
2025-12-23 18:58:20,940 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) ixrss: 0
2025-12-23 18:58:20,940 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) majflt: 0
2025-12-23 18:58:20,940 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) maxrss: 578700
2025-12-23 18:58:20,940 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) minflt: 52064
2025-12-23 18:58:20,941 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) msgrcv: 0
2025-12-23 18:58:20,941 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) msgsnd: 0
2025-12-23 18:58:20,941 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) nivcsw: 3
2025-12-23 18:58:20,942 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) nsignals: 0
2025-12-23 18:58:20,942 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) nswap: 0
2025-12-23 18:58:20,942 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) nvcsw: 678
2025-12-23 18:58:20,942 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) oublock: 8
2025-12-23 18:58:20,943 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) stime: 0.236226
2025-12-23 18:58:20,943 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) utime: 0.44781
2025-12-23 18:58:20,943 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) wtime: 0.712
2025-12-23 18:58:20,943 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-23 18:58:20,943 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605351) errors:

2025-12-23 18:58:20,945 - DEBUG - ya.test.1602925  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8ptz/0046d2/ydb/apps/ydbd/ydbd
2025-12-23 18:58:20,946 - DEBUG - ydb.tests.library.harness.kikimr_runner.1602925  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/8ptz/0046d2/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:19900', '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-12-23 18:58:20,947 - DEBUG - ya.test.1602925  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-23 18:58:20,947 - DEBUG - ya.test.1602925  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-23 18:58:20,948 - DEBUG - ya.test.1602925  - execute: Executing '['/home/runner/.ya/build/build_root/8ptz/0046d2/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:19900', '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-12-23 18:58:20,949 - DEBUG - ya.test.1602925  - execute: Command pid: 1605536
2025-12-23 18:58:21,597 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) rc: 0
2025-12-23 18:58:21,598 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) elapsed time (sec): 0.647796630859375
2025-12-23 18:58:21,598 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) idrss: 0
2025-12-23 18:58:21,598 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) inblock: 0
2025-12-23 18:58:21,599 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) isrss: 0
2025-12-23 18:58:21,599 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) ixrss: 0
2025-12-23 18:58:21,599 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) majflt: 0
2025-12-23 18:58:21,600 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) maxrss: 578700
2025-12-23 18:58:21,600 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) minflt: 52352
2025-12-23 18:58:21,600 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) msgrcv: 0
2025-12-23 18:58:21,600 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) msgsnd: 0
2025-12-23 18:58:21,601 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) nivcsw: 3
2025-12-23 18:58:21,601 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) nsignals: 0
2025-12-23 18:58:21,601 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) nswap: 0
2025-12-23 18:58:21,601 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) nvcsw: 678
2025-12-23 18:58:21,602 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) oublock: 8
2025-12-23 18:58:21,602 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) stime: 0.173674
2025-12-23 18:58:21,602 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) utime: 0.445256
2025-12-23 18:58:21,603 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) wtime: 0.649
2025-12-23 18:58:21,603 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-23 18:58:21,603 - DEBUG - ya.test.1602925  - _save_outputs: Command (pid 1605536) errors:

2025-12-23 18:58:21,604 - INFO - ydb.tests.library.harness.kikimr_runner.1602925  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-23 18:58:21,748 - INFO - ydb.tests.library.harness.kikimr_runner.1602925  - __run: Cluster started and initialized
2025-12-23 18:58:21,789 - DEBUG - ydb.connection.1602925  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 18:58:21,792 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1602925  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 18:58:21,793 - DEBUG - ydb.connection.1602925  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 18:58:21,807 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1602925  - context_resolve: Resolving endpoints for database /Root
2025-12-23 18:58:21,809 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ListEndpoints, 8be57490-11e6-463b-9931-dbdb45529d19, localhost:19900): creating call state
2025-12-23 18:58:21,810 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ListEndpoints, 8be57490-11e6-463b-9931-dbdb45529d19, localhost:19900): request = { database: "/Root" }
2025-12-23 18:58:21,816 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ListEndpoints, 8be57490-11e6-463b-9931-dbdb45529d19, localhost:19900): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ou4bsyjxme.auto.internal" port: 19900 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-12-23 18:58:21,817 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1602925  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ou4bsyjxme.auto.internal:19900, location 1, ssl: False>]>
2025-12-23 18:58:21,818 - DEBUG - ydb.connection.1602925  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 18:58:21,830 - DEBUG - ydb.connection.1602925  - close: Closing channel for endpoint localhost:19900
2025-12-23 18:58:21,831 - DEBUG - ydb.query.pool.1602925  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-23 18:58:21,833 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(CreateSession, 265f0034-a747-4c74-8676-d69fcd797a23, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:21,834 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(CreateSession, 265f0034-a747-4c74-8676-d69fcd797a23, ghrun-ou4bsyjxme.auto.internal:19900): request = {  }
2025-12-23 18:58:21,842 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(CreateSession, 265f0034-a747-4c74-8676-d69fcd797a23, ghrun-ou4bsyjxme.auto.internal:19900): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" node_id: 1 }
2025-12-23 18:58:21,843 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(AttachSession, 4c5b1b5a-5162-4132-afb4-c875718cb895, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:21,844 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(AttachSession, 4c5b1b5a-5162-4132-afb4-c875718cb895, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" }
2025-12-23 18:58:21,845 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(AttachSession, 4c5b1b5a-5162-4132-afb4-c875718cb895, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:21,848 - DEBUG - ydb.query.pool.1602925  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:21,851 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 906ae01e-901c-4585-94f5-ef95eabc214d, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:21,853 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 906ae01e-901c-4585-94f5-ef95eabc214d, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:21,854 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 906ae01e-901c-4585-94f5-ef95eabc214d, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:23,522 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:23,523 - INFO - __tests__.alter_compression.1602925  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-12-23 18:58:23,523 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:23,524 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, d2144014-f6b5-4b4d-9882-a286f7a9c0e8, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:23,525 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, d2144014-f6b5-4b4d-9882-a286f7a9c0e8, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:23,526 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, d2144014-f6b5-4b4d-9882-a286f7a9c0e8, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:24,302 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:24,303 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:24,303 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 41aeda3b-571e-4e01-a991-45501584e40d, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:24,304 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 41aeda3b-571e-4e01-a991-45501584e40d, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:24,305 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 41aeda3b-571e-4e01-a991-45501584e40d, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:25,149 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:25,150 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:25,151 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 68c98b37-8698-4b5a-97df-41d6fe037dea, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:25,152 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 68c98b37-8698-4b5a-97df-41d6fe037dea, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:25,153 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 68c98b37-8698-4b5a-97df-41d6fe037dea, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:25,783 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:25,783 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:25,784 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 43370b31-8010-48fa-94eb-5d4b3dfc1ec7, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:25,785 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 43370b31-8010-48fa-94eb-5d4b3dfc1ec7, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:25,786 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 43370b31-8010-48fa-94eb-5d4b3dfc1ec7, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:26,934 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:26,935 - INFO - __tests__.alter_compression.1602925  - 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-12-23 18:58:26,935 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:26,936 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, b0202b6f-abce-42e6-966d-bd6d191c0872, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:26,937 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, b0202b6f-abce-42e6-966d-bd6d191c0872, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:26,938 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, b0202b6f-abce-42e6-966d-bd6d191c0872, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:27,601 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:27,602 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:27,602 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 40b67e52-ff28-4813-ac7b-a56645414909, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:27,603 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 40b67e52-ff28-4813-ac7b-a56645414909, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:27,604 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 40b67e52-ff28-4813-ac7b-a56645414909, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:27,758 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:27,759 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:27,759 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 23ffa0f7-f47a-48f5-8b6a-19665f62f9c2, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:27,760 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 23ffa0f7-f47a-48f5-8b6a-19665f62f9c2, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:27,761 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 23ffa0f7-f47a-48f5-8b6a-19665f62f9c2, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:27,978 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:27,979 - INFO - __tests__.alter_compression.1602925  - 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-12-23 18:58:27,979 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:27,979 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, dbc7cb11-fea7-4bc7-ac00-3faa3ca3e2e3, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:27,980 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, dbc7cb11-fea7-4bc7-ac00-3faa3ca3e2e3, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:27,981 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, dbc7cb11-fea7-4bc7-ac00-3faa3ca3e2e3, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:28,603 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:28,604 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:28,604 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 5a1abd74-f69d-4671-8115-94856c7720fd, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:28,605 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 5a1abd74-f69d-4671-8115-94856c7720fd, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:28,606 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 5a1abd74-f69d-4671-8115-94856c7720fd, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:28,762 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:28,762 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:28,763 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 49e73beb-2327-4405-b326-03dd93ec21ec, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:28,764 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 49e73beb-2327-4405-b326-03dd93ec21ec, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:28,764 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 49e73beb-2327-4405-b326-03dd93ec21ec, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:28,985 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:28,985 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 4855296, 'BlobCount': 384}}
2025-12-23 18:58:28,986 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:28,986 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, e91ed655-1a9c-43b7-a0c7-898192433053, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:28,987 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, e91ed655-1a9c-43b7-a0c7-898192433053, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:28,988 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, e91ed655-1a9c-43b7-a0c7-898192433053, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:29,544 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:29,544 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:29,545 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 2484dc25-01ba-46c7-a068-8ad284a7c673, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:29,545 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 2484dc25-01ba-46c7-a068-8ad284a7c673, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:29,546 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 2484dc25-01ba-46c7-a068-8ad284a7c673, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:29,694 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:29,695 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:29,695 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 031cd10c-e73a-47b2-ad4e-b2424ff3660e, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:29,696 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 031cd10c-e73a-47b2-ad4e-b2424ff3660e, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:29,698 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 031cd10c-e73a-47b2-ad4e-b2424ff3660e, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:29,965 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:29,966 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 6473728, 'BlobCount': 512}}
2025-12-23 18:58:29,966 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:29,967 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 6d6068f4-4832-461c-afc7-c14e856c4472, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:29,967 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 6d6068f4-4832-461c-afc7-c14e856c4472, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:29,968 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 6d6068f4-4832-461c-afc7-c14e856c4472, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:30,576 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:30,577 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:30,577 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 84edbe3f-ae27-4f63-9473-fe2d9e0896ad, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:30,578 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 84edbe3f-ae27-4f63-9473-fe2d9e0896ad, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:30,579 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 84edbe3f-ae27-4f63-9473-fe2d9e0896ad, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:30,721 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:30,722 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:30,722 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 509f5386-d33d-480d-95e9-6d14f24e056b, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:30,723 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 509f5386-d33d-480d-95e9-6d14f24e056b, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:30,723 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 509f5386-d33d-480d-95e9-6d14f24e056b, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:31,015 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:31,015 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 8092160, 'BlobCount': 640}}
2025-12-23 18:58:31,015 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:31,016 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 33fa809c-289a-4218-8c30-a03da2cd14aa, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:31,016 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 33fa809c-289a-4218-8c30-a03da2cd14aa, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:31,017 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 33fa809c-289a-4218-8c30-a03da2cd14aa, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:31,597 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:31,598 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:31,598 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 2c4d479f-3bb1-4616-8035-4c7841c5ae1e, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:31,599 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 2c4d479f-3bb1-4616-8035-4c7841c5ae1e, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:31,600 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 2c4d479f-3bb1-4616-8035-4c7841c5ae1e, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:31,752 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:31,753 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:31,754 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 286c01f2-79c2-472a-a755-4c1279db9e0b, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:31,755 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 286c01f2-79c2-472a-a755-4c1279db9e0b, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:31,756 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 286c01f2-79c2-472a-a755-4c1279db9e0b, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:32,047 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:32,048 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 9710592, 'BlobCount': 768}}
2025-12-23 18:58:32,048 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:32,049 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 52107b98-44d5-4b53-bc15-8d545711af65, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:32,049 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 52107b98-44d5-4b53-bc15-8d545711af65, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:32,050 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 52107b98-44d5-4b53-bc15-8d545711af65, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:32,700 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:32,701 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:32,701 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 3d5aa0e3-7115-4942-9cf7-9669743eca53, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:32,702 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 3d5aa0e3-7115-4942-9cf7-9669743eca53, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:32,703 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 3d5aa0e3-7115-4942-9cf7-9669743eca53, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:32,860 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:32,861 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:32,861 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, ba070bfb-8867-4aa3-b4a5-23ebb05db4db, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:32,862 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, ba070bfb-8867-4aa3-b4a5-23ebb05db4db, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:32,863 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, ba070bfb-8867-4aa3-b4a5-23ebb05db4db, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:33,163 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:33,163 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 11329024, 'BlobCount': 896}}
2025-12-23 18:58:33,164 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:33,164 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 2968472d-86db-4fca-adff-11e70605876a, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:33,165 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 2968472d-86db-4fca-adff-11e70605876a, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:33,166 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 2968472d-86db-4fca-adff-11e70605876a, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:33,834 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:33,834 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:33,835 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 50e2c3c1-71eb-4b0d-b371-ddfa47778ca7, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:33,835 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 50e2c3c1-71eb-4b0d-b371-ddfa47778ca7, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:33,836 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 50e2c3c1-71eb-4b0d-b371-ddfa47778ca7, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:33,965 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:33,966 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:33,966 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, e7317c9c-0403-4f70-99ba-7a1e7c3fe622, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:33,967 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, e7317c9c-0403-4f70-99ba-7a1e7c3fe622, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:33,968 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, e7317c9c-0403-4f70-99ba-7a1e7c3fe622, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:34,340 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:34,340 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 12947456, 'BlobCount': 1024}}
2025-12-23 18:58:34,341 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:34,341 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 051d2587-74a9-40c1-8c39-15359140c318, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:34,342 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 051d2587-74a9-40c1-8c39-15359140c318, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:34,343 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 051d2587-74a9-40c1-8c39-15359140c318, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:35,019 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:35,020 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:35,020 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, b15b0805-338a-4873-ba24-26f86b165e2c, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:35,021 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, b15b0805-338a-4873-ba24-26f86b165e2c, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:35,022 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, b15b0805-338a-4873-ba24-26f86b165e2c, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:35,180 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:35,181 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:35,182 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, ce03b1c7-416d-4334-a6c1-3d34b69bab64, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:35,183 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, ce03b1c7-416d-4334-a6c1-3d34b69bab64, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:35,184 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, ce03b1c7-416d-4334-a6c1-3d34b69bab64, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:35,603 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:35,604 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 14565888, 'BlobCount': 1152}}
2025-12-23 18:58:35,605 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:35,605 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, b5df83db-d7d7-420c-a0be-5c73166f87ec, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:35,606 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, b5df83db-d7d7-420c-a0be-5c73166f87ec, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:35,608 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, b5df83db-d7d7-420c-a0be-5c73166f87ec, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:36,262 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:36,262 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:36,263 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, aad7b459-5d1e-4ab2-a575-7cd86da467e0, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:36,264 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, aad7b459-5d1e-4ab2-a575-7cd86da467e0, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:36,265 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, aad7b459-5d1e-4ab2-a575-7cd86da467e0, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:36,414 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:36,414 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:36,415 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 10594e5c-8864-4cdd-ae9d-d70b1b4e7d54, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:36,416 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 10594e5c-8864-4cdd-ae9d-d70b1b4e7d54, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:36,417 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 10594e5c-8864-4cdd-ae9d-d70b1b4e7d54, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:36,895 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:36,895 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 16184320, 'BlobCount': 1280}}
2025-12-23 18:58:36,896 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:36,897 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, cd92f761-a99d-40b3-bff2-d6cc0956fa38, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:36,898 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, cd92f761-a99d-40b3-bff2-d6cc0956fa38, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:36,899 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, cd92f761-a99d-40b3-bff2-d6cc0956fa38, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:37,580 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:37,581 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:37,581 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, f891db32-8f4e-4a00-b014-0441fe8ada38, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:37,582 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, f891db32-8f4e-4a00-b014-0441fe8ada38, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:37,583 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, f891db32-8f4e-4a00-b014-0441fe8ada38, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:37,792 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:37,793 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:37,794 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, e2009f51-8ab2-47b0-b78b-36186ff42836, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:37,795 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, e2009f51-8ab2-47b0-b78b-36186ff42836, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:37,796 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, e2009f51-8ab2-47b0-b78b-36186ff42836, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:37,977 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:37,978 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:37,979 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 7965e373-334a-4e9b-ab7e-2446c822849d, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:37,980 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 7965e373-334a-4e9b-ab7e-2446c822849d, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:37,981 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 7965e373-334a-4e9b-ab7e-2446c822849d, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:40,555 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:50,585 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:50,586 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 44d1a24a-ddfb-4432-b6e8-5fef3ab68668, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:50,587 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 44d1a24a-ddfb-4432-b6e8-5fef3ab68668, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:50,588 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 44d1a24a-ddfb-4432-b6e8-5fef3ab68668, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:52,962 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:52,988 - INFO - root.1602925  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-12-23 18:58:52,989 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:58:52,989 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 0f82821b-061c-467e-a2e0-154563020498, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:58:52,990 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 0f82821b-061c-467e-a2e0-154563020498, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:58:52,991 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 0f82821b-061c-467e-a2e0-154563020498, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:58:55,697 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:05,749 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:05,751 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 078bb23e-43f2-4b5c-99f5-ce84e5bfc58f, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:05,751 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 078bb23e-43f2-4b5c-99f5-ce84e5bfc58f, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:05,753 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 078bb23e-43f2-4b5c-99f5-ce84e5bfc58f, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:08,425 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:08,483 - INFO - root.1602925  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-12-23 18:59:08,484 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:08,484 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 20517cbe-140d-406a-ac3d-51533cbd948a, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:08,485 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 20517cbe-140d-406a-ac3d-51533cbd948a, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:08,486 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 20517cbe-140d-406a-ac3d-51533cbd948a, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:08,727 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:08,729 - INFO - ya.test.1602925  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_8_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 8])
2025-12-23 18:59:08,730 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:08,730 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 6ef2b69b-69a8-4fca-88f2-5a6bdc3605ed, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:08,731 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 6ef2b69b-69a8-4fca-88f2-5a6bdc3605ed, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_8_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 8)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:08,732 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 6ef2b69b-69a8-4fca-88f2-5a6bdc3605ed, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:11,093 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:11,094 - INFO - __tests__.alter_compression.1602925  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_8_compression created
2025-12-23 18:59:11,095 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:11,095 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 26d50120-de9a-4ede-a85b-0298bf69e1be, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:11,096 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 26d50120-de9a-4ede-a85b-0298bf69e1be, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_8_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:11,097 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 26d50120-de9a-4ede-a85b-0298bf69e1be, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:11,764 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:11,765 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:11,765 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 3a6053ec-618e-458e-9268-b6b43d1c43d5, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:11,766 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 3a6053ec-618e-458e-9268-b6b43d1c43d5, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:11,767 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 3a6053ec-618e-458e-9268-b6b43d1c43d5, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:13,420 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:13,421 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:13,422 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 5c549c08-3532-43b0-aae0-70f47b2f94b0, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:13,422 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 5c549c08-3532-43b0-aae0-70f47b2f94b0, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:13,423 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 5c549c08-3532-43b0-aae0-70f47b2f94b0, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:13,998 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:13,999 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:13,999 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 29f389ed-beee-4ff0-8417-f1589e9021a6, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:14,000 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 29f389ed-beee-4ff0-8417-f1589e9021a6, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:14,001 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 29f389ed-beee-4ff0-8417-f1589e9021a6, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:15,619 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:15,619 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318016, 'BlobCount': 128}}
2025-12-23 18:59:15,620 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:15,620 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 423ce2aa-7b88-41c8-88d4-6d5929e1bc85, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:15,621 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 423ce2aa-7b88-41c8-88d4-6d5929e1bc85, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:15,623 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 423ce2aa-7b88-41c8-88d4-6d5929e1bc85, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:17,041 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:17,042 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:17,043 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 0b593ba1-741f-4be5-8454-c71c8313ed2d, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:17,044 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 0b593ba1-741f-4be5-8454-c71c8313ed2d, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:17,045 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 0b593ba1-741f-4be5-8454-c71c8313ed2d, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:17,308 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:17,309 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:17,310 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 1da5f510-516b-4838-9b3d-0609dcb68266, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:17,311 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 1da5f510-516b-4838-9b3d-0609dcb68266, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:17,312 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 1da5f510-516b-4838-9b3d-0609dcb68266, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:17,615 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:17,616 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637312, 'BlobCount': 256}}
2025-12-23 18:59:17,616 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:17,616 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 12633cb5-b842-456d-85ea-285063bb6750, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:17,617 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 12633cb5-b842-456d-85ea-285063bb6750, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:17,618 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 12633cb5-b842-456d-85ea-285063bb6750, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:19,145 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:19,146 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:19,147 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 17ac43f8-4fdc-4ea7-bf90-eabe071128ed, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:19,147 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 17ac43f8-4fdc-4ea7-bf90-eabe071128ed, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:19,148 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 17ac43f8-4fdc-4ea7-bf90-eabe071128ed, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:19,329 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:19,330 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:19,330 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 90ee5c7c-c6da-4562-b707-4c33a4d6ad11, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:19,331 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 90ee5c7c-c6da-4562-b707-4c33a4d6ad11, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:19,333 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 90ee5c7c-c6da-4562-b707-4c33a4d6ad11, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:19,626 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:19,627 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956352, 'BlobCount': 384}}
2025-12-23 18:59:19,627 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:19,628 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, c1ebc993-b909-4e04-af1d-0d7d13f2b8d0, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:19,629 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, c1ebc993-b909-4e04-af1d-0d7d13f2b8d0, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:19,630 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, c1ebc993-b909-4e04-af1d-0d7d13f2b8d0, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:21,232 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:21,233 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:21,233 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, cac0be0c-5ff5-4908-b554-e7f85a9baf10, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:21,234 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, cac0be0c-5ff5-4908-b554-e7f85a9baf10, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:21,235 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, cac0be0c-5ff5-4908-b554-e7f85a9baf10, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:21,456 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:21,457 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:21,458 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 2d9dc0a8-5966-4a1b-9d42-0e0fd8260e42, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:21,458 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 2d9dc0a8-5966-4a1b-9d42-0e0fd8260e42, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:21,460 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 2d9dc0a8-5966-4a1b-9d42-0e0fd8260e42, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:21,807 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:21,808 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275712, 'BlobCount': 512}}
2025-12-23 18:59:21,808 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:21,809 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, ca67d088-b93f-41bf-b771-8103c16f8dbe, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:21,810 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, ca67d088-b93f-41bf-b771-8103c16f8dbe, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:21,812 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, ca67d088-b93f-41bf-b771-8103c16f8dbe, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:23,815 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:23,815 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:23,816 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 9e0c34b2-9549-4153-82fc-e4aa1d239870, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:23,817 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 9e0c34b2-9549-4153-82fc-e4aa1d239870, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:23,818 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 9e0c34b2-9549-4153-82fc-e4aa1d239870, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:24,013 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:24,013 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:24,014 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 83c2bdf3-313a-410c-8292-9b59fa0463d8, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:24,015 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 83c2bdf3-313a-410c-8292-9b59fa0463d8, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:24,016 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 83c2bdf3-313a-410c-8292-9b59fa0463d8, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:24,419 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:24,419 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594672, 'BlobCount': 640}}
2025-12-23 18:59:24,420 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:24,420 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 9853e0d7-eece-4b05-bd9a-0768bab3dda2, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:24,421 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 9853e0d7-eece-4b05-bd9a-0768bab3dda2, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:24,422 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 9853e0d7-eece-4b05-bd9a-0768bab3dda2, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:25,937 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:25,938 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:25,938 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 3740503d-d49b-43cb-8e31-12c98eb260d2, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:25,939 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 3740503d-d49b-43cb-8e31-12c98eb260d2, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:25,940 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 3740503d-d49b-43cb-8e31-12c98eb260d2, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:26,185 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:26,185 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:26,186 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 26594005-497a-46ea-b6e7-95460123194e, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:26,187 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 26594005-497a-46ea-b6e7-95460123194e, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:26,188 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 26594005-497a-46ea-b6e7-95460123194e, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:26,572 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:26,573 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913856, 'BlobCount': 768}}
2025-12-23 18:59:26,573 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:26,574 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, b3c0674f-c792-4d0d-ae8f-b8e2d89b3132, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:26,575 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, b3c0674f-c792-4d0d-ae8f-b8e2d89b3132, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:26,576 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, b3c0674f-c792-4d0d-ae8f-b8e2d89b3132, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:27,778 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:27,779 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:27,779 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 7c908e71-5006-440d-bdae-af7395f6df9f, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:27,780 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 7c908e71-5006-440d-bdae-af7395f6df9f, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:27,781 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 7c908e71-5006-440d-bdae-af7395f6df9f, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:28,013 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:28,014 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:28,014 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 9b0e6888-bbd5-4511-9e07-9edbe5f449c2, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:28,015 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 9b0e6888-bbd5-4511-9e07-9edbe5f449c2, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:28,016 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 9b0e6888-bbd5-4511-9e07-9edbe5f449c2, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:28,607 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:28,608 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2232992, 'BlobCount': 896}}
2025-12-23 18:59:28,609 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:28,609 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 83f7ee5c-93e1-47ee-8cc1-3ede975d5638, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:28,611 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 83f7ee5c-93e1-47ee-8cc1-3ede975d5638, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:28,612 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 83f7ee5c-93e1-47ee-8cc1-3ede975d5638, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:30,113 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:30,114 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:30,114 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 4790e2b7-1621-48f4-8031-6e62635fbc48, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:30,115 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 4790e2b7-1621-48f4-8031-6e62635fbc48, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:30,117 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 4790e2b7-1621-48f4-8031-6e62635fbc48, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:30,342 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:30,343 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:30,344 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 479e060b-9b9d-4f9f-ae6f-88b71a62b711, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:30,345 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 479e060b-9b9d-4f9f-ae6f-88b71a62b711, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:30,346 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 479e060b-9b9d-4f9f-ae6f-88b71a62b711, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:30,846 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:30,846 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552480, 'BlobCount': 1024}}
2025-12-23 18:59:30,847 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:30,848 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 956bbf61-afb5-4476-8719-5a1e6adf1512, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:30,849 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 956bbf61-afb5-4476-8719-5a1e6adf1512, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:30,850 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 956bbf61-afb5-4476-8719-5a1e6adf1512, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:32,403 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:32,404 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:32,405 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, aa946a6e-714d-433d-a8f2-72b084e6c20b, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:32,406 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, aa946a6e-714d-433d-a8f2-72b084e6c20b, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:32,407 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, aa946a6e-714d-433d-a8f2-72b084e6c20b, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:32,635 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:32,635 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:32,636 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, e94082c3-cc98-40cd-86c6-ee336d6fd374, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:32,637 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, e94082c3-cc98-40cd-86c6-ee336d6fd374, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:32,638 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, e94082c3-cc98-40cd-86c6-ee336d6fd374, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:33,275 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:33,275 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871488, 'BlobCount': 1152}}
2025-12-23 18:59:33,276 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:33,276 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 4ae463cc-6856-4b9b-970a-17f731951cc7, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:33,277 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 4ae463cc-6856-4b9b-970a-17f731951cc7, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:33,278 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 4ae463cc-6856-4b9b-970a-17f731951cc7, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:34,703 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:34,704 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:34,704 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 7d424326-f909-4827-93bb-b9d2a4a7c14c, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:34,705 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 7d424326-f909-4827-93bb-b9d2a4a7c14c, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:34,706 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 7d424326-f909-4827-93bb-b9d2a4a7c14c, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:34,930 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:34,930 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:34,931 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, a62309d1-e1f3-4587-b67e-8958c2710d32, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:34,932 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, a62309d1-e1f3-4587-b67e-8958c2710d32, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:34,933 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, a62309d1-e1f3-4587-b67e-8958c2710d32, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:35,566 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:35,567 - INFO - __tests__.alter_compression.1602925  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3190992, 'BlobCount': 1280}}
2025-12-23 18:59:35,567 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:35,568 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, a4be000c-6b3d-4cfa-b59a-dda224998de4, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:35,569 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, a4be000c-6b3d-4cfa-b59a-dda224998de4, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_8_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:35,570 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, a4be000c-6b3d-4cfa-b59a-dda224998de4, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:36,522 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:36,522 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:36,523 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 3a67fbc4-507b-4c89-971d-2f0fca6e2dab, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:36,524 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 3a67fbc4-507b-4c89-971d-2f0fca6e2dab, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:36,525 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 3a67fbc4-507b-4c89-971d-2f0fca6e2dab, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:36,782 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:36,783 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:36,784 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 293e4569-4439-450f-93b8-9bf8bbebb109, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:36,785 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 293e4569-4439-450f-93b8-9bf8bbebb109, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:36,786 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 293e4569-4439-450f-93b8-9bf8bbebb109, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:37,021 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:37,022 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:37,023 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 57355dc4-c3a3-404d-a0bd-ba7ec8700d45, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:37,023 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 57355dc4-c3a3-404d-a0bd-ba7ec8700d45, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:37,024 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 57355dc4-c3a3-404d-a0bd-ba7ec8700d45, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:40,085 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:50,132 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:50,133 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 142662a5-d0a0-4d14-bdfb-15f85b44a620, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:50,134 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 142662a5-d0a0-4d14-bdfb-15f85b44a620, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:50,136 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 142662a5-d0a0-4d14-bdfb-15f85b44a620, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:53,300 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:53,346 - INFO - root.1602925  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_8_compression`, volumes `value` (8000000, 1595496)
2025-12-23 18:59:53,347 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:53,348 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 3d53d021-c0fe-4547-82ef-ff55d58776d5, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:53,349 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 3d53d021-c0fe-4547-82ef-ff55d58776d5, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:53,351 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 3d53d021-c0fe-4547-82ef-ff55d58776d5, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:53,566 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:53,567 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:53,568 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 85485ed9-b391-4ede-95c4-1032e8f6bc51, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 18:59:53,568 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 85485ed9-b391-4ede-95c4-1032e8f6bc51, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 18:59:53,570 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 85485ed9-b391-4ede-95c4-1032e8f6bc51, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 18:59:56,756 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 18:59:59,461 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1602925  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 18:59:59,462 - DEBUG - ydb.connection.1602925  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 18:59:59,472 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1602925  - context_resolve: Resolving endpoints for database /Root
2025-12-23 18:59:59,473 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ListEndpoints, 581068da-4f95-4120-9461-da4fcbe79157, localhost:19900): creating call state
2025-12-23 18:59:59,473 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ListEndpoints, 581068da-4f95-4120-9461-da4fcbe79157, localhost:19900): request = { database: "/Root" }
2025-12-23 18:59:59,478 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ListEndpoints, 581068da-4f95-4120-9461-da4fcbe79157, localhost:19900): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ou4bsyjxme.auto.internal" port: 19900 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-12-23 18:59:59,479 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1602925  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ou4bsyjxme.auto.internal:19900, location 1, ssl: False>]>
2025-12-23 18:59:59,479 - DEBUG - ydb.connection.1602925  - close: Closing channel for endpoint localhost:19900
2025-12-23 19:00:06,802 - DEBUG - ydb.query.pool.1602925  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:06,803 - DEBUG - ydb.connection.1602925  - _prepare_call: RpcState(ExecuteQuery, 5dde0432-b298-42b2-9b6d-60b8ed29abf6, ghrun-ou4bsyjxme.auto.internal:19900): creating call state
2025-12-23 19:00:06,804 - DEBUG - ydb.connection.1602925  - _log_request: RpcState(ExecuteQuery, 5dde0432-b298-42b2-9b6d-60b8ed29abf6, ghrun-ou4bsyjxme.auto.internal:19900): request = { session_id: "ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:00:06,805 - DEBUG - ydb.connection.1602925  - _log_response: RpcState(ExecuteQuery, 5dde0432-b298-42b2-9b6d-60b8ed29abf6, ghrun-ou4bsyjxme.auto.internal:19900): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:00:09,848 - DEBUG - ydb.query.pool.1602925  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNlZGJjMzItMjE4ZDFkZWEtOTNhYTA1ZDgtMjM0MWE1NzE=
2025-12-23 19:00:09,897 - INFO - root.1602925  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_8_compression`, volumes `value` (8000000, 1595496)
2025-12-23 19:00:09,897 - INFO - root.1602925  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_8_compression` 8092160 / 1595496: 5.071877334697173
2025-12-23 19:00:09,900 - INFO - ya.test.1602925  - pytest_runtest_teardown: Test teardown
