2025-12-04 21:02:54,132 - INFO - ya.test.705492  - pytest_runtest_setup: ####################################################################################################
2025-12-04 21:02:54,133 - INFO - ya.test.705492  - pytest_runtest_setup: test_all_supported_compression[zstd_2_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 2]
2025-12-04 21:02:54,133 - INFO - ya.test.705492  - pytest_runtest_setup: ####################################################################################################
2025-12-04 21:02:54,133 - INFO - ya.test.705492  - pytest_runtest_setup: Test setup
2025-12-04 21:02:54,136 - DEBUG - ya.test.705492  - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-04 21:02:54,137 - DEBUG - ya.test.705492  - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-04 21:02:54,137 - DEBUG - ya.test.705492  - execute: Executing '['/home/runner/.ya/build/build_root/uyxd/001a1a/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 21:02:54,152 - DEBUG - ya.test.705492  - execute: Command pid: 707223
2025-12-04 21:02:55,313 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) rc: 0
2025-12-04 21:02:55,314 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) elapsed time (sec): 1.1598303318023682
2025-12-04 21:02:55,314 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) idrss: 0
2025-12-04 21:02:55,314 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) inblock: 0
2025-12-04 21:02:55,315 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) isrss: 0
2025-12-04 21:02:55,315 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) ixrss: 0
2025-12-04 21:02:55,315 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) majflt: 0
2025-12-04 21:02:55,320 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) maxrss: 491488
2025-12-04 21:02:55,320 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) minflt: 45771
2025-12-04 21:02:55,321 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) msgrcv: 0
2025-12-04 21:02:55,321 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) msgsnd: 0
2025-12-04 21:02:55,321 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) nivcsw: 188
2025-12-04 21:02:55,322 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) nsignals: 0
2025-12-04 21:02:55,322 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) nswap: 0
2025-12-04 21:02:55,322 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) nvcsw: 14
2025-12-04 21:02:55,322 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) oublock: 8
2025-12-04 21:02:55,323 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) stime: 0.252976
2025-12-04 21:02:55,323 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) utime: 0.49261299999999997
2025-12-04 21:02:55,323 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) wtime: 1.175
2025-12-04 21:02:55,328 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) output:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-fryxcz52gi    Host information: 
        Linux ghrun-fryxcz52gi 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/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-04 21:02:55,329 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 707223) errors:

2025-12-04 21:02:55,329 - INFO - __tests__.base.705492  - _setup_ydb: Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-fryxcz52gi    Host information: 
        Linux ghrun-fryxcz52gi 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/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-04 21:02:55,544 - DEBUG - ya.test.705492  - get_binary: Binary was found by /home/runner/.ya/build/build_root/uyxd/001a1a/ydb/apps/ydbd/ydbd
2025-12-04 21:02:55,546 - DEBUG - library.python.filelock.705492  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11329
2025-12-04 21:02:55,556 - DEBUG - ydb.tests.library.harness.kikimr_runner.705492  - start: Working directory: /home/runner/.ya/build/build_root/uyxd/001a1a/r3tmp/kikimr_cluster_kez9hbpx
2025-12-04 21:02:55,599 - DEBUG - ya.test.705492  - get_binary: Binary was found by /home/runner/.ya/build/build_root/uyxd/001a1a/ydb/apps/ydbd/ydbd
2025-12-04 21:02:55,600 - DEBUG - library.python.filelock.705492  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15331
2025-12-04 21:02:55,601 - DEBUG - library.python.filelock.705492  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63777
2025-12-04 21:02:55,601 - DEBUG - library.python.filelock.705492  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21749
2025-12-04 21:02:55,602 - DEBUG - library.python.filelock.705492  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5924
2025-12-04 21:02:55,603 - INFO - ydb.tests.library.harness.kikimr_runner.705492  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/uyxd/001a1a/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs"
2025-12-04 21:02:55,604 - INFO - ydb.tests.library.harness.kikimr_runner.705492  - __make_run_command: Final command: /home/runner/.ya/build/build_root/uyxd/001a1a/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/uyxd/001a1a/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_fo69g3e5.log --grpc-port=15331 --mon-port=63777 --ic-port=11329
2025-12-04 21:02:55,604 - DEBUG - ydb.tests.library.harness.kikimr_runner.705492  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:15331/1, disk_size 68719476736
2025-12-04 21:02:55,604 - INFO - ydb.tests.library.harness.kikimr_runner.705492  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/uyxd/001a1a/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs"
2025-12-04 21:02:55,605 - INFO - ydb.tests.library.harness.kikimr_runner.705492  - __make_run_command: Final command: /home/runner/.ya/build/build_root/uyxd/001a1a/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/uyxd/001a1a/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_fo69g3e5.log --grpc-port=15331 --mon-port=63777 --ic-port=11329
2025-12-04 21:02:55,606 - DEBUG - ya.test.705492  - execute: Executing '('/home/runner/.ya/build/build_root/uyxd/001a1a/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/uyxd/001a1a/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/uyxd/001a1a/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_fo69g3e5.log', '--grpc-port=15331', '--mon-port=63777', '--ic-port=11329')' in '/home/runner/.ya/build/build_root/uyxd/001a1a/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1' (no wait)
2025-12-04 21:02:55,625 - DEBUG - ya.test.705492  - execute: Command pid: 708059
2025-12-04 21:02:55,626 - INFO - ydb.tests.library.harness.kikimr_runner.705492  - start: Started node localhost:15331/1
2025-12-04 21:02:55,628 - DEBUG - ya.test.705492  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-04 21:02:55,629 - DEBUG - ya.test.705492  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-04 21:02:55,629 - DEBUG - ya.test.705492  - execute: Executing '['/home/runner/.ya/build/build_root/uyxd/001a1a/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 21:02:55,636 - DEBUG - ya.test.705492  - execute: Command pid: 708084
2025-12-04 21:02:57,182 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) rc: 0
2025-12-04 21:02:57,183 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) elapsed time (sec): 1.5407497882843018
2025-12-04 21:02:57,183 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) idrss: 0
2025-12-04 21:02:57,183 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) inblock: 0
2025-12-04 21:02:57,184 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) isrss: 0
2025-12-04 21:02:57,184 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) ixrss: 0
2025-12-04 21:02:57,184 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) majflt: 0
2025-12-04 21:02:57,185 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) maxrss: 502564
2025-12-04 21:02:57,185 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) minflt: 45750
2025-12-04 21:02:57,185 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) msgrcv: 0
2025-12-04 21:02:57,185 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) msgsnd: 0
2025-12-04 21:02:57,186 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) nivcsw: 217
2025-12-04 21:02:57,187 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) nsignals: 0
2025-12-04 21:02:57,195 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) nswap: 0
2025-12-04 21:02:57,195 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) nvcsw: 15
2025-12-04 21:02:57,195 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) oublock: 8
2025-12-04 21:02:57,196 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) stime: 0.223405
2025-12-04 21:02:57,196 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) utime: 0.49615099999999995
2025-12-04 21:02:57,196 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) wtime: 1.548
2025-12-04 21:02:57,197 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) output:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-fryxcz52gi    Host information: 
        Linux ghrun-fryxcz52gi 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/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-04 21:02:57,197 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 708084) errors:

2025-12-04 21:02:57,198 - INFO - ydb.tests.library.harness.kikimr_runner.705492  - start: Node 1 version:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-fryxcz52gi    Host information: 
        Linux ghrun-fryxcz52gi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 21:03:03,377 - DEBUG - ya.test.705492  - get_binary: Binary was found by /home/runner/.ya/build/build_root/uyxd/001a1a/ydb/apps/ydbd/ydbd
2025-12-04 21:03:03,378 - DEBUG - ydb.tests.library.harness.kikimr_runner.705492  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/uyxd/001a1a/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:15331', '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: 11329\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-04 21:03:03,379 - DEBUG - ya.test.705492  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-04 21:03:03,385 - DEBUG - ya.test.705492  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-04 21:03:03,386 - DEBUG - ya.test.705492  - execute: Executing '['/home/runner/.ya/build/build_root/uyxd/001a1a/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:15331', '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: 11329\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-04 21:03:03,400 - DEBUG - ya.test.705492  - execute: Command pid: 710058
2025-12-04 21:03:04,738 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) rc: 0
2025-12-04 21:03:04,739 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) elapsed time (sec): 1.3378071784973145
2025-12-04 21:03:04,739 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) idrss: 0
2025-12-04 21:03:04,740 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) inblock: 0
2025-12-04 21:03:04,740 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) isrss: 0
2025-12-04 21:03:04,740 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) ixrss: 0
2025-12-04 21:03:04,740 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) majflt: 0
2025-12-04 21:03:04,741 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) maxrss: 590452
2025-12-04 21:03:04,741 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) minflt: 52225
2025-12-04 21:03:04,741 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) msgrcv: 0
2025-12-04 21:03:04,742 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) msgsnd: 0
2025-12-04 21:03:04,742 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) nivcsw: 213
2025-12-04 21:03:04,742 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) nsignals: 0
2025-12-04 21:03:04,742 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) nswap: 0
2025-12-04 21:03:04,743 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) nvcsw: 690
2025-12-04 21:03:04,743 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) oublock: 8
2025-12-04 21:03:04,748 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) stime: 0.29920599999999997
2025-12-04 21:03:04,749 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) utime: 0.556028
2025-12-04 21:03:04,749 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) wtime: 1.352
2025-12-04 21:03:04,749 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-04 21:03:04,750 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710058) errors:

2025-12-04 21:03:04,751 - DEBUG - ya.test.705492  - get_binary: Binary was found by /home/runner/.ya/build/build_root/uyxd/001a1a/ydb/apps/ydbd/ydbd
2025-12-04 21:03:04,770 - DEBUG - ydb.tests.library.harness.kikimr_runner.705492  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/uyxd/001a1a/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:15331', '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-04 21:03:04,771 - DEBUG - ya.test.705492  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-04 21:03:04,772 - DEBUG - ya.test.705492  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-04 21:03:04,772 - DEBUG - ya.test.705492  - execute: Executing '['/home/runner/.ya/build/build_root/uyxd/001a1a/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:15331', '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-04 21:03:04,788 - DEBUG - ya.test.705492  - execute: Command pid: 710548
2025-12-04 21:03:06,100 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) rc: 0
2025-12-04 21:03:06,100 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) elapsed time (sec): 1.3108336925506592
2025-12-04 21:03:06,101 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) idrss: 0
2025-12-04 21:03:06,101 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) inblock: 0
2025-12-04 21:03:06,101 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) isrss: 0
2025-12-04 21:03:06,102 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) ixrss: 0
2025-12-04 21:03:06,102 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) majflt: 0
2025-12-04 21:03:06,102 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) maxrss: 591076
2025-12-04 21:03:06,102 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) minflt: 52176
2025-12-04 21:03:06,103 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) msgrcv: 0
2025-12-04 21:03:06,103 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) msgsnd: 0
2025-12-04 21:03:06,103 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) nivcsw: 256
2025-12-04 21:03:06,113 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) nsignals: 0
2025-12-04 21:03:06,114 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) nswap: 0
2025-12-04 21:03:06,114 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) nvcsw: 402
2025-12-04 21:03:06,114 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) oublock: 8
2025-12-04 21:03:06,114 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) stime: 0.313283
2025-12-04 21:03:06,115 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) utime: 0.540831
2025-12-04 21:03:06,115 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) wtime: 1.327
2025-12-04 21:03:06,115 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-04 21:03:06,116 - DEBUG - ya.test.705492  - _save_outputs: Command (pid 710548) errors:

2025-12-04 21:03:06,116 - INFO - ydb.tests.library.harness.kikimr_runner.705492  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-04 21:03:06,549 - INFO - ydb.tests.library.harness.kikimr_runner.705492  - __run: Cluster started and initialized
2025-12-04 21:03:06,589 - DEBUG - ydb.connection.705492  - 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-04 21:03:06,600 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.705492  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:03:06,601 - DEBUG - ydb.connection.705492  - 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-04 21:03:06,622 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.705492  - context_resolve: Resolving endpoints for database /Root
2025-12-04 21:03:06,623 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ListEndpoints, 49d3ab2f-f2a6-4244-ae12-824776146251, localhost:15331): creating call state
2025-12-04 21:03:06,624 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ListEndpoints, 49d3ab2f-f2a6-4244-ae12-824776146251, localhost:15331): request = { database: "/Root" }
2025-12-04 21:03:06,642 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ListEndpoints, 49d3ab2f-f2a6-4244-ae12-824776146251, localhost:15331): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-fryxcz52gi.auto.internal" port: 15331 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-04 21:03:06,643 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.705492  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-fryxcz52gi.auto.internal:15331, location 1, ssl: False>]>
2025-12-04 21:03:06,644 - DEBUG - ydb.connection.705492  - 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-04 21:03:06,668 - DEBUG - ydb.connection.705492  - close: Closing channel for endpoint localhost:15331
2025-12-04 21:03:06,669 - DEBUG - ydb.query.pool.705492  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-04 21:03:06,671 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(CreateSession, 0e7e2bd0-c53f-45fc-8bdb-136f567dc0ad, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:06,671 - DEBUG - ydb.connection.705492  - _log_request: RpcState(CreateSession, 0e7e2bd0-c53f-45fc-8bdb-136f567dc0ad, ghrun-fryxcz52gi.auto.internal:15331): request = {  }
2025-12-04 21:03:06,688 - DEBUG - ydb.connection.705492  - _log_response: RpcState(CreateSession, 0e7e2bd0-c53f-45fc-8bdb-136f567dc0ad, ghrun-fryxcz52gi.auto.internal:15331): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" node_id: 1 }
2025-12-04 21:03:06,689 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(AttachSession, b78c8031-09fe-4183-bc7a-9bd7e7ba06fc, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:06,689 - DEBUG - ydb.connection.705492  - _log_request: RpcState(AttachSession, b78c8031-09fe-4183-bc7a-9bd7e7ba06fc, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" }
2025-12-04 21:03:06,700 - DEBUG - ydb.connection.705492  - _log_response: RpcState(AttachSession, b78c8031-09fe-4183-bc7a-9bd7e7ba06fc, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:06,735 - DEBUG - ydb.query.pool.705492  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:06,736 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, a672da98-4b4b-4896-85a4-5729e822fa29, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:06,737 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, a672da98-4b4b-4896-85a4-5729e822fa29, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:06,739 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, a672da98-4b4b-4896-85a4-5729e822fa29, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:10,853 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:10,854 - INFO - __tests__.alter_compression.705492  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-12-04 21:03:10,855 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:10,855 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 86e007e8-1866-4907-ad47-81532c67cf25, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:10,856 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 86e007e8-1866-4907-ad47-81532c67cf25, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:10,865 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 86e007e8-1866-4907-ad47-81532c67cf25, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:12,156 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:12,157 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:12,158 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 59a0200c-b911-45e5-bcfb-4a07d9d14c87, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:12,159 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 59a0200c-b911-45e5-bcfb-4a07d9d14c87, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:12,162 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 59a0200c-b911-45e5-bcfb-4a07d9d14c87, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:13,585 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:13,586 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:13,587 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 3d9a4ec4-02dc-4ce2-8698-0caee93529c8, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:13,590 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 3d9a4ec4-02dc-4ce2-8698-0caee93529c8, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:13,592 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 3d9a4ec4-02dc-4ce2-8698-0caee93529c8, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:15,598 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:15,599 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:15,600 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, f7578ff2-0abc-448a-a4f4-1c2bb99a7a21, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:15,601 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, f7578ff2-0abc-448a-a4f4-1c2bb99a7a21, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:15,602 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, f7578ff2-0abc-448a-a4f4-1c2bb99a7a21, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:17,832 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:17,833 - INFO - __tests__.alter_compression.705492  - 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-04 21:03:17,833 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:17,834 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, a7164d52-dd74-4680-9113-dc9a888c2b5a, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:17,835 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, a7164d52-dd74-4680-9113-dc9a888c2b5a, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:17,840 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, a7164d52-dd74-4680-9113-dc9a888c2b5a, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:19,267 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:19,268 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:19,269 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, b39d4501-2ab1-4cb5-9e9f-7d35ff9a2461, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:19,270 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, b39d4501-2ab1-4cb5-9e9f-7d35ff9a2461, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:19,274 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, b39d4501-2ab1-4cb5-9e9f-7d35ff9a2461, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:19,685 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:19,686 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:19,687 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, db448163-a34d-4b47-8dbe-6c0d5355cf67, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:19,688 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, db448163-a34d-4b47-8dbe-6c0d5355cf67, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:19,689 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, db448163-a34d-4b47-8dbe-6c0d5355cf67, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:20,561 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:20,562 - INFO - __tests__.alter_compression.705492  - 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-04 21:03:20,563 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:20,563 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, f1b86fb3-3b2f-4673-adbd-cc858e9d7332, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:20,564 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, f1b86fb3-3b2f-4673-adbd-cc858e9d7332, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:20,567 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, f1b86fb3-3b2f-4673-adbd-cc858e9d7332, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:22,027 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:22,028 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:22,029 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, d3516e87-fc5c-4fbd-96a8-cf2d03903fd3, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:22,030 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, d3516e87-fc5c-4fbd-96a8-cf2d03903fd3, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:22,031 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, d3516e87-fc5c-4fbd-96a8-cf2d03903fd3, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:22,338 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:22,339 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:22,340 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 5d173c2a-5e25-4bca-b5b1-655464955e1e, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:22,341 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 5d173c2a-5e25-4bca-b5b1-655464955e1e, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:22,346 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 5d173c2a-5e25-4bca-b5b1-655464955e1e, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:23,061 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:23,062 - INFO - __tests__.alter_compression.705492  - 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-04 21:03:23,062 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:23,063 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 373eff2e-c648-4ab1-a635-80a954889d41, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:23,065 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 373eff2e-c648-4ab1-a635-80a954889d41, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:23,066 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 373eff2e-c648-4ab1-a635-80a954889d41, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:24,938 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:24,939 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:24,940 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 6b49e091-d1ce-43ae-b445-2e468c82ebc7, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:24,941 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 6b49e091-d1ce-43ae-b445-2e468c82ebc7, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:24,942 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 6b49e091-d1ce-43ae-b445-2e468c82ebc7, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:25,401 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:25,402 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:25,403 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 0d4c0b0c-6fdc-4e12-a765-9727eb899f51, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:25,404 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 0d4c0b0c-6fdc-4e12-a765-9727eb899f51, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:25,415 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 0d4c0b0c-6fdc-4e12-a765-9727eb899f51, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:26,107 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:26,108 - INFO - __tests__.alter_compression.705492  - 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-04 21:03:26,108 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:26,109 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, a69f5553-8ff5-48e9-858b-882edd9eb195, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:26,110 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, a69f5553-8ff5-48e9-858b-882edd9eb195, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:26,111 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, a69f5553-8ff5-48e9-858b-882edd9eb195, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:27,518 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:27,519 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:27,519 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 70d70b86-feb5-42c4-b4f4-216cf972d528, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:27,520 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 70d70b86-feb5-42c4-b4f4-216cf972d528, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:27,521 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 70d70b86-feb5-42c4-b4f4-216cf972d528, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:27,854 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:27,855 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:27,855 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 67c5005c-dc17-4af7-88d1-03bd0c541438, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:27,856 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 67c5005c-dc17-4af7-88d1-03bd0c541438, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:27,858 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 67c5005c-dc17-4af7-88d1-03bd0c541438, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:28,684 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:28,685 - INFO - __tests__.alter_compression.705492  - 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-04 21:03:28,685 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:28,686 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 7be37b73-f580-452a-ba1a-17e95263af4c, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:28,687 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 7be37b73-f580-452a-ba1a-17e95263af4c, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:28,689 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 7be37b73-f580-452a-ba1a-17e95263af4c, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:30,192 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:30,193 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:30,194 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 1d72d6a2-2b01-4113-b9cc-5dc1fafbd32b, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:30,195 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 1d72d6a2-2b01-4113-b9cc-5dc1fafbd32b, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:30,201 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 1d72d6a2-2b01-4113-b9cc-5dc1fafbd32b, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:30,697 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:30,698 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:30,699 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 9c11cb20-06c1-47cc-acb6-f3d2b7ec2570, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:30,700 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 9c11cb20-06c1-47cc-acb6-f3d2b7ec2570, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:30,711 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 9c11cb20-06c1-47cc-acb6-f3d2b7ec2570, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:31,801 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:31,802 - INFO - __tests__.alter_compression.705492  - 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-04 21:03:31,803 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:31,803 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, f9c74506-68f7-4243-8579-055164d0d26d, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:31,804 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, f9c74506-68f7-4243-8579-055164d0d26d, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:31,815 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, f9c74506-68f7-4243-8579-055164d0d26d, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:33,536 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:33,537 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:33,537 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 38083429-01eb-4b2c-828c-d8bb5a22ec86, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:33,538 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 38083429-01eb-4b2c-828c-d8bb5a22ec86, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:33,540 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 38083429-01eb-4b2c-828c-d8bb5a22ec86, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:34,076 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:34,077 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:34,078 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 728911ac-36a5-498e-828b-45463dde8bb7, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:34,079 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 728911ac-36a5-498e-828b-45463dde8bb7, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:34,093 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 728911ac-36a5-498e-828b-45463dde8bb7, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:34,759 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:34,760 - INFO - __tests__.alter_compression.705492  - 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-04 21:03:34,760 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:34,761 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 7eb47b24-8cd6-48a2-ae19-39906d1644cb, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:34,762 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 7eb47b24-8cd6-48a2-ae19-39906d1644cb, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:34,763 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 7eb47b24-8cd6-48a2-ae19-39906d1644cb, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:35,941 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:35,942 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:35,943 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, a4a6e7e4-adcd-4b98-9e09-4f99dbc72811, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:35,944 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, a4a6e7e4-adcd-4b98-9e09-4f99dbc72811, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:35,945 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, a4a6e7e4-adcd-4b98-9e09-4f99dbc72811, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:36,311 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:36,312 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:36,313 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, f9faa4e4-427d-4e7f-ae8e-a552783b3f07, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:36,313 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, f9faa4e4-427d-4e7f-ae8e-a552783b3f07, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:36,315 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, f9faa4e4-427d-4e7f-ae8e-a552783b3f07, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:37,096 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:37,097 - INFO - __tests__.alter_compression.705492  - 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-04 21:03:37,097 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:37,098 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 87a3168c-c34f-4087-b52b-bdb4f1fc7f77, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:37,099 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 87a3168c-c34f-4087-b52b-bdb4f1fc7f77, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:37,106 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 87a3168c-c34f-4087-b52b-bdb4f1fc7f77, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:38,967 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:38,968 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:38,968 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 3f691e32-bf1a-4849-a0a9-feb9f82236ed, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:38,969 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 3f691e32-bf1a-4849-a0a9-feb9f82236ed, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:38,970 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 3f691e32-bf1a-4849-a0a9-feb9f82236ed, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:39,510 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:39,511 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:39,512 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 8f2cf744-aed8-487f-988c-d982722f3d39, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:39,513 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 8f2cf744-aed8-487f-988c-d982722f3d39, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:39,516 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 8f2cf744-aed8-487f-988c-d982722f3d39, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:40,271 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:40,272 - INFO - __tests__.alter_compression.705492  - 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-04 21:03:40,273 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:40,273 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 6d375f3f-7d5c-48da-a3c1-89ca794966c2, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:40,274 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 6d375f3f-7d5c-48da-a3c1-89ca794966c2, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:40,276 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 6d375f3f-7d5c-48da-a3c1-89ca794966c2, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:41,741 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:41,741 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:41,742 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 2a4bd241-bbd1-40fd-99ee-775ea9c471e6, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:41,743 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 2a4bd241-bbd1-40fd-99ee-775ea9c471e6, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:41,756 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 2a4bd241-bbd1-40fd-99ee-775ea9c471e6, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:42,061 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:42,062 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:42,062 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, d6a659e9-6aaf-49a0-ad77-b5fc5dbff78a, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:42,063 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, d6a659e9-6aaf-49a0-ad77-b5fc5dbff78a, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:42,065 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, d6a659e9-6aaf-49a0-ad77-b5fc5dbff78a, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:43,167 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:43,168 - INFO - __tests__.alter_compression.705492  - 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-04 21:03:43,168 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:43,169 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, ebdde236-b486-4f8c-9b31-d1c87b4630b6, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:43,170 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, ebdde236-b486-4f8c-9b31-d1c87b4630b6, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:43,172 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, ebdde236-b486-4f8c-9b31-d1c87b4630b6, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:44,760 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:44,761 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:44,762 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, a85bdc0a-2d24-4eb0-8ac9-aae60612bf9a, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:44,763 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, a85bdc0a-2d24-4eb0-8ac9-aae60612bf9a, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:44,764 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, a85bdc0a-2d24-4eb0-8ac9-aae60612bf9a, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:45,178 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:45,179 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:45,180 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 56b23b08-c83b-490f-8ae8-ef20dd343804, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:45,181 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 56b23b08-c83b-490f-8ae8-ef20dd343804, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:45,184 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 56b23b08-c83b-490f-8ae8-ef20dd343804, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:45,523 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:45,523 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:03:45,524 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 831205f0-ed89-40a5-aeaf-fc28e25beb4e, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:03:45,525 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 831205f0-ed89-40a5-aeaf-fc28e25beb4e, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:03:45,526 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 831205f0-ed89-40a5-aeaf-fc28e25beb4e, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:51,992 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:02,083 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:02,084 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, d5c0e7dc-5ee4-4c7e-bb3e-842f918c19bc, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:02,085 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, d5c0e7dc-5ee4-4c7e-bb3e-842f918c19bc, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:04:02,086 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, d5c0e7dc-5ee4-4c7e-bb3e-842f918c19bc, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:07,103 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:07,146 - INFO - root.705492  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-12-04 21:04:07,147 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:07,148 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 1eb659c5-a320-4cdc-985e-6b6f0c9154e7, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:07,149 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 1eb659c5-a320-4cdc-985e-6b6f0c9154e7, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:04:07,150 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 1eb659c5-a320-4cdc-985e-6b6f0c9154e7, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:12,364 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:22,408 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:22,409 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, b271e0fc-3d3e-4c11-bdce-36b0a85346f6, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:22,409 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, b271e0fc-3d3e-4c11-bdce-36b0a85346f6, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:04:22,411 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, b271e0fc-3d3e-4c11-bdce-36b0a85346f6, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:23,754 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:23,758 - INFO - root.705492  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-12-04 21:04:23,758 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:23,759 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 0fd59a6c-fe4e-4901-844a-51e6b9ca4f1e, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:23,764 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 0fd59a6c-fe4e-4901-844a-51e6b9ca4f1e, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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-04 21:04:23,765 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 0fd59a6c-fe4e-4901-844a-51e6b9ca4f1e, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:24,444 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:24,449 - INFO - ya.test.705492  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_2_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 2])
2025-12-04 21:04:24,450 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:24,451 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 70ee6cf1-d7d1-484c-bba0-a0c38590f1da, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:24,452 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 70ee6cf1-d7d1-484c-bba0-a0c38590f1da, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_2_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 2)\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-04 21:04:24,475 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 70ee6cf1-d7d1-484c-bba0-a0c38590f1da, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:29,543 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:29,543 - INFO - __tests__.alter_compression.705492  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_2_compression created
2025-12-04 21:04:29,544 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:29,545 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, fc72ee5a-5369-4dc6-8ca7-4a84b22724ba, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:29,549 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, fc72ee5a-5369-4dc6-8ca7-4a84b22724ba, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_2_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:29,559 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, fc72ee5a-5369-4dc6-8ca7-4a84b22724ba, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:30,528 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:30,528 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:30,529 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 550d2b24-dda2-471f-b520-ba4e1cb6bc5f, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:30,530 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 550d2b24-dda2-471f-b520-ba4e1cb6bc5f, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:30,532 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 550d2b24-dda2-471f-b520-ba4e1cb6bc5f, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:32,197 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:32,198 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:32,198 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, ba37544a-5c57-482b-92cb-499079325a8c, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:32,199 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, ba37544a-5c57-482b-92cb-499079325a8c, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:32,201 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, ba37544a-5c57-482b-92cb-499079325a8c, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:33,277 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:33,278 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:33,279 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 222993dd-a334-4fc5-840f-fb7756c833e5, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:33,279 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 222993dd-a334-4fc5-840f-fb7756c833e5, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:33,281 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 222993dd-a334-4fc5-840f-fb7756c833e5, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:35,393 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:35,394 - INFO - __tests__.alter_compression.705492  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318880, 'BlobCount': 128}}
2025-12-04 21:04:35,394 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:35,395 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, c8ceaba7-a76f-4027-bb70-c79a9e45c003, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:35,396 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, c8ceaba7-a76f-4027-bb70-c79a9e45c003, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:35,397 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, c8ceaba7-a76f-4027-bb70-c79a9e45c003, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:36,831 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:36,832 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:36,833 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 12845c3e-c064-42ee-9e36-f1fcdf22957b, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:36,834 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 12845c3e-c064-42ee-9e36-f1fcdf22957b, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:36,835 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 12845c3e-c064-42ee-9e36-f1fcdf22957b, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:37,287 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:37,288 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:37,289 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, a4e1aa13-ad18-42ed-ba4b-60e74a15b01c, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:37,290 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, a4e1aa13-ad18-42ed-ba4b-60e74a15b01c, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:37,292 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, a4e1aa13-ad18-42ed-ba4b-60e74a15b01c, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:37,813 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:37,814 - INFO - __tests__.alter_compression.705492  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638656, 'BlobCount': 256}}
2025-12-04 21:04:37,814 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:37,815 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, a2a52470-cc05-4ca2-9a26-1cfbab590489, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:37,816 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, a2a52470-cc05-4ca2-9a26-1cfbab590489, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:37,824 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, a2a52470-cc05-4ca2-9a26-1cfbab590489, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:39,500 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:39,501 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:39,501 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, aa8070a7-3901-4d6d-812b-ca08cc8dff5d, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:39,502 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, aa8070a7-3901-4d6d-812b-ca08cc8dff5d, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:39,505 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, aa8070a7-3901-4d6d-812b-ca08cc8dff5d, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:39,938 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:39,939 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:39,939 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 9da9b93b-9211-4b59-a161-99ef5b8fea1b, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:39,940 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 9da9b93b-9211-4b59-a161-99ef5b8fea1b, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:39,944 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 9da9b93b-9211-4b59-a161-99ef5b8fea1b, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:40,592 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:40,603 - INFO - __tests__.alter_compression.705492  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957984, 'BlobCount': 384}}
2025-12-04 21:04:40,604 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:40,604 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 1721ad69-56e4-4307-bdfd-4626015d47ff, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:40,613 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 1721ad69-56e4-4307-bdfd-4626015d47ff, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:40,614 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 1721ad69-56e4-4307-bdfd-4626015d47ff, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:42,397 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:42,397 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:42,399 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 88e7744b-ff96-42a6-aa10-4d5e8e646708, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:42,400 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 88e7744b-ff96-42a6-aa10-4d5e8e646708, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:42,401 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 88e7744b-ff96-42a6-aa10-4d5e8e646708, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:42,754 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:42,755 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:42,755 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, f5d3934e-4e5d-466b-9c37-03369e3a4d59, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:42,758 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, f5d3934e-4e5d-466b-9c37-03369e3a4d59, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:42,760 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, f5d3934e-4e5d-466b-9c37-03369e3a4d59, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:43,473 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:43,474 - INFO - __tests__.alter_compression.705492  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1277728, 'BlobCount': 512}}
2025-12-04 21:04:43,475 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:43,475 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, f51ecf43-2e2e-4ee3-8d94-3d64744618cc, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:43,476 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, f51ecf43-2e2e-4ee3-8d94-3d64744618cc, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:43,478 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, f51ecf43-2e2e-4ee3-8d94-3d64744618cc, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:44,807 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:44,808 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:44,809 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 2522aba8-81ff-4924-ab63-2d4773654483, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:44,809 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 2522aba8-81ff-4924-ab63-2d4773654483, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:44,811 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 2522aba8-81ff-4924-ab63-2d4773654483, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:45,173 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:45,184 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:45,184 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 53c8b9ee-b639-4702-be5a-6df6e5f9eb32, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:45,185 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 53c8b9ee-b639-4702-be5a-6df6e5f9eb32, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:45,187 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 53c8b9ee-b639-4702-be5a-6df6e5f9eb32, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:45,946 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:45,946 - INFO - __tests__.alter_compression.705492  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1597184, 'BlobCount': 640}}
2025-12-04 21:04:45,949 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:45,950 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 9d198374-10a6-48cb-8808-fa504b666ab9, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:45,951 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 9d198374-10a6-48cb-8808-fa504b666ab9, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:45,954 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 9d198374-10a6-48cb-8808-fa504b666ab9, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:47,293 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:47,293 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:47,294 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, b10e00c0-b651-4bfa-88df-8e8020f0ca6a, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:47,296 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, b10e00c0-b651-4bfa-88df-8e8020f0ca6a, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:47,306 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, b10e00c0-b651-4bfa-88df-8e8020f0ca6a, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:47,648 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:47,651 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:47,651 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 13d094c7-bd16-4be0-ac11-f1f8456eea2d, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:47,652 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 13d094c7-bd16-4be0-ac11-f1f8456eea2d, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:47,654 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 13d094c7-bd16-4be0-ac11-f1f8456eea2d, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:48,196 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:48,197 - INFO - __tests__.alter_compression.705492  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1916800, 'BlobCount': 768}}
2025-12-04 21:04:48,204 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:48,205 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 33134c86-a5af-4ee9-82c2-d8cf22fbc528, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:48,206 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 33134c86-a5af-4ee9-82c2-d8cf22fbc528, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:48,207 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 33134c86-a5af-4ee9-82c2-d8cf22fbc528, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:48,218 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.705492  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:04:48,224 - DEBUG - ydb.connection.705492  - 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-04 21:04:48,236 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.705492  - context_resolve: Resolving endpoints for database /Root
2025-12-04 21:04:48,237 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ListEndpoints, 06619ab3-9d9d-4e45-99a5-febb002dc847, localhost:15331): creating call state
2025-12-04 21:04:48,238 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ListEndpoints, 06619ab3-9d9d-4e45-99a5-febb002dc847, localhost:15331): request = { database: "/Root" }
2025-12-04 21:04:48,247 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ListEndpoints, 06619ab3-9d9d-4e45-99a5-febb002dc847, localhost:15331): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-fryxcz52gi.auto.internal" port: 15331 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-04 21:04:48,248 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.705492  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-fryxcz52gi.auto.internal:15331, location 1, ssl: False>]>
2025-12-04 21:04:48,250 - DEBUG - ydb.connection.705492  - close: Closing channel for endpoint localhost:15331
2025-12-04 21:04:49,478 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:49,482 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:49,488 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, f73618f1-c391-45f8-bba4-6c2a68e31678, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:49,500 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, f73618f1-c391-45f8-bba4-6c2a68e31678, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:49,516 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, f73618f1-c391-45f8-bba4-6c2a68e31678, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:49,922 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:49,928 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:49,955 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 772b0343-6d47-457d-9b23-c24cbe2b4c88, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:49,970 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 772b0343-6d47-457d-9b23-c24cbe2b4c88, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:49,985 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 772b0343-6d47-457d-9b23-c24cbe2b4c88, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:50,795 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:50,796 - INFO - __tests__.alter_compression.705492  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2236496, 'BlobCount': 896}}
2025-12-04 21:04:50,797 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:50,798 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 4e86c6e6-a9ba-4664-a4e6-e5c812d30a51, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:50,799 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 4e86c6e6-a9ba-4664-a4e6-e5c812d30a51, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:50,800 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 4e86c6e6-a9ba-4664-a4e6-e5c812d30a51, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:52,067 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:52,072 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:52,073 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 9f84be89-09de-4056-b447-9f0c6474fbcf, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:52,074 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 9f84be89-09de-4056-b447-9f0c6474fbcf, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:52,076 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 9f84be89-09de-4056-b447-9f0c6474fbcf, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:52,481 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:52,492 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:52,499 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 632014c7-daad-484e-9122-6cde087cdc20, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:52,500 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 632014c7-daad-484e-9122-6cde087cdc20, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:52,504 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 632014c7-daad-484e-9122-6cde087cdc20, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:53,344 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:53,345 - INFO - __tests__.alter_compression.705492  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2556272, 'BlobCount': 1024}}
2025-12-04 21:04:53,346 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:53,346 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 496c8736-e371-49c4-b65e-ed09fdedaf88, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:53,348 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 496c8736-e371-49c4-b65e-ed09fdedaf88, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:53,357 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 496c8736-e371-49c4-b65e-ed09fdedaf88, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:54,559 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:54,560 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:54,561 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 1c681ca2-48ac-40da-b1dd-dcdc29746a55, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:54,565 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 1c681ca2-48ac-40da-b1dd-dcdc29746a55, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:54,567 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 1c681ca2-48ac-40da-b1dd-dcdc29746a55, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:54,957 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:54,958 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:54,959 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 573b9691-21d7-486c-a62f-7ee0fef9baf5, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:54,960 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 573b9691-21d7-486c-a62f-7ee0fef9baf5, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:54,961 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 573b9691-21d7-486c-a62f-7ee0fef9baf5, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:55,722 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:55,723 - INFO - __tests__.alter_compression.705492  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2875584, 'BlobCount': 1152}}
2025-12-04 21:04:55,723 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:55,724 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 13310012-b019-4f59-a50d-86dcb6401fd0, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:55,725 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 13310012-b019-4f59-a50d-86dcb6401fd0, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:55,727 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 13310012-b019-4f59-a50d-86dcb6401fd0, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:57,201 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:57,201 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:57,202 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 04d92626-a1e2-4c4f-9027-a8fc989ff05a, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:57,203 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 04d92626-a1e2-4c4f-9027-a8fc989ff05a, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:57,204 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 04d92626-a1e2-4c4f-9027-a8fc989ff05a, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:57,550 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:57,551 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:57,562 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, ecc7197a-f911-4687-95a4-a2a5087bd77b, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:57,563 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, ecc7197a-f911-4687-95a4-a2a5087bd77b, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:57,565 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, ecc7197a-f911-4687-95a4-a2a5087bd77b, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:58,478 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:58,483 - INFO - __tests__.alter_compression.705492  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3195568, 'BlobCount': 1280}}
2025-12-04 21:04:58,484 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:58,485 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 238fd175-5568-4a7c-af1f-f73bfb8cff56, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:58,485 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 238fd175-5568-4a7c-af1f-f73bfb8cff56, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_2_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:58,487 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 238fd175-5568-4a7c-af1f-f73bfb8cff56, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:59,624 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:59,643 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:04:59,652 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, a15b656a-61ff-41b3-9680-44b50015d28a, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:04:59,653 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, a15b656a-61ff-41b3-9680-44b50015d28a, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:04:59,661 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, a15b656a-61ff-41b3-9680-44b50015d28a, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:05:00,032 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:05:00,033 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:05:00,034 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 2f9b5a02-1b9a-450b-a22c-b2a4f2f85edf, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:05:00,034 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 2f9b5a02-1b9a-450b-a22c-b2a4f2f85edf, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:05:00,040 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 2f9b5a02-1b9a-450b-a22c-b2a4f2f85edf, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:05:00,523 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:05:00,524 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:05:00,525 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 2ff430ef-5ff1-4dd0-85c1-9da494db10c2, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:05:00,526 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 2ff430ef-5ff1-4dd0-85c1-9da494db10c2, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_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-04 21:05:00,528 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 2ff430ef-5ff1-4dd0-85c1-9da494db10c2, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:05:05,769 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:05:15,811 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:05:15,818 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 58b716b1-9618-45f1-8139-83b5c48b478d, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:05:15,819 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 58b716b1-9618-45f1-8139-83b5c48b478d, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_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-04 21:05:15,821 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 58b716b1-9618-45f1-8139-83b5c48b478d, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:05:21,252 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:05:21,299 - INFO - root.705492  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_2_compression`, volumes `value` (8000000, 1597784)
2025-12-04 21:05:21,300 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:05:21,301 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 99adb301-08b0-4e3d-a381-3dc62dfe793c, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:05:21,304 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 99adb301-08b0-4e3d-a381-3dc62dfe793c, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" 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_2_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-04 21:05:21,306 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 99adb301-08b0-4e3d-a381-3dc62dfe793c, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:05:21,753 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:05:21,754 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:05:21,755 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, 95b7602b-a519-4df8-af16-273d12c9c9b8, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:05:21,756 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, 95b7602b-a519-4df8-af16-273d12c9c9b8, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_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-04 21:05:21,757 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, 95b7602b-a519-4df8-af16-273d12c9c9b8, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:05:27,053 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:05:37,100 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:05:37,104 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, a105ca0f-0192-4bd0-96cf-3cc88eade90b, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:05:37,106 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, a105ca0f-0192-4bd0-96cf-3cc88eade90b, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_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-04 21:05:37,107 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, a105ca0f-0192-4bd0-96cf-3cc88eade90b, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:05:38,725 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:05:48,736 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:05:48,740 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, b5cb363e-2227-49c8-80fe-bbb3d3f160c2, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:05:48,782 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, b5cb363e-2227-49c8-80fe-bbb3d3f160c2, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_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-04 21:05:48,809 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, b5cb363e-2227-49c8-80fe-bbb3d3f160c2, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:05:50,183 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:06:00,208 - DEBUG - ydb.query.pool.705492  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:06:00,209 - DEBUG - ydb.connection.705492  - _prepare_call: RpcState(ExecuteQuery, d86aba1e-64a5-4981-8b59-5df998d8bc7b, ghrun-fryxcz52gi.auto.internal:15331): creating call state
2025-12-04 21:06:00,210 - DEBUG - ydb.connection.705492  - _log_request: RpcState(ExecuteQuery, d86aba1e-64a5-4981-8b59-5df998d8bc7b, ghrun-fryxcz52gi.auto.internal:15331): request = { session_id: "ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_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-04 21:06:00,221 - DEBUG - ydb.connection.705492  - _log_response: RpcState(ExecuteQuery, d86aba1e-64a5-4981-8b59-5df998d8bc7b, ghrun-fryxcz52gi.auto.internal:15331): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:06:01,669 - DEBUG - ydb.query.pool.705492  - release: Session returned to queue: ydb://session/3?node_id=1&id=OTkwYjgyZjQtYjg3MWJkZDQtNGZlYWJlNWYtOTg4N2RjNzk=
2025-12-04 21:06:01,673 - INFO - root.705492  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_2_compression`, volumes `value` (8000000, 1465320)
2025-12-04 21:06:01,683 - INFO - root.705492  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_2_compression` 8092160 / 1465320: 5.522452433598122
2025-12-04 21:06:01,686 - INFO - ya.test.705492  - pytest_runtest_teardown: Test teardown
