2025-10-15 15:11:55,110 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:11:55,111 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_18_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 18]
2025-10-15 15:11:55,111 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:11:55,111 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-15 15:11:55,114 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-15 15:11:55,115 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-15 15:11:55,115 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001be2/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:11:55,125 - DEBUG - ya.test - execute: Command pid: 568522
2025-10-15 15:11:56,206 - DEBUG - ya.test - _save_outputs: Command (pid 568522) rc: 0
2025-10-15 15:11:56,207 - DEBUG - ya.test - _save_outputs: Command (pid 568522) elapsed time (sec): 1.0800929069519043
2025-10-15 15:11:56,207 - DEBUG - ya.test - _save_outputs: Command (pid 568522) idrss: 0
2025-10-15 15:11:56,208 - DEBUG - ya.test - _save_outputs: Command (pid 568522) inblock: 0
2025-10-15 15:11:56,208 - DEBUG - ya.test - _save_outputs: Command (pid 568522) isrss: 0
2025-10-15 15:11:56,208 - DEBUG - ya.test - _save_outputs: Command (pid 568522) ixrss: 0
2025-10-15 15:11:56,209 - DEBUG - ya.test - _save_outputs: Command (pid 568522) majflt: 0
2025-10-15 15:11:56,209 - DEBUG - ya.test - _save_outputs: Command (pid 568522) maxrss: 478440
2025-10-15 15:11:56,209 - DEBUG - ya.test - _save_outputs: Command (pid 568522) minflt: 45051
2025-10-15 15:11:56,209 - DEBUG - ya.test - _save_outputs: Command (pid 568522) msgrcv: 0
2025-10-15 15:11:56,210 - DEBUG - ya.test - _save_outputs: Command (pid 568522) msgsnd: 0
2025-10-15 15:11:56,210 - DEBUG - ya.test - _save_outputs: Command (pid 568522) nivcsw: 177
2025-10-15 15:11:56,210 - DEBUG - ya.test - _save_outputs: Command (pid 568522) nsignals: 0
2025-10-15 15:11:56,211 - DEBUG - ya.test - _save_outputs: Command (pid 568522) nswap: 0
2025-10-15 15:11:56,211 - DEBUG - ya.test - _save_outputs: Command (pid 568522) nvcsw: 14
2025-10-15 15:11:56,211 - DEBUG - ya.test - _save_outputs: Command (pid 568522) oublock: 8
2025-10-15 15:11:56,211 - DEBUG - ya.test - _save_outputs: Command (pid 568522) stime: 0.207235
2025-10-15 15:11:56,212 - DEBUG - ya.test - _save_outputs: Command (pid 568522) utime: 0.547272
2025-10-15 15:11:56,212 - DEBUG - ya.test - _save_outputs: Command (pid 568522) wtime: 1.09
2025-10-15 15:11:56,213 - DEBUG - ya.test - _save_outputs: Command (pid 568522) output:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

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

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

2025-10-15 15:11:56,214 - DEBUG - ya.test - _save_outputs: Command (pid 568522) errors:

2025-10-15 15:11:56,214 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

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

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

2025-10-15 15:11:56,354 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001be2/ydb/apps/ydbd/ydbd
2025-10-15 15:11:56,355 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25658
2025-10-15 15:11:56,359 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/w2kl/001be2/r3tmp/kikimr_cluster_9w41j2f4
2025-10-15 15:11:56,433 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001be2/ydb/apps/ydbd/ydbd
2025-10-15 15:11:56,434 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1039
2025-10-15 15:11:56,435 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26984
2025-10-15 15:11:56,435 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19817
2025-10-15 15:11:56,436 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11333
2025-10-15 15:11:56,437 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001be2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs"
2025-10-15 15:11:56,437 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001be2/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/w2kl/001be2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_xo09vjkn.log --grpc-port=1039 --mon-port=26984 --ic-port=25658
2025-10-15 15:11:56,438 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:1039/1, disk_size 68719476736
2025-10-15 15:11:56,438 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001be2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs"
2025-10-15 15:11:56,439 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001be2/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/w2kl/001be2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_xo09vjkn.log --grpc-port=1039 --mon-port=26984 --ic-port=25658
2025-10-15 15:11:56,440 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/w2kl/001be2/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/w2kl/001be2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/w2kl/001be2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_xo09vjkn.log', '--grpc-port=1039', '--mon-port=26984', '--ic-port=25658')' in '/home/runner/.ya/build/build_root/w2kl/001be2/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1' (no wait)
2025-10-15 15:11:56,446 - DEBUG - ya.test - execute: Command pid: 568650
2025-10-15 15:11:56,447 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:1039/1
2025-10-15 15:11:56,449 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-15 15:11:56,450 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-15 15:11:56,451 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001be2/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:11:56,457 - DEBUG - ya.test - execute: Command pid: 568651
2025-10-15 15:11:58,019 - DEBUG - ya.test - _save_outputs: Command (pid 568651) rc: 0
2025-10-15 15:11:58,019 - DEBUG - ya.test - _save_outputs: Command (pid 568651) elapsed time (sec): 1.560713529586792
2025-10-15 15:11:58,020 - DEBUG - ya.test - _save_outputs: Command (pid 568651) idrss: 0
2025-10-15 15:11:58,020 - DEBUG - ya.test - _save_outputs: Command (pid 568651) inblock: 0
2025-10-15 15:11:58,020 - DEBUG - ya.test - _save_outputs: Command (pid 568651) isrss: 0
2025-10-15 15:11:58,021 - DEBUG - ya.test - _save_outputs: Command (pid 568651) ixrss: 0
2025-10-15 15:11:58,021 - DEBUG - ya.test - _save_outputs: Command (pid 568651) majflt: 0
2025-10-15 15:11:58,021 - DEBUG - ya.test - _save_outputs: Command (pid 568651) maxrss: 479860
2025-10-15 15:11:58,022 - DEBUG - ya.test - _save_outputs: Command (pid 568651) minflt: 45163
2025-10-15 15:11:58,022 - DEBUG - ya.test - _save_outputs: Command (pid 568651) msgrcv: 0
2025-10-15 15:11:58,022 - DEBUG - ya.test - _save_outputs: Command (pid 568651) msgsnd: 0
2025-10-15 15:11:58,023 - DEBUG - ya.test - _save_outputs: Command (pid 568651) nivcsw: 308
2025-10-15 15:11:58,023 - DEBUG - ya.test - _save_outputs: Command (pid 568651) nsignals: 0
2025-10-15 15:11:58,023 - DEBUG - ya.test - _save_outputs: Command (pid 568651) nswap: 0
2025-10-15 15:11:58,024 - DEBUG - ya.test - _save_outputs: Command (pid 568651) nvcsw: 14
2025-10-15 15:11:58,024 - DEBUG - ya.test - _save_outputs: Command (pid 568651) oublock: 8
2025-10-15 15:11:58,024 - DEBUG - ya.test - _save_outputs: Command (pid 568651) stime: 0.224233
2025-10-15 15:11:58,026 - DEBUG - ya.test - _save_outputs: Command (pid 568651) utime: 0.5481929999999999
2025-10-15 15:11:58,026 - DEBUG - ya.test - _save_outputs: Command (pid 568651) wtime: 1.567
2025-10-15 15:11:58,027 - DEBUG - ya.test - _save_outputs: Command (pid 568651) output:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

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

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

2025-10-15 15:11:58,027 - DEBUG - ya.test - _save_outputs: Command (pid 568651) errors:

2025-10-15 15:11:58,028 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b
2025-10-15 15:12:03,833 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001be2/ydb/apps/ydbd/ydbd
2025-10-15 15:12:03,834 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2kl/001be2/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:1039', '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: 25658\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-15 15:12:03,835 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-15 15:12:03,836 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-15 15:12:03,836 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001be2/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:1039', '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: 25658\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-15 15:12:03,841 - DEBUG - ya.test - execute: Command pid: 570516
2025-10-15 15:12:05,537 - DEBUG - ya.test - _save_outputs: Command (pid 570516) rc: 0
2025-10-15 15:12:05,538 - DEBUG - ya.test - _save_outputs: Command (pid 570516) elapsed time (sec): 1.6952509880065918
2025-10-15 15:12:05,538 - DEBUG - ya.test - _save_outputs: Command (pid 570516) idrss: 0
2025-10-15 15:12:05,538 - DEBUG - ya.test - _save_outputs: Command (pid 570516) inblock: 0
2025-10-15 15:12:05,538 - DEBUG - ya.test - _save_outputs: Command (pid 570516) isrss: 0
2025-10-15 15:12:05,539 - DEBUG - ya.test - _save_outputs: Command (pid 570516) ixrss: 0
2025-10-15 15:12:05,539 - DEBUG - ya.test - _save_outputs: Command (pid 570516) majflt: 0
2025-10-15 15:12:05,539 - DEBUG - ya.test - _save_outputs: Command (pid 570516) maxrss: 594468
2025-10-15 15:12:05,540 - DEBUG - ya.test - _save_outputs: Command (pid 570516) minflt: 51509
2025-10-15 15:12:05,540 - DEBUG - ya.test - _save_outputs: Command (pid 570516) msgrcv: 0
2025-10-15 15:12:05,540 - DEBUG - ya.test - _save_outputs: Command (pid 570516) msgsnd: 0
2025-10-15 15:12:05,540 - DEBUG - ya.test - _save_outputs: Command (pid 570516) nivcsw: 312
2025-10-15 15:12:05,541 - DEBUG - ya.test - _save_outputs: Command (pid 570516) nsignals: 0
2025-10-15 15:12:05,552 - DEBUG - ya.test - _save_outputs: Command (pid 570516) nswap: 0
2025-10-15 15:12:05,553 - DEBUG - ya.test - _save_outputs: Command (pid 570516) nvcsw: 627
2025-10-15 15:12:05,553 - DEBUG - ya.test - _save_outputs: Command (pid 570516) oublock: 8
2025-10-15 15:12:05,553 - DEBUG - ya.test - _save_outputs: Command (pid 570516) stime: 0.27787999999999996
2025-10-15 15:12:05,554 - DEBUG - ya.test - _save_outputs: Command (pid 570516) utime: 0.593194
2025-10-15 15:12:05,554 - DEBUG - ya.test - _save_outputs: Command (pid 570516) wtime: 1.7
2025-10-15 15:12:05,554 - DEBUG - ya.test - _save_outputs: Command (pid 570516) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-15 15:12:05,555 - DEBUG - ya.test - _save_outputs: Command (pid 570516) errors:

2025-10-15 15:12:05,556 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001be2/ydb/apps/ydbd/ydbd
2025-10-15 15:12:05,556 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2kl/001be2/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:1039', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-10-15 15:12:05,559 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-15 15:12:05,559 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-15 15:12:05,560 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001be2/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:1039', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-15 15:12:05,575 - DEBUG - ya.test - execute: Command pid: 571147
2025-10-15 15:12:07,101 - DEBUG - ya.test - _save_outputs: Command (pid 571147) rc: 0
2025-10-15 15:12:07,102 - DEBUG - ya.test - _save_outputs: Command (pid 571147) elapsed time (sec): 1.5248637199401855
2025-10-15 15:12:07,102 - DEBUG - ya.test - _save_outputs: Command (pid 571147) idrss: 0
2025-10-15 15:12:07,102 - DEBUG - ya.test - _save_outputs: Command (pid 571147) inblock: 0
2025-10-15 15:12:07,103 - DEBUG - ya.test - _save_outputs: Command (pid 571147) isrss: 0
2025-10-15 15:12:07,103 - DEBUG - ya.test - _save_outputs: Command (pid 571147) ixrss: 0
2025-10-15 15:12:07,103 - DEBUG - ya.test - _save_outputs: Command (pid 571147) majflt: 0
2025-10-15 15:12:07,103 - DEBUG - ya.test - _save_outputs: Command (pid 571147) maxrss: 595168
2025-10-15 15:12:07,104 - DEBUG - ya.test - _save_outputs: Command (pid 571147) minflt: 51471
2025-10-15 15:12:07,104 - DEBUG - ya.test - _save_outputs: Command (pid 571147) msgrcv: 0
2025-10-15 15:12:07,104 - DEBUG - ya.test - _save_outputs: Command (pid 571147) msgsnd: 0
2025-10-15 15:12:07,105 - DEBUG - ya.test - _save_outputs: Command (pid 571147) nivcsw: 271
2025-10-15 15:12:07,106 - DEBUG - ya.test - _save_outputs: Command (pid 571147) nsignals: 0
2025-10-15 15:12:07,106 - DEBUG - ya.test - _save_outputs: Command (pid 571147) nswap: 0
2025-10-15 15:12:07,106 - DEBUG - ya.test - _save_outputs: Command (pid 571147) nvcsw: 588
2025-10-15 15:12:07,106 - DEBUG - ya.test - _save_outputs: Command (pid 571147) oublock: 8
2025-10-15 15:12:07,107 - DEBUG - ya.test - _save_outputs: Command (pid 571147) stime: 0.30277
2025-10-15 15:12:07,107 - DEBUG - ya.test - _save_outputs: Command (pid 571147) utime: 0.564939
2025-10-15 15:12:07,107 - DEBUG - ya.test - _save_outputs: Command (pid 571147) wtime: 1.54
2025-10-15 15:12:07,108 - DEBUG - ya.test - _save_outputs: Command (pid 571147) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-15 15:12:07,108 - DEBUG - ya.test - _save_outputs: Command (pid 571147) errors:

2025-10-15 15:12:07,108 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-15 15:12:07,314 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-15 15:12:07,362 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-15 15:12:07,368 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:12:07,368 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-15 15:12:07,406 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:12:07,406 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, b16f5491-1da1-4c0e-b74d-1a276c20d83e, localhost:1039): creating call state
2025-10-15 15:12:07,407 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, b16f5491-1da1-4c0e-b74d-1a276c20d83e, localhost:1039): request = { database: "/Root" }
2025-10-15 15:12:07,421 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, b16f5491-1da1-4c0e-b74d-1a276c20d83e, localhost:1039): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 1039 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-10-15 15:12:07,422 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:1039, location 1, ssl: False>]>
2025-10-15 15:12:07,426 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-15 15:12:07,451 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1039
2025-10-15 15:12:07,453 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-10-15 15:12:07,455 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 7e8b6aaa-bcaf-40c4-bf67-73684c6cab44, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:07,455 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 7e8b6aaa-bcaf-40c4-bf67-73684c6cab44, ghrun-lvxbqnxafi.auto.internal:1039): request = {  }
2025-10-15 15:12:07,466 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 7e8b6aaa-bcaf-40c4-bf67-73684c6cab44, ghrun-lvxbqnxafi.auto.internal:1039): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" node_id: 1 }
2025-10-15 15:12:07,467 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, b68ec8c8-151b-430e-830a-81e3f2e3e887, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:07,467 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, b68ec8c8-151b-430e-830a-81e3f2e3e887, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" }
2025-10-15 15:12:07,479 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, b68ec8c8-151b-430e-830a-81e3f2e3e887, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:07,484 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:07,485 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1d1e456e-b494-41b9-bed0-852acf20166c, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:07,486 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1d1e456e-b494-41b9-bed0-852acf20166c, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:07,488 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1d1e456e-b494-41b9-bed0-852acf20166c, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:11,789 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:11,790 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-10-15 15:12:11,790 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:11,791 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ab66ec54-e806-4b4c-a645-a8350cf36ce5, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:11,792 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ab66ec54-e806-4b4c-a645-a8350cf36ce5, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:11,793 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ab66ec54-e806-4b4c-a645-a8350cf36ce5, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:13,441 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:13,442 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:13,443 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 48d5f264-faa4-40b1-8106-f29bf63a115d, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:13,444 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 48d5f264-faa4-40b1-8106-f29bf63a115d, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:13,448 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 48d5f264-faa4-40b1-8106-f29bf63a115d, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:14,784 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:14,785 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:14,786 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 144e3f94-7a1b-45d3-a14e-db076deb0bf4, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:14,786 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 144e3f94-7a1b-45d3-a14e-db076deb0bf4, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:14,788 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 144e3f94-7a1b-45d3-a14e-db076deb0bf4, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:16,179 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:16,180 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:16,181 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2484427f-6fb3-4198-a58d-031c050bd690, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:16,182 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2484427f-6fb3-4198-a58d-031c050bd690, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:16,185 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2484427f-6fb3-4198-a58d-031c050bd690, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:18,291 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:18,292 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 1618432, 'BlobCount': 128}}
2025-10-15 15:12:18,292 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:18,293 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cf47fbec-5a06-47f2-b454-47499f740c28, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:18,294 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cf47fbec-5a06-47f2-b454-47499f740c28, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:18,299 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cf47fbec-5a06-47f2-b454-47499f740c28, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:19,617 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:19,618 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:19,618 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 29ae24db-2ba2-4c70-9935-8468cc3ddbfd, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:19,619 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 29ae24db-2ba2-4c70-9935-8468cc3ddbfd, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:19,621 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 29ae24db-2ba2-4c70-9935-8468cc3ddbfd, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:20,134 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:20,135 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:20,136 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8799e044-46c9-4892-b612-10a224176d78, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:20,136 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8799e044-46c9-4892-b612-10a224176d78, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:20,138 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8799e044-46c9-4892-b612-10a224176d78, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:20,570 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:20,571 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 3236864, 'BlobCount': 256}}
2025-10-15 15:12:20,571 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:20,572 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5be36804-5b17-4fa5-9bee-71c40bc7483f, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:20,573 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5be36804-5b17-4fa5-9bee-71c40bc7483f, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:20,574 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5be36804-5b17-4fa5-9bee-71c40bc7483f, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:21,715 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:21,716 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:21,717 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 870c7c18-ff6d-4772-a386-33ca1efa47c8, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:21,718 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 870c7c18-ff6d-4772-a386-33ca1efa47c8, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:21,721 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 870c7c18-ff6d-4772-a386-33ca1efa47c8, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:21,947 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:21,948 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:21,948 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0b9a4f95-b1a9-4225-bcb1-9846689758de, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:21,949 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0b9a4f95-b1a9-4225-bcb1-9846689758de, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:21,950 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0b9a4f95-b1a9-4225-bcb1-9846689758de, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:22,601 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:22,602 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 303164, 'Portions': 184}}, blobs: {'__DEFAULT': {'Portions': 182, 'BlobSize': 4858608, 'BlobCount': 384}}
2025-10-15 15:12:22,602 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:22,603 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 18183247-9507-4bd0-bcf2-70cccbc9c154, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:22,604 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 18183247-9507-4bd0-bcf2-70cccbc9c154, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:22,605 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 18183247-9507-4bd0-bcf2-70cccbc9c154, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:23,739 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:23,740 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:23,741 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2ef01bec-7520-4535-af5a-6946d35cc089, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:23,742 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2ef01bec-7520-4535-af5a-6946d35cc089, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:23,743 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2ef01bec-7520-4535-af5a-6946d35cc089, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:24,059 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:24,060 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:24,061 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 13224557-1b2f-4074-80ce-2efb2e1668fb, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:24,062 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 13224557-1b2f-4074-80ce-2efb2e1668fb, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:24,063 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 13224557-1b2f-4074-80ce-2efb2e1668fb, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:24,620 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:24,620 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 240}}, blobs: {'__DEFAULT': {'Portions': 240, 'BlobSize': 6477520, 'BlobCount': 506}}
2025-10-15 15:12:24,621 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:24,622 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6b8d5cda-a55b-4d4e-8b5a-5cbbf2fcf840, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:24,623 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6b8d5cda-a55b-4d4e-8b5a-5cbbf2fcf840, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:24,624 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6b8d5cda-a55b-4d4e-8b5a-5cbbf2fcf840, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:25,906 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:25,907 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:25,908 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 14c5dbb4-b209-401c-bf04-2f5d923af683, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:25,908 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 14c5dbb4-b209-401c-bf04-2f5d923af683, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:25,910 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 14c5dbb4-b209-401c-bf04-2f5d923af683, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:26,218 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:26,219 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:26,220 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 87e01bb1-b0cf-4b4c-a75d-71e2e98eb0f2, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:26,221 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 87e01bb1-b0cf-4b4c-a75d-71e2e98eb0f2, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:26,223 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 87e01bb1-b0cf-4b4c-a75d-71e2e98eb0f2, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:26,601 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:26,602 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 506238, 'Portions': 299}}, blobs: {'__DEFAULT': {'Portions': 295, 'BlobSize': 8095896, 'BlobCount': 622}}
2025-10-15 15:12:26,603 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:26,603 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5db1c183-c205-4de4-9b50-f718bfeb0c8c, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:26,604 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5db1c183-c205-4de4-9b50-f718bfeb0c8c, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:26,608 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5db1c183-c205-4de4-9b50-f718bfeb0c8c, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:27,813 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:27,814 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:27,815 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e4ac4539-1573-4f09-ab00-4ae0dc39286f, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:27,815 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e4ac4539-1573-4f09-ab00-4ae0dc39286f, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:27,819 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e4ac4539-1573-4f09-ab00-4ae0dc39286f, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:28,078 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:28,079 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:28,080 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9eae71ca-9bfa-4502-a097-c10ba3e442e8, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:28,081 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9eae71ca-9bfa-4502-a097-c10ba3e442e8, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:28,084 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9eae71ca-9bfa-4502-a097-c10ba3e442e8, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:28,582 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:28,582 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 355}}, blobs: {'__DEFAULT': {'Portions': 355, 'BlobSize': 9714144, 'BlobCount': 744}}
2025-10-15 15:12:28,583 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:28,584 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c49b810e-6317-4836-93d5-7b0d4f34218e, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:28,584 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c49b810e-6317-4836-93d5-7b0d4f34218e, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:28,586 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c49b810e-6317-4836-93d5-7b0d4f34218e, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:29,754 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:29,755 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:29,756 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f632b4aa-d19b-412a-8873-10b61d4110eb, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:29,756 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f632b4aa-d19b-412a-8873-10b61d4110eb, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:29,758 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f632b4aa-d19b-412a-8873-10b61d4110eb, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:30,154 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:30,155 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:30,156 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, efe3195f-0397-478d-9710-4dd7dc8316ea, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:30,157 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, efe3195f-0397-478d-9710-4dd7dc8316ea, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:30,158 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, efe3195f-0397-478d-9710-4dd7dc8316ea, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:30,785 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:30,786 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 414}}, blobs: {'__DEFAULT': {'Portions': 404, 'BlobSize': 11331576, 'BlobCount': 848}}
2025-10-15 15:12:30,786 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:30,787 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5de74c51-3907-40a9-bf73-db18577fe63b, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:30,788 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5de74c51-3907-40a9-bf73-db18577fe63b, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:30,790 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5de74c51-3907-40a9-bf73-db18577fe63b, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:31,908 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:31,909 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:31,910 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5b2fefc5-3049-415a-91f4-9b1de1688bfb, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:31,912 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5b2fefc5-3049-415a-91f4-9b1de1688bfb, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:31,914 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5b2fefc5-3049-415a-91f4-9b1de1688bfb, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:32,159 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:32,160 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:32,161 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a92593e7-7bf9-4716-85f4-b543daa612b0, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:32,162 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a92593e7-7bf9-4716-85f4-b543daa612b0, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:32,177 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a92593e7-7bf9-4716-85f4-b543daa612b0, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:33,109 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:33,110 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 462}}, blobs: {'__DEFAULT': {'Portions': 462, 'BlobSize': 12949488, 'BlobCount': 966}}
2025-10-15 15:12:33,111 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:33,111 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c8cafcb4-4021-4007-b8ad-1a68812f403a, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:33,112 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c8cafcb4-4021-4007-b8ad-1a68812f403a, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:33,115 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c8cafcb4-4021-4007-b8ad-1a68812f403a, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:34,373 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:34,374 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:34,374 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3bd99688-a9ca-4ed7-aea8-3b7e7447792e, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:34,375 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3bd99688-a9ca-4ed7-aea8-3b7e7447792e, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:34,377 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3bd99688-a9ca-4ed7-aea8-3b7e7447792e, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:34,670 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:34,671 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:34,672 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2e8e44f5-5399-4ea7-8fa1-7cad2d75ae3b, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:34,673 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2e8e44f5-5399-4ea7-8fa1-7cad2d75ae3b, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:34,675 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2e8e44f5-5399-4ea7-8fa1-7cad2d75ae3b, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:35,394 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:35,395 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 519}}, blobs: {'__DEFAULT': {'Portions': 519, 'BlobSize': 14567224, 'BlobCount': 1082}}
2025-10-15 15:12:35,396 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:35,397 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e65e239-5c17-4539-8e4e-1edc90aa353d, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:35,398 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e65e239-5c17-4539-8e4e-1edc90aa353d, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:35,406 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e65e239-5c17-4539-8e4e-1edc90aa353d, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:36,539 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:36,540 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:36,540 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 30e3ace3-e6a0-49c7-85fe-198bc54ce044, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:36,541 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 30e3ace3-e6a0-49c7-85fe-198bc54ce044, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:36,543 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 30e3ace3-e6a0-49c7-85fe-198bc54ce044, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:37,028 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:37,029 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:37,030 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2b498bb3-cab0-4504-b713-7f0f595bd951, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:37,031 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2b498bb3-cab0-4504-b713-7f0f595bd951, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:37,032 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2b498bb3-cab0-4504-b713-7f0f595bd951, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:37,725 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:37,726 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1041661, 'Portions': 570}}, blobs: {'__DEFAULT': {'Portions': 536, 'BlobSize': 16403832, 'BlobCount': 1130}}
2025-10-15 15:12:37,726 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:37,727 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9ef89751-29d4-4a51-bc6d-5c8c6c583164, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:37,728 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9ef89751-29d4-4a51-bc6d-5c8c6c583164, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:37,729 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9ef89751-29d4-4a51-bc6d-5c8c6c583164, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:39,126 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:39,127 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:39,128 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a86d21f0-af3a-4e72-b482-38a1cc0d61fd, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:39,129 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a86d21f0-af3a-4e72-b482-38a1cc0d61fd, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:39,132 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a86d21f0-af3a-4e72-b482-38a1cc0d61fd, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:39,438 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:39,439 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:39,440 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 48fb18fb-b91a-4e33-b126-b81f881f1b0d, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:39,440 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 48fb18fb-b91a-4e33-b126-b81f881f1b0d, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:39,443 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 48fb18fb-b91a-4e33-b126-b81f881f1b0d, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:39,926 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:39,927 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:39,928 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 62ea2c9a-ee11-4f6f-83b9-ec0e15db8b36, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:39,929 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 62ea2c9a-ee11-4f6f-83b9-ec0e15db8b36, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:39,944 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 62ea2c9a-ee11-4f6f-83b9-ec0e15db8b36, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:45,355 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:55,387 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:55,388 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0f5f00cb-082f-4567-921b-2115bd120049, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:55,389 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0f5f00cb-082f-4567-921b-2115bd120049, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:55,391 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0f5f00cb-082f-4567-921b-2115bd120049, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:59,733 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:59,789 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8075888)
2025-10-15 15:12:59,790 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:12:59,790 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d7750a89-891b-4e7f-87c0-a7a39c3b856a, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:12:59,791 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d7750a89-891b-4e7f-87c0-a7a39c3b856a, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:59,792 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d7750a89-891b-4e7f-87c0-a7a39c3b856a, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:04,430 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:14,503 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:14,504 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0d91cdb8-f3cd-43a8-ac76-7261f525cbeb, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:13:14,505 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0d91cdb8-f3cd-43a8-ac76-7261f525cbeb, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:14,507 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0d91cdb8-f3cd-43a8-ac76-7261f525cbeb, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:16,848 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:26,868 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:26,869 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b8d37f24-c866-4c68-98d9-785c001ebd73, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:13:26,870 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b8d37f24-c866-4c68-98d9-785c001ebd73, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:26,872 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b8d37f24-c866-4c68-98d9-785c001ebd73, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:28,577 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:38,593 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:38,594 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a32abbfb-d5a5-4bdf-98ff-4626d4c23039, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:13:38,595 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a32abbfb-d5a5-4bdf-98ff-4626d4c23039, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:38,596 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a32abbfb-d5a5-4bdf-98ff-4626d4c23039, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:40,693 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:40,707 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8031824)
2025-10-15 15:13:40,708 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:40,708 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 70ba5fd7-1dae-4f0e-acea-307901fcfa85, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:13:40,709 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 70ba5fd7-1dae-4f0e-acea-307901fcfa85, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:40,710 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 70ba5fd7-1dae-4f0e-acea-307901fcfa85, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:41,077 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:41,085 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_18_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 18])
2025-10-15 15:13:41,086 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:41,087 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5f1c7870-d4e6-496e-8b11-9ea74a3e5643, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:13:41,088 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5f1c7870-d4e6-496e-8b11-9ea74a3e5643, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_18_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 18)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:41,094 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5f1c7870-d4e6-496e-8b11-9ea74a3e5643, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:45,320 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:45,320 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_18_compression created
2025-10-15 15:13:45,321 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:45,322 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cae6a3e6-70be-4703-9bcb-030e5fb0e30d, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:13:45,323 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cae6a3e6-70be-4703-9bcb-030e5fb0e30d, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_18_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:45,324 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cae6a3e6-70be-4703-9bcb-030e5fb0e30d, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:46,465 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:46,466 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:46,466 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 964168ed-1267-4aa9-a935-ce5d80ce1f3e, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:13:46,467 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 964168ed-1267-4aa9-a935-ce5d80ce1f3e, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:46,471 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 964168ed-1267-4aa9-a935-ce5d80ce1f3e, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:52,889 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:52,890 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:52,891 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5ff6049d-c3c0-48fe-942b-4060ce4769f1, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:13:52,892 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5ff6049d-c3c0-48fe-942b-4060ce4769f1, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:52,898 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5ff6049d-c3c0-48fe-942b-4060ce4769f1, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:54,009 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:54,010 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:54,011 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 40e97583-fbf1-400f-89d5-166d22c9dbb3, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:13:54,012 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 40e97583-fbf1-400f-89d5-166d22c9dbb3, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:54,014 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 40e97583-fbf1-400f-89d5-166d22c9dbb3, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:56,077 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:56,078 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-10-15 15:13:56,078 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:13:56,079 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 57d8ecaa-673a-4ac9-959c-81d46b6a59e6, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:13:56,080 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 57d8ecaa-673a-4ac9-959c-81d46b6a59e6, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:56,082 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 57d8ecaa-673a-4ac9-959c-81d46b6a59e6, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:01,754 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:01,755 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:01,756 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 57edd8b9-2bef-4ec6-8e46-4a712202d4ec, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:01,756 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 57edd8b9-2bef-4ec6-8e46-4a712202d4ec, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:01,758 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 57edd8b9-2bef-4ec6-8e46-4a712202d4ec, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:02,105 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:02,106 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:02,107 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 067fd952-b74e-4874-a89d-38c6163c6382, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:02,108 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 067fd952-b74e-4874-a89d-38c6163c6382, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:02,118 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 067fd952-b74e-4874-a89d-38c6163c6382, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:02,643 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:02,644 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-10-15 15:14:02,644 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:02,645 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e69a46ea-1432-4fa1-aa85-db66803396d0, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:02,646 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e69a46ea-1432-4fa1-aa85-db66803396d0, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:02,649 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e69a46ea-1432-4fa1-aa85-db66803396d0, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:05,677 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:14:05,678 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-15 15:14:05,702 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:14:05,703 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, c45234f5-d6ef-4f7d-ae15-c7fe03408cc8, localhost:1039): creating call state
2025-10-15 15:14:05,703 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, c45234f5-d6ef-4f7d-ae15-c7fe03408cc8, localhost:1039): request = { database: "/Root" }
2025-10-15 15:14:05,716 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, c45234f5-d6ef-4f7d-ae15-c7fe03408cc8, localhost:1039): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 1039 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-10-15 15:14:05,717 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:1039, location 1, ssl: False>]>
2025-10-15 15:14:05,717 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:1039
2025-10-15 15:14:08,958 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:08,959 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:08,959 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e56da511-aaae-4e8c-a0bf-b7e6aab25ad2, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:08,960 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e56da511-aaae-4e8c-a0bf-b7e6aab25ad2, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:08,966 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e56da511-aaae-4e8c-a0bf-b7e6aab25ad2, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:09,483 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:09,484 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:09,485 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 887ff348-3f62-4763-bd0d-6d51b5e5e29b, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:09,486 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 887ff348-3f62-4763-bd0d-6d51b5e5e29b, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:09,489 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 887ff348-3f62-4763-bd0d-6d51b5e5e29b, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:10,334 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:10,335 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 174}}, blobs: {'__DEFAULT': {'Portions': 174, 'BlobSize': 962232, 'BlobCount': 384}}
2025-10-15 15:14:10,336 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:10,336 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 15140cfe-3da6-4857-a0bc-b485019a157f, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:10,337 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 15140cfe-3da6-4857-a0bc-b485019a157f, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:10,338 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 15140cfe-3da6-4857-a0bc-b485019a157f, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:15,862 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:15,863 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:15,864 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 36759c8e-bc63-4cd4-a9fe-f2909e9495d9, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:15,869 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 36759c8e-bc63-4cd4-a9fe-f2909e9495d9, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:15,871 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 36759c8e-bc63-4cd4-a9fe-f2909e9495d9, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:16,325 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:16,326 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:16,327 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f3e6399d-8f7d-4899-a77e-3428a4b113f4, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:16,328 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f3e6399d-8f7d-4899-a77e-3428a4b113f4, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:16,330 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f3e6399d-8f7d-4899-a77e-3428a4b113f4, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:16,749 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:16,750 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 409478, 'Portions': 228}}, blobs: {'__DEFAULT': {'Portions': 222, 'BlobSize': 1282528, 'BlobCount': 496}}
2025-10-15 15:14:16,751 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:16,752 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8383b20d-4383-4d63-b11f-84cac85f9551, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:16,755 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8383b20d-4383-4d63-b11f-84cac85f9551, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:16,762 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8383b20d-4383-4d63-b11f-84cac85f9551, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:21,705 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:21,706 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:21,707 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0c1113ad-f210-4d96-bdfd-8afa0dd8e866, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:21,708 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0c1113ad-f210-4d96-bdfd-8afa0dd8e866, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:21,714 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0c1113ad-f210-4d96-bdfd-8afa0dd8e866, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:21,982 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:21,983 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:21,984 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7b14ffa3-27a4-426a-b906-7ee1e551565c, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:21,985 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7b14ffa3-27a4-426a-b906-7ee1e551565c, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:21,987 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7b14ffa3-27a4-426a-b906-7ee1e551565c, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:22,601 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:22,602 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 250}}, blobs: {'__DEFAULT': {'Portions': 250, 'BlobSize': 1615832, 'BlobCount': 576}}
2025-10-15 15:14:22,602 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:22,603 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 08adde48-5e0c-489d-80db-91d1bc111b65, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:22,604 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 08adde48-5e0c-489d-80db-91d1bc111b65, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:22,607 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 08adde48-5e0c-489d-80db-91d1bc111b65, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:28,093 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:28,094 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:28,095 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a60268b7-4d22-4b56-b8ed-0af0acbaa063, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:28,096 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a60268b7-4d22-4b56-b8ed-0af0acbaa063, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:28,098 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a60268b7-4d22-4b56-b8ed-0af0acbaa063, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:28,464 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:28,465 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:28,466 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f5f28dae-7149-45f9-8cb6-c789d9b2a506, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:28,468 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f5f28dae-7149-45f9-8cb6-c789d9b2a506, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:28,471 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f5f28dae-7149-45f9-8cb6-c789d9b2a506, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:28,978 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:28,979 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 290}}, blobs: {'__DEFAULT': {'Portions': 286, 'BlobSize': 1942200, 'BlobCount': 662}}
2025-10-15 15:14:28,980 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:28,980 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bd9d4851-fcf8-4a78-a8e4-bb8d6debc25e, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:28,981 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bd9d4851-fcf8-4a78-a8e4-bb8d6debc25e, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:28,983 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bd9d4851-fcf8-4a78-a8e4-bb8d6debc25e, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:34,074 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:34,075 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:34,076 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a61ba63e-650b-4abd-a0a4-32bad10c9410, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:34,077 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a61ba63e-650b-4abd-a0a4-32bad10c9410, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:34,079 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a61ba63e-650b-4abd-a0a4-32bad10c9410, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:34,428 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:34,429 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:34,430 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 48c369eb-2f37-484d-8649-1c30b3eb4f0d, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:34,431 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 48c369eb-2f37-484d-8649-1c30b3eb4f0d, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:34,437 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 48c369eb-2f37-484d-8649-1c30b3eb4f0d, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:35,034 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:35,035 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 335}}, blobs: {'__DEFAULT': {'Portions': 335, 'BlobSize': 2260744, 'BlobCount': 766}}
2025-10-15 15:14:35,036 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:35,036 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ba57f00e-02b4-4231-b27f-e7b09c64110c, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:35,037 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ba57f00e-02b4-4231-b27f-e7b09c64110c, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:35,039 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ba57f00e-02b4-4231-b27f-e7b09c64110c, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:39,764 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:39,765 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:39,765 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9528e2bb-cc21-4694-9758-d90ae5f4c4fc, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:39,766 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9528e2bb-cc21-4694-9758-d90ae5f4c4fc, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:39,767 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9528e2bb-cc21-4694-9758-d90ae5f4c4fc, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:40,105 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:40,106 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:40,107 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5e847ef5-cec6-455d-b546-1bdccb6f5f59, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:40,108 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5e847ef5-cec6-455d-b546-1bdccb6f5f59, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:40,109 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5e847ef5-cec6-455d-b546-1bdccb6f5f59, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:40,800 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:40,801 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 811119, 'Portions': 376}}, blobs: {'__DEFAULT': {'Portions': 369, 'BlobSize': 2577792, 'BlobCount': 844}}
2025-10-15 15:14:40,802 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:40,802 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 40907c24-c3f5-4711-871d-260d1733279d, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:40,803 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 40907c24-c3f5-4711-871d-260d1733279d, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:40,804 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 40907c24-c3f5-4711-871d-260d1733279d, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:45,151 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:45,152 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:45,153 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7b332716-a16c-4017-bc1f-4da786df4438, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:45,154 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7b332716-a16c-4017-bc1f-4da786df4438, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:45,155 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7b332716-a16c-4017-bc1f-4da786df4438, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:45,587 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:45,588 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:45,589 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aa99003f-3403-4e83-b667-3f3d3553502f, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:45,590 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aa99003f-3403-4e83-b667-3f3d3553502f, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:45,592 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aa99003f-3403-4e83-b667-3f3d3553502f, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:46,429 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:46,430 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 912618, 'Portions': 413}}, blobs: {'__DEFAULT': {'Portions': 399, 'BlobSize': 2931240, 'BlobCount': 916}}
2025-10-15 15:14:46,431 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:46,432 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bc75b5af-d5ee-4168-8bd3-e91474313118, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:46,432 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bc75b5af-d5ee-4168-8bd3-e91474313118, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:46,436 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bc75b5af-d5ee-4168-8bd3-e91474313118, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:50,790 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:50,791 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:50,791 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8a6e7bfe-9be9-4972-93ef-ee53dbe8d7ad, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:50,792 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8a6e7bfe-9be9-4972-93ef-ee53dbe8d7ad, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:50,794 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8a6e7bfe-9be9-4972-93ef-ee53dbe8d7ad, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:51,158 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:51,159 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:51,160 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dfd0070f-4f37-45ed-92fa-f40cdbbae968, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:51,161 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dfd0070f-4f37-45ed-92fa-f40cdbbae968, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:51,163 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dfd0070f-4f37-45ed-92fa-f40cdbbae968, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:51,707 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:51,708 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 420}}, blobs: {'__DEFAULT': {'Portions': 420, 'BlobSize': 3206224, 'BlobCount': 968}}
2025-10-15 15:14:51,709 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:51,709 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 033a61de-cecc-4719-86be-4db0db9bee21, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:51,710 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 033a61de-cecc-4719-86be-4db0db9bee21, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_18_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:51,711 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 033a61de-cecc-4719-86be-4db0db9bee21, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:52,749 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:52,750 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:52,751 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1191f559-4d65-477c-abdd-2beb1570398f, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:52,752 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1191f559-4d65-477c-abdd-2beb1570398f, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:52,753 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1191f559-4d65-477c-abdd-2beb1570398f, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:53,094 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:53,094 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:53,095 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c83ddd4a-0314-4107-9639-acac5bfc5e64, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:53,096 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c83ddd4a-0314-4107-9639-acac5bfc5e64, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:53,097 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c83ddd4a-0314-4107-9639-acac5bfc5e64, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:53,430 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:53,431 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:14:53,431 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5c4b0955-a2cd-4bf8-a870-5a8536833b49, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:14:53,432 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5c4b0955-a2cd-4bf8-a870-5a8536833b49, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:53,434 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5c4b0955-a2cd-4bf8-a870-5a8536833b49, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:56,479 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:06,517 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:06,518 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 74c20281-8a4f-4562-bbc6-19340ea18cd7, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:06,519 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 74c20281-8a4f-4562-bbc6-19340ea18cd7, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:06,521 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 74c20281-8a4f-4562-bbc6-19340ea18cd7, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:09,120 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:09,148 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_18_compression`, volumes `value` (8000000, 1574160)
2025-10-15 15:15:09,148 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:09,149 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e1208a21-461a-4a88-8236-9d5a8c0a9d88, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:09,150 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e1208a21-461a-4a88-8236-9d5a8c0a9d88, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:09,152 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e1208a21-461a-4a88-8236-9d5a8c0a9d88, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:09,460 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:09,461 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:09,462 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f2c7b063-6d7d-442a-8c4f-80d7bbed747e, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:09,462 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f2c7b063-6d7d-442a-8c4f-80d7bbed747e, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:09,464 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f2c7b063-6d7d-442a-8c4f-80d7bbed747e, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:12,266 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:22,293 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:22,295 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0d5c2aaa-9a6d-485d-8651-6a673f225572, ghrun-lvxbqnxafi.auto.internal:1039): creating call state
2025-10-15 15:15:22,296 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0d5c2aaa-9a6d-485d-8651-6a673f225572, ghrun-lvxbqnxafi.auto.internal:1039): request = { session_id: "ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:22,297 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0d5c2aaa-9a6d-485d-8651-6a673f225572, ghrun-lvxbqnxafi.auto.internal:1039): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:24,946 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmE2YjI3MTAtYzc5ZmNjMDEtZDgxMGUyMGQtNGY2N2M4NWU=
2025-10-15 15:15:24,996 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_18_compression`, volumes `value` (8000000, 1574160)
2025-10-15 15:15:25,001 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_18_compression` 8075888 / 1574160: 5.130284088021548
2025-10-15 15:15:25,003 - INFO - ya.test - pytest_runtest_teardown: Test teardown
