2025-12-07 20:16:34,802 - INFO - ya.test.667945  - pytest_runtest_setup: ####################################################################################################
2025-12-07 20:16:34,802 - INFO - ya.test.667945  - pytest_runtest_setup: test_all_supported_compression[zstd_15_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 15]
2025-12-07 20:16:34,803 - INFO - ya.test.667945  - pytest_runtest_setup: ####################################################################################################
2025-12-07 20:16:34,803 - INFO - ya.test.667945  - pytest_runtest_setup: Test setup
2025-12-07 20:16:34,805 - DEBUG - ya.test.667945  - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-07 20:16:34,806 - DEBUG - ya.test.667945  - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-07 20:16:34,806 - DEBUG - ya.test.667945  - execute: Executing '['/home/runner/.ya/build/build_root/nbq9/0021ec/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-07 20:16:34,808 - DEBUG - ya.test.667945  - execute: Command pid: 668901
2025-12-07 20:16:35,473 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) rc: 0
2025-12-07 20:16:35,473 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) elapsed time (sec): 0.663644552230835
2025-12-07 20:16:35,474 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) idrss: 0
2025-12-07 20:16:35,474 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) inblock: 0
2025-12-07 20:16:35,474 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) isrss: 0
2025-12-07 20:16:35,474 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) ixrss: 0
2025-12-07 20:16:35,475 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) majflt: 0
2025-12-07 20:16:35,475 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) maxrss: 501280
2025-12-07 20:16:35,475 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) minflt: 45748
2025-12-07 20:16:35,476 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) msgrcv: 0
2025-12-07 20:16:35,476 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) msgsnd: 0
2025-12-07 20:16:35,476 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) nivcsw: 29
2025-12-07 20:16:35,476 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) nsignals: 0
2025-12-07 20:16:35,477 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) nswap: 0
2025-12-07 20:16:35,477 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) nvcsw: 14
2025-12-07 20:16:35,477 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) oublock: 8
2025-12-07 20:16:35,478 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) stime: 0.192195
2025-12-07 20:16:35,478 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) utime: 0.425013
2025-12-07 20:16:35,478 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) wtime: 0.666
2025-12-07 20:16:35,478 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) output:
Git info:
    Commit: e83fd7e6e4205be8576c8746b2e97bc2aba35179
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge bb726b0e809e5cee85d4dbb9c4701b21f4ec5dd1 into 9e8876b554304ab0d6f8d3f9510beeea45180cea
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-7gzmsyrbqy    Host information: 
        Linux ghrun-7gzmsyrbqy 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-07 20:16:35,479 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 668901) errors:

2025-12-07 20:16:35,479 - INFO - __tests__.base.667945  - _setup_ydb: Git info:
    Commit: e83fd7e6e4205be8576c8746b2e97bc2aba35179
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge bb726b0e809e5cee85d4dbb9c4701b21f4ec5dd1 into 9e8876b554304ab0d6f8d3f9510beeea45180cea
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-7gzmsyrbqy    Host information: 
        Linux ghrun-7gzmsyrbqy 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-07 20:16:35,583 - DEBUG - ya.test.667945  - get_binary: Binary was found by /home/runner/.ya/build/build_root/nbq9/0021ec/ydb/apps/ydbd/ydbd
2025-12-07 20:16:35,585 - DEBUG - library.python.filelock.667945  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8524
2025-12-07 20:16:35,587 - DEBUG - ydb.tests.library.harness.kikimr_runner.667945  - start: Working directory: /home/runner/.ya/build/build_root/nbq9/0021ec/r3tmp/kikimr_cluster_89uhgmf7
2025-12-07 20:16:35,626 - DEBUG - ya.test.667945  - get_binary: Binary was found by /home/runner/.ya/build/build_root/nbq9/0021ec/ydb/apps/ydbd/ydbd
2025-12-07 20:16:35,627 - DEBUG - library.python.filelock.667945  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8927
2025-12-07 20:16:35,627 - DEBUG - library.python.filelock.667945  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21141
2025-12-07 20:16:35,628 - DEBUG - library.python.filelock.667945  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62093
2025-12-07 20:16:35,628 - DEBUG - library.python.filelock.667945  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20177
2025-12-07 20:16:35,629 - INFO - ydb.tests.library.harness.kikimr_runner.667945  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/nbq9/0021ec/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/kikimr_configs"
2025-12-07 20:16:35,630 - INFO - ydb.tests.library.harness.kikimr_runner.667945  - __make_run_command: Final command: /home/runner/.ya/build/build_root/nbq9/0021ec/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/nbq9/0021ec/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1/logfile_64ma6lb3.log --grpc-port=8927 --mon-port=21141 --ic-port=8524
2025-12-07 20:16:35,630 - DEBUG - ydb.tests.library.harness.kikimr_runner.667945  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:8927/1, disk_size 68719476736
2025-12-07 20:16:35,631 - INFO - ydb.tests.library.harness.kikimr_runner.667945  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/nbq9/0021ec/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/kikimr_configs"
2025-12-07 20:16:35,631 - INFO - ydb.tests.library.harness.kikimr_runner.667945  - __make_run_command: Final command: /home/runner/.ya/build/build_root/nbq9/0021ec/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/nbq9/0021ec/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1/logfile_64ma6lb3.log --grpc-port=8927 --mon-port=21141 --ic-port=8524
2025-12-07 20:16:35,632 - DEBUG - ya.test.667945  - execute: Executing '('/home/runner/.ya/build/build_root/nbq9/0021ec/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/nbq9/0021ec/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/nbq9/0021ec/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1/logfile_64ma6lb3.log', '--grpc-port=8927', '--mon-port=21141', '--ic-port=8524')' in '/home/runner/.ya/build/build_root/nbq9/0021ec/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1' (no wait)
2025-12-07 20:16:35,635 - DEBUG - ya.test.667945  - execute: Command pid: 669077
2025-12-07 20:16:35,636 - INFO - ydb.tests.library.harness.kikimr_runner.667945  - start: Started node localhost:8927/1
2025-12-07 20:16:35,637 - DEBUG - ya.test.667945  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-07 20:16:35,638 - DEBUG - ya.test.667945  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-07 20:16:35,639 - DEBUG - ya.test.667945  - execute: Executing '['/home/runner/.ya/build/build_root/nbq9/0021ec/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-07 20:16:35,640 - DEBUG - ya.test.667945  - execute: Command pid: 669083
2025-12-07 20:16:36,342 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) rc: 0
2025-12-07 20:16:36,342 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) elapsed time (sec): 0.7010817527770996
2025-12-07 20:16:36,343 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) idrss: 0
2025-12-07 20:16:36,343 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) inblock: 0
2025-12-07 20:16:36,343 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) isrss: 0
2025-12-07 20:16:36,344 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) ixrss: 0
2025-12-07 20:16:36,344 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) majflt: 0
2025-12-07 20:16:36,345 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) maxrss: 512080
2025-12-07 20:16:36,345 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) minflt: 45674
2025-12-07 20:16:36,345 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) msgrcv: 0
2025-12-07 20:16:36,346 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) msgsnd: 0
2025-12-07 20:16:36,346 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) nivcsw: 35
2025-12-07 20:16:36,346 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) nsignals: 0
2025-12-07 20:16:36,346 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) nswap: 0
2025-12-07 20:16:36,347 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) nvcsw: 15
2025-12-07 20:16:36,347 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) oublock: 8
2025-12-07 20:16:36,347 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) stime: 0.1638
2025-12-07 20:16:36,347 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) utime: 0.48704699999999995
2025-12-07 20:16:36,348 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) wtime: 0.703
2025-12-07 20:16:36,348 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) output:
Git info:
    Commit: e83fd7e6e4205be8576c8746b2e97bc2aba35179
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge bb726b0e809e5cee85d4dbb9c4701b21f4ec5dd1 into 9e8876b554304ab0d6f8d3f9510beeea45180cea
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-7gzmsyrbqy    Host information: 
        Linux ghrun-7gzmsyrbqy 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

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

2025-12-07 20:16:36,349 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669083) errors:

2025-12-07 20:16:36,349 - INFO - ydb.tests.library.harness.kikimr_runner.667945  - start: Node 1 version:
Git info:
    Commit: e83fd7e6e4205be8576c8746b2e97bc2aba35179
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge bb726b0e809e5cee85d4dbb9c4701b21f4ec5dd1 into 9e8876b554304ab0d6f8d3f9510beeea45180cea
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-7gzmsyrbqy    Host information: 
        Linux ghrun-7gzmsyrbqy 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-07 20:16:38,934 - DEBUG - ya.test.667945  - get_binary: Binary was found by /home/runner/.ya/build/build_root/nbq9/0021ec/ydb/apps/ydbd/ydbd
2025-12-07 20:16:38,935 - DEBUG - ydb.tests.library.harness.kikimr_runner.667945  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/nbq9/0021ec/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8927', '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: 8524\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-07 20:16:38,936 - DEBUG - ya.test.667945  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-07 20:16:38,936 - DEBUG - ya.test.667945  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-07 20:16:38,937 - DEBUG - ya.test.667945  - execute: Executing '['/home/runner/.ya/build/build_root/nbq9/0021ec/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8927', '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: 8524\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-07 20:16:38,942 - DEBUG - ya.test.667945  - execute: Command pid: 669517
2025-12-07 20:16:39,860 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) rc: 0
2025-12-07 20:16:39,861 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) elapsed time (sec): 0.9174008369445801
2025-12-07 20:16:39,861 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) idrss: 0
2025-12-07 20:16:39,862 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) inblock: 0
2025-12-07 20:16:39,862 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) isrss: 0
2025-12-07 20:16:39,862 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) ixrss: 0
2025-12-07 20:16:39,862 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) majflt: 0
2025-12-07 20:16:39,863 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) maxrss: 585048
2025-12-07 20:16:39,863 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) minflt: 52027
2025-12-07 20:16:39,863 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) msgrcv: 0
2025-12-07 20:16:39,863 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) msgsnd: 0
2025-12-07 20:16:39,864 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) nivcsw: 122
2025-12-07 20:16:39,864 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) nsignals: 0
2025-12-07 20:16:39,865 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) nswap: 0
2025-12-07 20:16:39,865 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) nvcsw: 972
2025-12-07 20:16:39,865 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) oublock: 8
2025-12-07 20:16:39,865 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) stime: 0.292113
2025-12-07 20:16:39,866 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) utime: 0.539747
2025-12-07 20:16:39,866 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) wtime: 0.923
2025-12-07 20:16:39,866 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-07 20:16:39,867 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669517) errors:

2025-12-07 20:16:39,868 - DEBUG - ya.test.667945  - get_binary: Binary was found by /home/runner/.ya/build/build_root/nbq9/0021ec/ydb/apps/ydbd/ydbd
2025-12-07 20:16:39,869 - DEBUG - ydb.tests.library.harness.kikimr_runner.667945  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/nbq9/0021ec/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8927', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-12-07 20:16:39,870 - DEBUG - ya.test.667945  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-07 20:16:39,871 - DEBUG - ya.test.667945  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-07 20:16:39,871 - DEBUG - ya.test.667945  - execute: Executing '['/home/runner/.ya/build/build_root/nbq9/0021ec/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8927', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-07 20:16:39,873 - DEBUG - ya.test.667945  - execute: Command pid: 669707
2025-12-07 20:16:40,739 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) rc: 0
2025-12-07 20:16:40,740 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) elapsed time (sec): 0.8651854991912842
2025-12-07 20:16:40,740 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) idrss: 0
2025-12-07 20:16:40,740 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) inblock: 0
2025-12-07 20:16:40,740 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) isrss: 0
2025-12-07 20:16:40,741 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) ixrss: 0
2025-12-07 20:16:40,741 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) majflt: 0
2025-12-07 20:16:40,741 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) maxrss: 585632
2025-12-07 20:16:40,742 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) minflt: 52225
2025-12-07 20:16:40,742 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) msgrcv: 0
2025-12-07 20:16:40,742 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) msgsnd: 0
2025-12-07 20:16:40,743 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) nivcsw: 119
2025-12-07 20:16:40,743 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) nsignals: 0
2025-12-07 20:16:40,743 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) nswap: 0
2025-12-07 20:16:40,743 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) nvcsw: 830
2025-12-07 20:16:40,744 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) oublock: 8
2025-12-07 20:16:40,744 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) stime: 0.258827
2025-12-07 20:16:40,744 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) utime: 0.5288769999999999
2025-12-07 20:16:40,744 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) wtime: 0.867
2025-12-07 20:16:40,745 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-07 20:16:40,745 - DEBUG - ya.test.667945  - _save_outputs: Command (pid 669707) errors:

2025-12-07 20:16:40,745 - INFO - ydb.tests.library.harness.kikimr_runner.667945  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-07 20:16:40,802 - INFO - ydb.tests.library.harness.kikimr_runner.667945  - __run: Cluster started and initialized
2025-12-07 20:16:40,834 - DEBUG - ydb.connection.667945  - 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-12-07 20:16:40,837 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.667945  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-07 20:16:40,838 - DEBUG - ydb.connection.667945  - 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-12-07 20:16:40,857 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.667945  - context_resolve: Resolving endpoints for database /Root
2025-12-07 20:16:40,858 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ListEndpoints, 46aa24e0-f8fc-452c-8408-25f099bcd362, localhost:8927): creating call state
2025-12-07 20:16:40,859 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ListEndpoints, 46aa24e0-f8fc-452c-8408-25f099bcd362, localhost:8927): request = { database: "/Root" }
2025-12-07 20:16:40,867 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ListEndpoints, 46aa24e0-f8fc-452c-8408-25f099bcd362, localhost:8927): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-7gzmsyrbqy.auto.internal" port: 8927 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-12-07 20:16:40,868 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.667945  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-7gzmsyrbqy.auto.internal:8927, location 1, ssl: False>]>
2025-12-07 20:16:40,868 - DEBUG - ydb.connection.667945  - 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-12-07 20:16:40,880 - DEBUG - ydb.connection.667945  - close: Closing channel for endpoint localhost:8927
2025-12-07 20:16:40,881 - DEBUG - ydb.query.pool.667945  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-07 20:16:40,883 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(CreateSession, 3366d8eb-5db7-47b1-b2aa-1a57dee5b215, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:40,883 - DEBUG - ydb.connection.667945  - _log_request: RpcState(CreateSession, 3366d8eb-5db7-47b1-b2aa-1a57dee5b215, ghrun-7gzmsyrbqy.auto.internal:8927): request = {  }
2025-12-07 20:16:40,890 - DEBUG - ydb.connection.667945  - _log_response: RpcState(CreateSession, 3366d8eb-5db7-47b1-b2aa-1a57dee5b215, ghrun-7gzmsyrbqy.auto.internal:8927): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" node_id: 1 }
2025-12-07 20:16:40,891 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(AttachSession, fbf463ea-2651-4813-9a78-64b1369ca266, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:40,891 - DEBUG - ydb.connection.667945  - _log_request: RpcState(AttachSession, fbf463ea-2651-4813-9a78-64b1369ca266, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" }
2025-12-07 20:16:40,893 - DEBUG - ydb.connection.667945  - _log_response: RpcState(AttachSession, fbf463ea-2651-4813-9a78-64b1369ca266, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:40,895 - DEBUG - ydb.query.pool.667945  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:40,896 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, be6f9630-a418-4e06-9aea-089fdeb0a914, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:40,897 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, be6f9630-a418-4e06-9aea-089fdeb0a914, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:40,899 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, be6f9630-a418-4e06-9aea-089fdeb0a914, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:42,781 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:42,782 - INFO - __tests__.alter_compression.667945  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-12-07 20:16:42,783 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:42,783 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, adb8fd2c-c41a-41a4-a765-f5903f8fe37b, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:42,784 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, adb8fd2c-c41a-41a4-a765-f5903f8fe37b, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:42,786 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, adb8fd2c-c41a-41a4-a765-f5903f8fe37b, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:43,626 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:43,627 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:43,627 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 28347e13-da75-4d5e-9ba0-388cf9558fe7, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:43,628 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 28347e13-da75-4d5e-9ba0-388cf9558fe7, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:43,630 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 28347e13-da75-4d5e-9ba0-388cf9558fe7, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:44,484 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:44,484 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:44,485 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, f68fb7a8-ffc5-4b97-93a0-680a94bb9697, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:44,486 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, f68fb7a8-ffc5-4b97-93a0-680a94bb9697, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:44,487 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, f68fb7a8-ffc5-4b97-93a0-680a94bb9697, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:45,177 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:45,178 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:45,179 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, ee9206f0-d48d-4433-8449-9e800d23a368, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:45,180 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, ee9206f0-d48d-4433-8449-9e800d23a368, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:45,181 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, ee9206f0-d48d-4433-8449-9e800d23a368, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:46,478 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:46,479 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 1618432, 'BlobCount': 128}}
2025-12-07 20:16:46,480 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:46,481 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, a5926fa5-692c-4788-b177-189146e37e85, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:46,482 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, a5926fa5-692c-4788-b177-189146e37e85, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:46,483 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, a5926fa5-692c-4788-b177-189146e37e85, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:47,233 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:47,234 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:47,235 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 91eab7bb-fd9e-495e-8126-fad5dc25c781, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:47,235 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 91eab7bb-fd9e-495e-8126-fad5dc25c781, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:47,236 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 91eab7bb-fd9e-495e-8126-fad5dc25c781, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:47,426 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:47,427 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:47,428 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, f5f0e240-cbfc-4646-8c59-30cd17b7b538, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:47,429 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, f5f0e240-cbfc-4646-8c59-30cd17b7b538, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:47,430 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, f5f0e240-cbfc-4646-8c59-30cd17b7b538, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:47,675 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:47,676 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 3236864, 'BlobCount': 256}}
2025-12-07 20:16:47,676 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:47,677 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 9e53090b-97f8-4fe9-bfe2-50d7612ec3c9, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:47,678 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 9e53090b-97f8-4fe9-bfe2-50d7612ec3c9, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:47,680 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 9e53090b-97f8-4fe9-bfe2-50d7612ec3c9, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:48,438 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:48,438 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:48,439 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 07bd5664-6705-4548-9b97-be4e48c3df9d, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:48,440 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 07bd5664-6705-4548-9b97-be4e48c3df9d, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:48,441 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 07bd5664-6705-4548-9b97-be4e48c3df9d, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:48,596 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:48,597 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:48,598 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 9d74df2b-48dd-4308-b6d1-b2e00631572c, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:48,599 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 9d74df2b-48dd-4308-b6d1-b2e00631572c, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:48,600 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 9d74df2b-48dd-4308-b6d1-b2e00631572c, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:48,872 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:48,873 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 4855296, 'BlobCount': 384}}
2025-12-07 20:16:48,873 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:48,874 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, c2a769e5-3827-41c5-8830-f8c96eb29e5e, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:48,875 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, c2a769e5-3827-41c5-8830-f8c96eb29e5e, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:48,877 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, c2a769e5-3827-41c5-8830-f8c96eb29e5e, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:49,679 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:49,680 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:49,681 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 8e49f59f-8de1-4e00-80d7-30d544ebf583, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:49,682 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 8e49f59f-8de1-4e00-80d7-30d544ebf583, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:49,683 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 8e49f59f-8de1-4e00-80d7-30d544ebf583, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:49,882 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:49,882 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:49,883 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 4005678b-024d-4d4b-9771-e2d528686856, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:49,884 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 4005678b-024d-4d4b-9771-e2d528686856, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:49,885 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 4005678b-024d-4d4b-9771-e2d528686856, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:50,197 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:50,198 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 6473728, 'BlobCount': 512}}
2025-12-07 20:16:50,198 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:50,199 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, cb39dc50-54b2-4406-92e4-98594e50d5ba, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:50,200 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, cb39dc50-54b2-4406-92e4-98594e50d5ba, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:50,201 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, cb39dc50-54b2-4406-92e4-98594e50d5ba, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:51,029 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:51,030 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:51,031 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 207a48a8-07f6-42c2-8c26-48e3f7d93686, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:51,032 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 207a48a8-07f6-42c2-8c26-48e3f7d93686, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:51,033 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 207a48a8-07f6-42c2-8c26-48e3f7d93686, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:51,309 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:51,310 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:51,310 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 15352af0-85a5-4060-a465-feb10f133ca9, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:51,312 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 15352af0-85a5-4060-a465-feb10f133ca9, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:51,314 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 15352af0-85a5-4060-a465-feb10f133ca9, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:51,674 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:51,675 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 8092160, 'BlobCount': 640}}
2025-12-07 20:16:51,675 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:51,675 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 2435a87c-62cc-405f-9270-67f4feaee04c, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:51,676 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 2435a87c-62cc-405f-9270-67f4feaee04c, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:51,677 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 2435a87c-62cc-405f-9270-67f4feaee04c, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:52,643 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:52,643 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:52,644 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 904339a4-a72a-4a96-ad08-c08644e4bc3f, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:52,645 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 904339a4-a72a-4a96-ad08-c08644e4bc3f, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:52,646 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 904339a4-a72a-4a96-ad08-c08644e4bc3f, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:52,861 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:52,862 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:52,862 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 0fc1fa9c-b655-4778-9614-f582c729b6e4, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:52,863 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 0fc1fa9c-b655-4778-9614-f582c729b6e4, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:52,865 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 0fc1fa9c-b655-4778-9614-f582c729b6e4, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:53,258 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:53,259 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 9710592, 'BlobCount': 768}}
2025-12-07 20:16:53,259 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:53,260 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 4b44463f-b48b-4276-af8e-73e2422cd977, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:53,261 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 4b44463f-b48b-4276-af8e-73e2422cd977, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:53,262 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 4b44463f-b48b-4276-af8e-73e2422cd977, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:54,204 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:54,204 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:54,205 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, f9985b3b-e601-453a-b985-f1726d0bd3af, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:54,206 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, f9985b3b-e601-453a-b985-f1726d0bd3af, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:54,207 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, f9985b3b-e601-453a-b985-f1726d0bd3af, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:54,405 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:54,406 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:54,406 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, a432e274-c9c7-444f-86ae-728470d7350a, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:54,407 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, a432e274-c9c7-444f-86ae-728470d7350a, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:54,409 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, a432e274-c9c7-444f-86ae-728470d7350a, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:54,806 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:54,807 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 11329024, 'BlobCount': 896}}
2025-12-07 20:16:54,808 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:54,808 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 1d5b3b34-0c34-4585-921b-f61dc37caf34, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:54,809 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 1d5b3b34-0c34-4585-921b-f61dc37caf34, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:54,811 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 1d5b3b34-0c34-4585-921b-f61dc37caf34, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:55,736 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:55,737 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:55,737 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 9dcc90c7-3c06-408c-ae4c-3b3ddcb0876d, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:55,738 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 9dcc90c7-3c06-408c-ae4c-3b3ddcb0876d, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:55,740 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 9dcc90c7-3c06-408c-ae4c-3b3ddcb0876d, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:55,968 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:55,969 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:55,970 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, aa430cef-676e-4033-a742-27a022c5b84c, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:55,971 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, aa430cef-676e-4033-a742-27a022c5b84c, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:55,972 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, aa430cef-676e-4033-a742-27a022c5b84c, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:56,507 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:56,507 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 12947456, 'BlobCount': 1024}}
2025-12-07 20:16:56,508 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:56,508 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 7c7640be-f0a2-4f57-a661-7c63ab518142, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:56,509 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 7c7640be-f0a2-4f57-a661-7c63ab518142, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:56,518 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 7c7640be-f0a2-4f57-a661-7c63ab518142, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:57,336 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:57,337 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:57,337 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, afea1a72-e86a-46cd-8ea4-e45793a23e93, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:57,339 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, afea1a72-e86a-46cd-8ea4-e45793a23e93, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:57,341 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, afea1a72-e86a-46cd-8ea4-e45793a23e93, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:57,530 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:57,531 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:57,531 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 5eb3ebd4-6d6a-4ae2-9872-f28ee1461fbc, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:57,532 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 5eb3ebd4-6d6a-4ae2-9872-f28ee1461fbc, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:57,534 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 5eb3ebd4-6d6a-4ae2-9872-f28ee1461fbc, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:58,000 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:58,001 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 14565888, 'BlobCount': 1152}}
2025-12-07 20:16:58,002 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:58,002 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 6b37b359-9509-4dd0-8d2e-894871f24912, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:58,003 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 6b37b359-9509-4dd0-8d2e-894871f24912, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:58,005 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 6b37b359-9509-4dd0-8d2e-894871f24912, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:58,864 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:58,865 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:58,866 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, a8284358-47c6-46fb-b4b1-684fc426494e, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:58,866 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, a8284358-47c6-46fb-b4b1-684fc426494e, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:58,868 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, a8284358-47c6-46fb-b4b1-684fc426494e, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:59,049 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:59,049 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:59,050 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 2cbbd20e-69cd-42ca-8708-38e5b28f9e5d, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:59,051 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 2cbbd20e-69cd-42ca-8708-38e5b28f9e5d, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:59,053 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 2cbbd20e-69cd-42ca-8708-38e5b28f9e5d, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:16:59,624 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:59,625 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 16184320, 'BlobCount': 1280}}
2025-12-07 20:16:59,626 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:16:59,626 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 91df31db-f18d-49e6-bbbd-327b6bd1c060, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:16:59,627 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 91df31db-f18d-49e6-bbbd-327b6bd1c060, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:16:59,629 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 91df31db-f18d-49e6-bbbd-327b6bd1c060, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:00,427 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:00,428 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:00,428 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 0940892e-8d0a-4a84-ad56-e8ae655f9208, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:00,430 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 0940892e-8d0a-4a84-ad56-e8ae655f9208, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:00,431 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 0940892e-8d0a-4a84-ad56-e8ae655f9208, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:00,648 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:00,649 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:00,649 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, f96f93aa-acc5-4770-8d66-e5d9c4e67ecf, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:00,650 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, f96f93aa-acc5-4770-8d66-e5d9c4e67ecf, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:00,652 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, f96f93aa-acc5-4770-8d66-e5d9c4e67ecf, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:00,827 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:00,828 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:00,829 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 09152802-0ef2-4a00-a491-c1d2eec9f0b7, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:00,829 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 09152802-0ef2-4a00-a491-c1d2eec9f0b7, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:00,831 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 09152802-0ef2-4a00-a491-c1d2eec9f0b7, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:03,619 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:13,654 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:13,655 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 75747941-f4a3-417d-85a7-e0ee39f84bef, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:13,656 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 75747941-f4a3-417d-85a7-e0ee39f84bef, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:13,657 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 75747941-f4a3-417d-85a7-e0ee39f84bef, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:16,186 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:16,223 - INFO - root.667945  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-12-07 20:17:16,224 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:16,225 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 93288091-6453-4dc5-a33d-9cc04f65f3e8, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:16,225 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 93288091-6453-4dc5-a33d-9cc04f65f3e8, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:16,227 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 93288091-6453-4dc5-a33d-9cc04f65f3e8, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:18,796 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:28,834 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:28,835 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 82d45194-4bea-461b-9391-e0da0d5ddfc8, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:28,836 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 82d45194-4bea-461b-9391-e0da0d5ddfc8, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:28,837 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 82d45194-4bea-461b-9391-e0da0d5ddfc8, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:31,827 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:31,884 - INFO - root.667945  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-12-07 20:17:31,885 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:31,885 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 353f9008-7650-40ff-8ffe-914cad28e72b, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:31,887 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 353f9008-7650-40ff-8ffe-914cad28e72b, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:31,888 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 353f9008-7650-40ff-8ffe-914cad28e72b, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:32,186 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:32,190 - INFO - ya.test.667945  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_15_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 15])
2025-12-07 20:17:32,191 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:32,192 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 43b28d56-a5c4-4706-a0a0-481e1573c8c6, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:32,192 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 43b28d56-a5c4-4706-a0a0-481e1573c8c6, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_15_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 15)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:32,195 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 43b28d56-a5c4-4706-a0a0-481e1573c8c6, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:35,267 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:35,267 - INFO - __tests__.alter_compression.667945  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_15_compression created
2025-12-07 20:17:35,268 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:35,268 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, fa30e1af-8f57-42ea-a877-6d194091bcf7, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:35,269 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, fa30e1af-8f57-42ea-a877-6d194091bcf7, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_15_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:35,270 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, fa30e1af-8f57-42ea-a877-6d194091bcf7, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:35,971 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:35,972 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:35,973 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, a991fc3b-5b27-4812-b94f-7c7dddfaed2d, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:35,974 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, a991fc3b-5b27-4812-b94f-7c7dddfaed2d, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:35,975 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, a991fc3b-5b27-4812-b94f-7c7dddfaed2d, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:38,495 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:38,496 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:38,497 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, bb8e6979-a4bd-4eb4-9123-dc2f80f3043f, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:38,497 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, bb8e6979-a4bd-4eb4-9123-dc2f80f3043f, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:38,499 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, bb8e6979-a4bd-4eb4-9123-dc2f80f3043f, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:39,227 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:39,228 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:39,229 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, db43c0de-2e57-439a-8f10-b451b5ed7603, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:39,229 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, db43c0de-2e57-439a-8f10-b451b5ed7603, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:39,230 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, db43c0de-2e57-439a-8f10-b451b5ed7603, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:40,638 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:40,638 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318544, 'BlobCount': 128}}
2025-12-07 20:17:40,639 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:40,640 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, f0a742fa-11f9-456d-8017-cc74eb3e5930, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:40,641 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, f0a742fa-11f9-456d-8017-cc74eb3e5930, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:40,643 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, f0a742fa-11f9-456d-8017-cc74eb3e5930, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:43,431 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:43,432 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:43,433 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 3d5a57e0-51e4-4636-a5cc-1e371ae937af, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:43,434 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 3d5a57e0-51e4-4636-a5cc-1e371ae937af, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:43,435 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 3d5a57e0-51e4-4636-a5cc-1e371ae937af, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:43,799 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:43,800 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:43,800 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, b402b303-6330-48a1-9a33-294dcb044792, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:43,801 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, b402b303-6330-48a1-9a33-294dcb044792, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:43,803 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, b402b303-6330-48a1-9a33-294dcb044792, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:44,211 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:44,212 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638064, 'BlobCount': 256}}
2025-12-07 20:17:44,212 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:44,213 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, fd33767d-3a4e-4955-9c80-1961f808e117, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:44,216 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, fd33767d-3a4e-4955-9c80-1961f808e117, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:44,217 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, fd33767d-3a4e-4955-9c80-1961f808e117, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:46,862 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:46,863 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:46,864 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, c210e093-1362-4e5d-97cf-e72e538912e2, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:46,864 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, c210e093-1362-4e5d-97cf-e72e538912e2, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:46,870 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, c210e093-1362-4e5d-97cf-e72e538912e2, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:47,234 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:47,235 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:47,236 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, a90964b0-5633-439b-b214-97710dd3f172, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:47,237 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, a90964b0-5633-439b-b214-97710dd3f172, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:47,240 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, a90964b0-5633-439b-b214-97710dd3f172, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:47,730 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:47,731 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957088, 'BlobCount': 384}}
2025-12-07 20:17:47,732 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:47,732 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, e00f2d65-5f4e-40eb-9082-b3d94ec082b7, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:47,733 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, e00f2d65-5f4e-40eb-9082-b3d94ec082b7, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:47,735 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, e00f2d65-5f4e-40eb-9082-b3d94ec082b7, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:50,419 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:50,420 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:50,421 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, f43fc85c-fd07-4817-83cf-3ba3ea45f5d6, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:50,422 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, f43fc85c-fd07-4817-83cf-3ba3ea45f5d6, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:50,423 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, f43fc85c-fd07-4817-83cf-3ba3ea45f5d6, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:50,717 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:50,718 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:50,718 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 5979c1b0-5336-4dc1-9717-d8d7cc39360b, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:50,719 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 5979c1b0-5336-4dc1-9717-d8d7cc39360b, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:50,721 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 5979c1b0-5336-4dc1-9717-d8d7cc39360b, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:51,096 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:51,097 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276560, 'BlobCount': 512}}
2025-12-07 20:17:51,097 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:51,098 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 9eaa34dd-e8f6-45e6-a2ca-1796f730849c, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:51,098 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 9eaa34dd-e8f6-45e6-a2ca-1796f730849c, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:51,099 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 9eaa34dd-e8f6-45e6-a2ca-1796f730849c, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:53,671 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:53,671 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:53,672 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, aba4d908-747c-4dd8-9377-2100e08724d1, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:53,673 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, aba4d908-747c-4dd8-9377-2100e08724d1, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:53,685 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, aba4d908-747c-4dd8-9377-2100e08724d1, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:54,007 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:54,008 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:54,009 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, b02b7054-67ce-49ac-af2a-4db2c0c0d7d6, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:54,010 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, b02b7054-67ce-49ac-af2a-4db2c0c0d7d6, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:54,011 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, b02b7054-67ce-49ac-af2a-4db2c0c0d7d6, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:54,561 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:54,562 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595584, 'BlobCount': 640}}
2025-12-07 20:17:54,562 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:54,563 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 2d5f02eb-25e1-4157-b2d0-6e087308633c, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:54,564 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 2d5f02eb-25e1-4157-b2d0-6e087308633c, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:54,565 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 2d5f02eb-25e1-4157-b2d0-6e087308633c, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:57,483 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:57,484 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:57,484 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, bd1e597b-aa03-4c49-b3e2-6f7d0d6546ea, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:57,485 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, bd1e597b-aa03-4c49-b3e2-6f7d0d6546ea, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:57,486 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, bd1e597b-aa03-4c49-b3e2-6f7d0d6546ea, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:57,858 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:57,859 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:57,860 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 959e9af7-fdb5-4c41-88db-b8f0eda52e50, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:57,861 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 959e9af7-fdb5-4c41-88db-b8f0eda52e50, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:57,862 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 959e9af7-fdb5-4c41-88db-b8f0eda52e50, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:17:58,431 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:58,431 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914960, 'BlobCount': 768}}
2025-12-07 20:17:58,432 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:17:58,434 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 2e2491b1-b3e5-4bc0-81a6-09fd00ecdfaf, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:17:58,436 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 2e2491b1-b3e5-4bc0-81a6-09fd00ecdfaf, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:17:58,437 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 2e2491b1-b3e5-4bc0-81a6-09fd00ecdfaf, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:01,142 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:01,143 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:01,144 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 9ce86594-a998-4fc0-80e0-50590b136a1d, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:01,144 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 9ce86594-a998-4fc0-80e0-50590b136a1d, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:01,154 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 9ce86594-a998-4fc0-80e0-50590b136a1d, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:01,536 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:01,537 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:01,538 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 2cdf51f9-9833-47ab-9b82-7aad375c18ee, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:01,539 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 2cdf51f9-9833-47ab-9b82-7aad375c18ee, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:01,540 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 2cdf51f9-9833-47ab-9b82-7aad375c18ee, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:02,159 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:02,160 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234080, 'BlobCount': 896}}
2025-12-07 20:18:02,161 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:02,161 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 81ffaf3e-647e-4f4e-a27d-d66c99688d7b, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:02,162 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 81ffaf3e-647e-4f4e-a27d-d66c99688d7b, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:02,164 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 81ffaf3e-647e-4f4e-a27d-d66c99688d7b, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:04,790 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:04,791 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:04,791 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 1053086f-7fb0-417c-9b55-db4d76a4724e, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:04,792 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 1053086f-7fb0-417c-9b55-db4d76a4724e, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:04,794 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 1053086f-7fb0-417c-9b55-db4d76a4724e, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:05,074 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:05,075 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:05,076 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 31299456-8fb1-490f-b686-2fad67cfe6e6, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:05,077 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 31299456-8fb1-490f-b686-2fad67cfe6e6, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:05,078 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 31299456-8fb1-490f-b686-2fad67cfe6e6, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:05,661 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:05,662 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553696, 'BlobCount': 1024}}
2025-12-07 20:18:05,663 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:05,663 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, a250c427-4bef-42f5-8c3e-42cf4be5b706, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:05,664 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, a250c427-4bef-42f5-8c3e-42cf4be5b706, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:05,674 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, a250c427-4bef-42f5-8c3e-42cf4be5b706, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:08,301 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:08,302 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:08,303 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, c29e6df4-f31e-4d88-9907-f94e008b553d, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:08,304 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, c29e6df4-f31e-4d88-9907-f94e008b553d, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:08,305 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, c29e6df4-f31e-4d88-9907-f94e008b553d, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:08,583 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:08,584 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:08,585 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 04959d80-5280-4799-a1d3-41eeefd378f2, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:08,586 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 04959d80-5280-4799-a1d3-41eeefd378f2, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:08,587 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 04959d80-5280-4799-a1d3-41eeefd378f2, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:09,210 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:09,211 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872720, 'BlobCount': 1152}}
2025-12-07 20:18:09,211 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:09,212 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, df843f11-6917-4dde-88f7-d7204f353114, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:09,213 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, df843f11-6917-4dde-88f7-d7204f353114, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:09,214 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, df843f11-6917-4dde-88f7-d7204f353114, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:11,966 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:11,976 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:11,978 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, f7264b0a-82f5-4c3f-9b94-b84ac5b9386b, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:11,979 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, f7264b0a-82f5-4c3f-9b94-b84ac5b9386b, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:11,981 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, f7264b0a-82f5-4c3f-9b94-b84ac5b9386b, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:12,352 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:12,375 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:12,376 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, a86221ce-4b5f-46fc-89af-fde84d641063, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:12,377 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, a86221ce-4b5f-46fc-89af-fde84d641063, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:12,379 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, a86221ce-4b5f-46fc-89af-fde84d641063, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:13,084 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:13,085 - INFO - __tests__.alter_compression.667945  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3192432, 'BlobCount': 1280}}
2025-12-07 20:18:13,085 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:13,086 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 96dc6296-d292-495a-a70a-ecd3a2ba031d, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:13,127 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 96dc6296-d292-495a-a70a-ecd3a2ba031d, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_15_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:13,129 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 96dc6296-d292-495a-a70a-ecd3a2ba031d, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:14,150 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:14,151 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:14,152 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, c3939057-2013-45d9-8577-6fd2b41e5868, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:14,153 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, c3939057-2013-45d9-8577-6fd2b41e5868, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:14,158 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, c3939057-2013-45d9-8577-6fd2b41e5868, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:14,424 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:14,425 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:14,426 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 178a16d6-ba38-47ee-a51b-e3f992747cd4, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:14,427 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 178a16d6-ba38-47ee-a51b-e3f992747cd4, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:14,429 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 178a16d6-ba38-47ee-a51b-e3f992747cd4, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:14,699 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:14,700 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:14,701 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, a7cd32ba-ee30-4485-82d6-a8973817b6f3, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:14,702 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, a7cd32ba-ee30-4485-82d6-a8973817b6f3, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:14,703 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, a7cd32ba-ee30-4485-82d6-a8973817b6f3, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:17,801 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.667945  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-07 20:18:17,812 - DEBUG - ydb.connection.667945  - 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-12-07 20:18:17,886 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.667945  - context_resolve: Resolving endpoints for database /Root
2025-12-07 20:18:17,892 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ListEndpoints, ef52f074-4da2-460b-ae62-770cc1c29506, localhost:8927): creating call state
2025-12-07 20:18:17,897 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ListEndpoints, ef52f074-4da2-460b-ae62-770cc1c29506, localhost:8927): request = { database: "/Root" }
2025-12-07 20:18:17,931 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ListEndpoints, ef52f074-4da2-460b-ae62-770cc1c29506, localhost:8927): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-7gzmsyrbqy.auto.internal" port: 8927 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-12-07 20:18:17,947 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.667945  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-7gzmsyrbqy.auto.internal:8927, location 1, ssl: False>]>
2025-12-07 20:18:17,949 - DEBUG - ydb.connection.667945  - close: Closing channel for endpoint localhost:8927
2025-12-07 20:18:18,493 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:28,535 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:28,535 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 1d4b595a-1735-43df-a8d6-b70dd424421a, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:28,536 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 1d4b595a-1735-43df-a8d6-b70dd424421a, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:28,542 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 1d4b595a-1735-43df-a8d6-b70dd424421a, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:31,792 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:31,848 - INFO - root.667945  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_15_compression`, volumes `value` (8000000, 1596216)
2025-12-07 20:18:31,863 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:31,863 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 285d2d3d-8e4b-44a2-928e-d766ee81b382, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:31,864 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 285d2d3d-8e4b-44a2-928e-d766ee81b382, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:31,866 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 285d2d3d-8e4b-44a2-928e-d766ee81b382, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:32,207 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:32,208 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:32,208 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 67f1bec6-e03b-416b-8d8c-9bf5b80b1a6a, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:32,209 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 67f1bec6-e03b-416b-8d8c-9bf5b80b1a6a, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:32,211 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 67f1bec6-e03b-416b-8d8c-9bf5b80b1a6a, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:35,375 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:45,418 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:45,431 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 583a3c1e-1ed7-4a9d-ae61-f6caa9d1c528, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:45,435 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 583a3c1e-1ed7-4a9d-ae61-f6caa9d1c528, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:45,436 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 583a3c1e-1ed7-4a9d-ae61-f6caa9d1c528, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:47,226 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:57,246 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:18:57,247 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, a37e31ca-d625-49ba-b531-e262228a5920, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:18:57,249 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, a37e31ca-d625-49ba-b531-e262228a5920, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:18:57,251 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, a37e31ca-d625-49ba-b531-e262228a5920, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:18:57,960 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:19:07,979 - DEBUG - ydb.query.pool.667945  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:19:07,991 - DEBUG - ydb.connection.667945  - _prepare_call: RpcState(ExecuteQuery, 53bbbfd0-9711-408e-b357-9b71643cac26, ghrun-7gzmsyrbqy.auto.internal:8927): creating call state
2025-12-07 20:19:07,991 - DEBUG - ydb.connection.667945  - _log_request: RpcState(ExecuteQuery, 53bbbfd0-9711-408e-b357-9b71643cac26, ghrun-7gzmsyrbqy.auto.internal:8927): request = { session_id: "ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-07 20:19:07,993 - DEBUG - ydb.connection.667945  - _log_response: RpcState(ExecuteQuery, 53bbbfd0-9711-408e-b357-9b71643cac26, ghrun-7gzmsyrbqy.auto.internal:8927): response = { <_MultiThreadedRendezvous object> }
2025-12-07 20:19:08,554 - DEBUG - ydb.query.pool.667945  - release: Session returned to queue: ydb://session/3?node_id=1&id=YTlkM2ViMzQtYWEwYjU2Y2MtYThiMjFkMzYtYWNjYzlmZmM=
2025-12-07 20:19:08,608 - INFO - root.667945  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_15_compression`, volumes `value` (8000000, 1870856)
2025-12-07 20:19:08,609 - INFO - root.667945  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_15_compression` 8092160 / 1870856: 4.325378329491954
2025-12-07 20:19:08,611 - INFO - ya.test.667945  - pytest_runtest_teardown: Test teardown
