2025-11-19 11:19:58,786 - INFO - ya.test.421771  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:19:58,786 - INFO - ya.test.421771  - pytest_runtest_setup: test_all_supported_compression[zstd_15_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 15]
2025-11-19 11:19:58,786 - INFO - ya.test.421771  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:19:58,787 - INFO - ya.test.421771  - pytest_runtest_setup: Test setup
2025-11-19 11:19:58,789 - DEBUG - ya.test.421771  - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-19 11:19:58,790 - DEBUG - ya.test.421771  - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-19 11:19:58,790 - DEBUG - ya.test.421771  - execute: Executing '['/home/runner/.ya/build/build_root/pbux/001559/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-19 11:19:58,793 - DEBUG - ya.test.421771  - execute: Command pid: 422598
2025-11-19 11:19:59,865 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) rc: 0
2025-11-19 11:19:59,865 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) elapsed time (sec): 1.0710124969482422
2025-11-19 11:19:59,866 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) idrss: 0
2025-11-19 11:19:59,866 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) inblock: 0
2025-11-19 11:19:59,866 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) isrss: 0
2025-11-19 11:19:59,867 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) ixrss: 0
2025-11-19 11:19:59,867 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) majflt: 0
2025-11-19 11:19:59,867 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) maxrss: 493664
2025-11-19 11:19:59,868 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) minflt: 45384
2025-11-19 11:19:59,868 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) msgrcv: 0
2025-11-19 11:19:59,868 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) msgsnd: 0
2025-11-19 11:19:59,869 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) nivcsw: 170
2025-11-19 11:19:59,869 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) nsignals: 0
2025-11-19 11:19:59,869 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) nswap: 0
2025-11-19 11:19:59,869 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) nvcsw: 14
2025-11-19 11:19:59,870 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) oublock: 8
2025-11-19 11:19:59,870 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) stime: 0.24437599999999998
2025-11-19 11:19:59,870 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) utime: 0.520838
2025-11-19 11:19:59,871 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) wtime: 1.074
2025-11-19 11:19:59,871 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) output:
Arc info:
    Branch: unknown-vcs-branch
    Commit: THIS_REVISION_IS_A_DUMMY
    Author: ordinal
    Summary: No VCS
    Last Changed Rev: -1
    Last Changed Date: 2015-03-14T06:05:35.000000Z
Other info:
    Build by: runner
    Top src dir: 
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-alsqrntpxi    Host information: 
        Linux ghrun-alsqrntpxi 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-11-19 11:19:59,871 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422598) errors:

2025-11-19 11:19:59,872 - INFO - __tests__.base.421771  - _setup_ydb: Arc info:
    Branch: unknown-vcs-branch
    Commit: THIS_REVISION_IS_A_DUMMY
    Author: ordinal
    Summary: No VCS
    Last Changed Rev: -1
    Last Changed Date: 2015-03-14T06:05:35.000000Z
Other info:
    Build by: runner
    Top src dir: 
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-alsqrntpxi    Host information: 
        Linux ghrun-alsqrntpxi 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-11-19 11:20:00,013 - DEBUG - ya.test.421771  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pbux/001559/ydb/apps/ydbd/ydbd
2025-11-19 11:20:00,015 - DEBUG - library.python.filelock.421771  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63376
2025-11-19 11:20:00,016 - DEBUG - ydb.tests.library.harness.kikimr_runner.421771  - start: Working directory: /home/runner/.ya/build/build_root/pbux/001559/r3tmp/kikimr_cluster_kflydztv
2025-11-19 11:20:00,063 - DEBUG - ya.test.421771  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pbux/001559/ydb/apps/ydbd/ydbd
2025-11-19 11:20:00,064 - DEBUG - library.python.filelock.421771  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15505
2025-11-19 11:20:00,064 - DEBUG - library.python.filelock.421771  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15506
2025-11-19 11:20:00,065 - DEBUG - library.python.filelock.421771  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/31552
2025-11-19 11:20:00,066 - DEBUG - library.python.filelock.421771  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62832
2025-11-19 11:20:00,066 - DEBUG - library.python.filelock.421771  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21803
2025-11-19 11:20:00,067 - INFO - ydb.tests.library.harness.kikimr_runner.421771  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pbux/001559/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/kikimr_configs"
2025-11-19 11:20:00,068 - INFO - ydb.tests.library.harness.kikimr_runner.421771  - __make_run_command: Final command: /home/runner/.ya/build/build_root/pbux/001559/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/pbux/001559/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1/logfile_nq5xtykj.log --grpc-port=15506 --mon-port=31552 --ic-port=63376
2025-11-19 11:20:00,068 - DEBUG - ydb.tests.library.harness.kikimr_runner.421771  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:15506/1, disk_size 68719476736
2025-11-19 11:20:00,069 - INFO - ydb.tests.library.harness.kikimr_runner.421771  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pbux/001559/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/kikimr_configs"
2025-11-19 11:20:00,069 - INFO - ydb.tests.library.harness.kikimr_runner.421771  - __make_run_command: Final command: /home/runner/.ya/build/build_root/pbux/001559/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/pbux/001559/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1/logfile_nq5xtykj.log --grpc-port=15506 --mon-port=31552 --ic-port=63376
2025-11-19 11:20:00,070 - DEBUG - ya.test.421771  - execute: Executing '('/home/runner/.ya/build/build_root/pbux/001559/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/pbux/001559/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/pbux/001559/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1/logfile_nq5xtykj.log', '--grpc-port=15506', '--mon-port=31552', '--ic-port=63376')' in '/home/runner/.ya/build/build_root/pbux/001559/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1' (no wait)
2025-11-19 11:20:00,088 - DEBUG - ya.test.421771  - execute: Command pid: 422836
2025-11-19 11:20:00,089 - INFO - ydb.tests.library.harness.kikimr_runner.421771  - start: Started node localhost:15506/1
2025-11-19 11:20:00,091 - DEBUG - ya.test.421771  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-19 11:20:00,091 - DEBUG - ya.test.421771  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-19 11:20:00,092 - DEBUG - ya.test.421771  - execute: Executing '['/home/runner/.ya/build/build_root/pbux/001559/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-19 11:20:00,112 - DEBUG - ya.test.421771  - execute: Command pid: 422840
2025-11-19 11:20:01,143 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) rc: 0
2025-11-19 11:20:01,144 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) elapsed time (sec): 1.0300495624542236
2025-11-19 11:20:01,144 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) idrss: 0
2025-11-19 11:20:01,144 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) inblock: 0
2025-11-19 11:20:01,145 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) isrss: 0
2025-11-19 11:20:01,145 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) ixrss: 0
2025-11-19 11:20:01,145 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) majflt: 0
2025-11-19 11:20:01,145 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) maxrss: 495896
2025-11-19 11:20:01,146 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) minflt: 45455
2025-11-19 11:20:01,146 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) msgrcv: 0
2025-11-19 11:20:01,146 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) msgsnd: 0
2025-11-19 11:20:01,147 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) nivcsw: 177
2025-11-19 11:20:01,147 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) nsignals: 0
2025-11-19 11:20:01,147 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) nswap: 0
2025-11-19 11:20:01,148 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) nvcsw: 14
2025-11-19 11:20:01,148 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) oublock: 8
2025-11-19 11:20:01,148 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) stime: 0.251889
2025-11-19 11:20:01,148 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) utime: 0.549348
2025-11-19 11:20:01,149 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) wtime: 1.05
2025-11-19 11:20:01,149 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) output:
Arc info:
    Branch: unknown-vcs-branch
    Commit: THIS_REVISION_IS_A_DUMMY
    Author: ordinal
    Summary: No VCS
    Last Changed Rev: -1
    Last Changed Date: 2015-03-14T06:05:35.000000Z
Other info:
    Build by: runner
    Top src dir: 
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-alsqrntpxi    Host information: 
        Linux ghrun-alsqrntpxi 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-11-19 11:20:01,150 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 422840) errors:

2025-11-19 11:20:01,150 - INFO - ydb.tests.library.harness.kikimr_runner.421771  - start: Node 1 version:
Arc info:
    Branch: unknown-vcs-branch
    Commit: THIS_REVISION_IS_A_DUMMY
    Author: ordinal
    Summary: No VCS
    Last Changed Rev: -1
    Last Changed Date: 2015-03-14T06:05:35.000000Z
Other info:
    Build by: runner
    Top src dir: 
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-alsqrntpxi    Host information: 
        Linux ghrun-alsqrntpxi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-11-19 11:20:06,275 - DEBUG - ya.test.421771  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pbux/001559/ydb/apps/ydbd/ydbd
2025-11-19 11:20:06,277 - DEBUG - ydb.tests.library.harness.kikimr_runner.421771  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/pbux/001559/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:15506', '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: 63376\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-19 11:20:06,278 - DEBUG - ya.test.421771  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-19 11:20:06,279 - DEBUG - ya.test.421771  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-19 11:20:06,279 - DEBUG - ya.test.421771  - execute: Executing '['/home/runner/.ya/build/build_root/pbux/001559/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:15506', '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: 63376\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-19 11:20:06,297 - DEBUG - ya.test.421771  - execute: Command pid: 424516
2025-11-19 11:20:07,771 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) rc: 0
2025-11-19 11:20:07,772 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) elapsed time (sec): 1.4734950065612793
2025-11-19 11:20:07,772 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) idrss: 0
2025-11-19 11:20:07,772 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) inblock: 0
2025-11-19 11:20:07,772 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) isrss: 0
2025-11-19 11:20:07,773 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) ixrss: 0
2025-11-19 11:20:07,773 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) majflt: 0
2025-11-19 11:20:07,773 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) maxrss: 595580
2025-11-19 11:20:07,773 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) minflt: 51683
2025-11-19 11:20:07,774 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) msgrcv: 0
2025-11-19 11:20:07,774 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) msgsnd: 0
2025-11-19 11:20:07,774 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) nivcsw: 214
2025-11-19 11:20:07,775 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) nsignals: 0
2025-11-19 11:20:07,775 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) nswap: 0
2025-11-19 11:20:07,775 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) nvcsw: 700
2025-11-19 11:20:07,775 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) oublock: 8
2025-11-19 11:20:07,775 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) stime: 0.32114699999999996
2025-11-19 11:20:07,790 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) utime: 0.635076
2025-11-19 11:20:07,790 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) wtime: 1.491
2025-11-19 11:20:07,790 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-19 11:20:07,791 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424516) errors:

2025-11-19 11:20:07,792 - DEBUG - ya.test.421771  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pbux/001559/ydb/apps/ydbd/ydbd
2025-11-19 11:20:07,793 - DEBUG - ydb.tests.library.harness.kikimr_runner.421771  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/pbux/001559/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:15506', '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-11-19 11:20:07,794 - DEBUG - ya.test.421771  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-19 11:20:07,795 - DEBUG - ya.test.421771  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-19 11:20:07,795 - DEBUG - ya.test.421771  - execute: Executing '['/home/runner/.ya/build/build_root/pbux/001559/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:15506', '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-11-19 11:20:07,800 - DEBUG - ya.test.421771  - execute: Command pid: 424834
2025-11-19 11:20:08,775 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) rc: 0
2025-11-19 11:20:08,776 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) elapsed time (sec): 0.9744584560394287
2025-11-19 11:20:08,777 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) idrss: 0
2025-11-19 11:20:08,777 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) inblock: 0
2025-11-19 11:20:08,777 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) isrss: 0
2025-11-19 11:20:08,778 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) ixrss: 0
2025-11-19 11:20:08,778 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) majflt: 0
2025-11-19 11:20:08,778 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) maxrss: 595580
2025-11-19 11:20:08,779 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) minflt: 51810
2025-11-19 11:20:08,779 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) msgrcv: 0
2025-11-19 11:20:08,779 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) msgsnd: 0
2025-11-19 11:20:08,779 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) nivcsw: 178
2025-11-19 11:20:08,780 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) nsignals: 0
2025-11-19 11:20:08,780 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) nswap: 0
2025-11-19 11:20:08,781 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) nvcsw: 416
2025-11-19 11:20:08,781 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) oublock: 8
2025-11-19 11:20:08,781 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) stime: 0.24670099999999998
2025-11-19 11:20:08,781 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) utime: 0.5766709999999999
2025-11-19 11:20:08,782 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) wtime: 0.98
2025-11-19 11:20:08,782 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-19 11:20:08,782 - DEBUG - ya.test.421771  - _save_outputs: Command (pid 424834) errors:

2025-11-19 11:20:08,783 - INFO - ydb.tests.library.harness.kikimr_runner.421771  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-19 11:20:08,907 - INFO - ydb.tests.library.harness.kikimr_runner.421771  - __run: Cluster started and initialized
2025-11-19 11:20:08,945 - DEBUG - ydb.connection.421771  - 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-11-19 11:20:08,956 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.421771  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:20:08,957 - DEBUG - ydb.connection.421771  - 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-11-19 11:20:08,973 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.421771  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:20:08,974 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ListEndpoints, ce184f45-de6e-4f34-9809-9eb7f185a54e, localhost:15506): creating call state
2025-11-19 11:20:08,975 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ListEndpoints, ce184f45-de6e-4f34-9809-9eb7f185a54e, localhost:15506): request = { database: "/Root" }
2025-11-19 11:20:08,991 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ListEndpoints, ce184f45-de6e-4f34-9809-9eb7f185a54e, localhost:15506): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alsqrntpxi.auto.internal" port: 15506 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-11-19 11:20:08,992 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.421771  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alsqrntpxi.auto.internal:15506, location 1, ssl: False>]>
2025-11-19 11:20:08,993 - DEBUG - ydb.connection.421771  - 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-11-19 11:20:09,025 - DEBUG - ydb.connection.421771  - close: Closing channel for endpoint localhost:15506
2025-11-19 11:20:09,028 - DEBUG - ydb.query.pool.421771  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-19 11:20:09,029 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(CreateSession, 0dd78c29-11c2-40af-877f-9d7f2c5aa119, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:09,030 - DEBUG - ydb.connection.421771  - _log_request: RpcState(CreateSession, 0dd78c29-11c2-40af-877f-9d7f2c5aa119, ghrun-alsqrntpxi.auto.internal:15506): request = {  }
2025-11-19 11:20:09,041 - DEBUG - ydb.connection.421771  - _log_response: RpcState(CreateSession, 0dd78c29-11c2-40af-877f-9d7f2c5aa119, ghrun-alsqrntpxi.auto.internal:15506): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" node_id: 1 }
2025-11-19 11:20:09,042 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(AttachSession, fb1bd06e-e8b1-4e6d-8a35-b625b938aa4b, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:09,043 - DEBUG - ydb.connection.421771  - _log_request: RpcState(AttachSession, fb1bd06e-e8b1-4e6d-8a35-b625b938aa4b, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" }
2025-11-19 11:20:09,045 - DEBUG - ydb.connection.421771  - _log_response: RpcState(AttachSession, fb1bd06e-e8b1-4e6d-8a35-b625b938aa4b, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:09,056 - DEBUG - ydb.query.pool.421771  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:09,057 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, bd210b1f-64f9-41a4-a3da-aa1f26cb6c74, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:09,058 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, bd210b1f-64f9-41a4-a3da-aa1f26cb6c74, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:09,060 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, bd210b1f-64f9-41a4-a3da-aa1f26cb6c74, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:11,520 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:11,520 - INFO - __tests__.alter_compression.421771  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-19 11:20:11,521 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:11,522 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 8604f6c7-a833-485d-8241-e34f086ce059, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:11,523 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 8604f6c7-a833-485d-8241-e34f086ce059, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:11,524 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 8604f6c7-a833-485d-8241-e34f086ce059, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:12,614 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:12,615 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:12,615 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 6bc0efa1-b7bd-4b7c-9336-24eaa3913f30, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:12,616 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 6bc0efa1-b7bd-4b7c-9336-24eaa3913f30, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:12,618 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 6bc0efa1-b7bd-4b7c-9336-24eaa3913f30, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:13,745 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:13,746 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:13,747 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 70fcb180-4e28-47aa-acd7-6c305adb9b5f, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:13,748 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 70fcb180-4e28-47aa-acd7-6c305adb9b5f, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:13,749 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 70fcb180-4e28-47aa-acd7-6c305adb9b5f, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:14,602 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:14,603 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:14,603 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, c810c99f-9cab-4b36-8b69-812b13c6a11c, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:14,604 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, c810c99f-9cab-4b36-8b69-812b13c6a11c, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:14,605 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, c810c99f-9cab-4b36-8b69-812b13c6a11c, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:16,066 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:16,067 - INFO - __tests__.alter_compression.421771  - 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-11-19 11:20:16,068 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:16,069 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 8f0573cb-14b7-41b3-9778-043d2e88402f, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:16,070 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 8f0573cb-14b7-41b3-9778-043d2e88402f, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:16,071 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 8f0573cb-14b7-41b3-9778-043d2e88402f, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:16,992 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:16,993 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:16,993 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 66e52a96-91a0-4d7d-9971-282aa91d4d5f, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:16,994 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 66e52a96-91a0-4d7d-9971-282aa91d4d5f, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:16,996 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 66e52a96-91a0-4d7d-9971-282aa91d4d5f, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:17,253 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:17,254 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:17,254 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, ed37ed9b-b8ef-4251-a0fb-3723a503d964, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:17,255 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, ed37ed9b-b8ef-4251-a0fb-3723a503d964, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:17,256 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, ed37ed9b-b8ef-4251-a0fb-3723a503d964, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:17,593 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:17,594 - INFO - __tests__.alter_compression.421771  - 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-11-19 11:20:17,594 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:17,594 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 276c7bc8-f211-4b01-b0e5-52c2572b74d8, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:17,595 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 276c7bc8-f211-4b01-b0e5-52c2572b74d8, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:17,596 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 276c7bc8-f211-4b01-b0e5-52c2572b74d8, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:18,678 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:18,679 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:18,680 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 4d5e7822-f410-42c1-b913-1b758dbca32a, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:18,681 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 4d5e7822-f410-42c1-b913-1b758dbca32a, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:18,683 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 4d5e7822-f410-42c1-b913-1b758dbca32a, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:18,964 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:18,965 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:18,965 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 98de256f-bb22-41f7-b060-6bc34d28bb39, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:18,966 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 98de256f-bb22-41f7-b060-6bc34d28bb39, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:18,968 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 98de256f-bb22-41f7-b060-6bc34d28bb39, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:19,278 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:19,279 - INFO - __tests__.alter_compression.421771  - 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-11-19 11:20:19,279 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:19,280 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 8498cb23-2a29-4f40-bfe4-68d0d1a331f5, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:19,281 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 8498cb23-2a29-4f40-bfe4-68d0d1a331f5, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:19,282 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 8498cb23-2a29-4f40-bfe4-68d0d1a331f5, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:20,372 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:20,373 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:20,374 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 00755614-1d00-4cb1-b7da-51e4e161b163, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:20,374 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 00755614-1d00-4cb1-b7da-51e4e161b163, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:20,377 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 00755614-1d00-4cb1-b7da-51e4e161b163, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:20,672 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:20,673 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:20,673 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, cf6415e4-fba9-4a11-8306-87f1ec280326, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:20,674 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, cf6415e4-fba9-4a11-8306-87f1ec280326, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:20,677 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, cf6415e4-fba9-4a11-8306-87f1ec280326, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:21,157 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:21,158 - INFO - __tests__.alter_compression.421771  - 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-11-19 11:20:21,158 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:21,159 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 43394edc-1b37-4c67-ac07-619ca6269ecf, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:21,160 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 43394edc-1b37-4c67-ac07-619ca6269ecf, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:21,167 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 43394edc-1b37-4c67-ac07-619ca6269ecf, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:22,214 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:22,215 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:22,216 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 188da618-ea51-4942-9e2e-d2a643a98b02, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:22,217 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 188da618-ea51-4942-9e2e-d2a643a98b02, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:22,218 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 188da618-ea51-4942-9e2e-d2a643a98b02, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:22,510 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:22,511 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:22,511 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 6d94adac-048f-4715-92f8-b92776ffd582, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:22,513 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 6d94adac-048f-4715-92f8-b92776ffd582, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:22,514 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 6d94adac-048f-4715-92f8-b92776ffd582, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:23,085 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:23,086 - INFO - __tests__.alter_compression.421771  - 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-11-19 11:20:23,086 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:23,087 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, e4368ec7-71cd-43bc-a675-b6f8cf9ba282, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:23,089 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, e4368ec7-71cd-43bc-a675-b6f8cf9ba282, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:23,091 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, e4368ec7-71cd-43bc-a675-b6f8cf9ba282, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:24,272 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:24,273 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:24,274 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 23fd5cfa-ed78-49f3-ad66-ea5b7ea5734c, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:24,275 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 23fd5cfa-ed78-49f3-ad66-ea5b7ea5734c, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:24,276 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 23fd5cfa-ed78-49f3-ad66-ea5b7ea5734c, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:24,604 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:24,605 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:24,606 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, ae931b04-cd7c-40d7-acdb-b68c7e87ad32, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:24,607 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, ae931b04-cd7c-40d7-acdb-b68c7e87ad32, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:24,608 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, ae931b04-cd7c-40d7-acdb-b68c7e87ad32, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:25,219 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:25,220 - INFO - __tests__.alter_compression.421771  - 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-11-19 11:20:25,220 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:25,221 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, c84bf0ed-a48f-4f64-be1e-80ff38148055, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:25,222 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, c84bf0ed-a48f-4f64-be1e-80ff38148055, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:25,233 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, c84bf0ed-a48f-4f64-be1e-80ff38148055, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:26,495 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:26,496 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:26,497 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 771f1aa0-0e11-4506-b1ea-d99d67d8f17c, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:26,498 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 771f1aa0-0e11-4506-b1ea-d99d67d8f17c, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:26,500 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 771f1aa0-0e11-4506-b1ea-d99d67d8f17c, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:26,778 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:26,779 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:26,780 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 9c45f6e1-1b7c-45ff-b785-10fc30686de9, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:26,780 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 9c45f6e1-1b7c-45ff-b785-10fc30686de9, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:26,781 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 9c45f6e1-1b7c-45ff-b785-10fc30686de9, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:27,325 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:27,326 - INFO - __tests__.alter_compression.421771  - 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-11-19 11:20:27,326 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:27,327 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, e23aa607-6944-4cfe-886c-e0b7a2ecbd25, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:27,328 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, e23aa607-6944-4cfe-886c-e0b7a2ecbd25, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:27,329 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, e23aa607-6944-4cfe-886c-e0b7a2ecbd25, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:28,604 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:28,605 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:28,606 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, e621426f-fb52-48b7-8c87-b94968d447ed, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:28,607 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, e621426f-fb52-48b7-8c87-b94968d447ed, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:28,609 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, e621426f-fb52-48b7-8c87-b94968d447ed, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:28,939 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:28,940 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:28,941 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 3de0bd88-268e-4bff-b7ee-56a39f6d164b, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:28,942 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 3de0bd88-268e-4bff-b7ee-56a39f6d164b, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:28,943 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 3de0bd88-268e-4bff-b7ee-56a39f6d164b, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:29,636 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:29,637 - INFO - __tests__.alter_compression.421771  - 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-11-19 11:20:29,638 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:29,639 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 06b31a1a-b7dc-4d88-b792-353aaa066aaf, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:29,641 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 06b31a1a-b7dc-4d88-b792-353aaa066aaf, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:29,643 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 06b31a1a-b7dc-4d88-b792-353aaa066aaf, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:30,805 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:30,806 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:30,807 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 994d2628-7b70-49bb-bb53-f82a209f9417, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:30,808 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 994d2628-7b70-49bb-bb53-f82a209f9417, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:30,809 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 994d2628-7b70-49bb-bb53-f82a209f9417, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:31,057 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:31,058 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:31,059 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 25476ce2-5ab2-4f7d-8748-aa348963d918, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:31,060 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 25476ce2-5ab2-4f7d-8748-aa348963d918, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:31,062 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 25476ce2-5ab2-4f7d-8748-aa348963d918, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:31,727 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:31,728 - INFO - __tests__.alter_compression.421771  - 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-11-19 11:20:31,728 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:31,729 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, d857144f-2b98-4457-8345-d1f1099a19a4, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:31,730 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, d857144f-2b98-4457-8345-d1f1099a19a4, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:31,735 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, d857144f-2b98-4457-8345-d1f1099a19a4, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:32,830 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:32,830 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:32,831 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, b390a88c-3e4f-4ba0-aa68-f1ea83fff954, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:32,832 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, b390a88c-3e4f-4ba0-aa68-f1ea83fff954, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:32,833 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, b390a88c-3e4f-4ba0-aa68-f1ea83fff954, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:33,181 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:33,182 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:33,182 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, ee10c012-e779-467a-88f3-8c1c833b3716, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:33,183 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, ee10c012-e779-467a-88f3-8c1c833b3716, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:33,189 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, ee10c012-e779-467a-88f3-8c1c833b3716, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:33,900 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:33,901 - INFO - __tests__.alter_compression.421771  - 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-11-19 11:20:33,902 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:33,902 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, f9d79374-fa63-4601-9d8a-e5d65e77182a, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:33,903 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, f9d79374-fa63-4601-9d8a-e5d65e77182a, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:33,905 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, f9d79374-fa63-4601-9d8a-e5d65e77182a, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:34,979 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:34,980 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:34,981 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 362a1a90-42fe-4fd9-9681-4b07c6a4d662, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:34,982 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 362a1a90-42fe-4fd9-9681-4b07c6a4d662, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:34,984 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 362a1a90-42fe-4fd9-9681-4b07c6a4d662, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:35,217 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:35,218 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:35,219 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 0b89e52c-c817-41b2-ad76-bcd388b98136, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:35,219 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 0b89e52c-c817-41b2-ad76-bcd388b98136, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:35,221 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 0b89e52c-c817-41b2-ad76-bcd388b98136, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:35,435 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:35,436 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:35,437 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 6af7a50c-a46e-4a2e-8493-60117910e7e6, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:35,438 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 6af7a50c-a46e-4a2e-8493-60117910e7e6, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:35,441 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 6af7a50c-a46e-4a2e-8493-60117910e7e6, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:39,322 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:49,369 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:49,370 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, a33c2310-c0f8-4d40-8746-373816d34cfe, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:49,371 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, a33c2310-c0f8-4d40-8746-373816d34cfe, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:49,372 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, a33c2310-c0f8-4d40-8746-373816d34cfe, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:52,418 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:52,463 - INFO - root.421771  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-19 11:20:52,464 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:20:52,464 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 4aa874ba-83a6-4471-9387-d9c90e57184d, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:20:52,465 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 4aa874ba-83a6-4471-9387-d9c90e57184d, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:52,466 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 4aa874ba-83a6-4471-9387-d9c90e57184d, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:55,422 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:05,476 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:05,477 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, b6283503-ff3c-4634-adc1-82b07a4b29c1, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:05,478 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, b6283503-ff3c-4634-adc1-82b07a4b29c1, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:05,479 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, b6283503-ff3c-4634-adc1-82b07a4b29c1, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:08,677 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:08,719 - INFO - root.421771  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-19 11:21:08,720 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:08,721 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, a1e5e778-9172-451d-8061-cc58b79cfa05, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:08,721 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, a1e5e778-9172-451d-8061-cc58b79cfa05, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:08,724 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, a1e5e778-9172-451d-8061-cc58b79cfa05, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:09,002 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:09,006 - INFO - ya.test.421771  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_15_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 15])
2025-11-19 11:21:09,006 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:09,007 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, b1b4ead7-cd5c-4a0a-a7b9-4e68d7144760, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:09,009 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, b1b4ead7-cd5c-4a0a-a7b9-4e68d7144760, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_15_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 15)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:09,010 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, b1b4ead7-cd5c-4a0a-a7b9-4e68d7144760, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:13,056 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:13,057 - INFO - __tests__.alter_compression.421771  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_15_compression created
2025-11-19 11:21:13,058 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:13,058 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, d92aa924-7ec5-45b1-b907-36b83a16fc2d, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:13,059 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, d92aa924-7ec5-45b1-b907-36b83a16fc2d, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_15_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:13,060 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, d92aa924-7ec5-45b1-b907-36b83a16fc2d, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:13,872 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:13,873 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:13,874 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 33ffa849-9557-4185-9d42-30a9cb5ff592, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:13,875 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 33ffa849-9557-4185-9d42-30a9cb5ff592, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:13,876 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 33ffa849-9557-4185-9d42-30a9cb5ff592, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:16,921 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:16,922 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:16,923 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, f973627c-d54d-46b0-86b1-58507b1d3392, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:16,924 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, f973627c-d54d-46b0-86b1-58507b1d3392, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:16,925 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, f973627c-d54d-46b0-86b1-58507b1d3392, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:17,924 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:17,926 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:17,927 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, ef8d3f0d-2dc7-425a-9069-18e507df0adc, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:17,928 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, ef8d3f0d-2dc7-425a-9069-18e507df0adc, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:17,931 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, ef8d3f0d-2dc7-425a-9069-18e507df0adc, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:19,828 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:19,829 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318544, 'BlobCount': 128}}
2025-11-19 11:21:19,830 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:19,830 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, f2d1136e-da50-4ef2-9a22-cfb2380a8a9a, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:19,831 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, f2d1136e-da50-4ef2-9a22-cfb2380a8a9a, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:19,833 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, f2d1136e-da50-4ef2-9a22-cfb2380a8a9a, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:22,729 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:22,730 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:22,730 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 872aa134-1451-467e-8eb1-a2db30fd9c35, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:22,731 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 872aa134-1451-467e-8eb1-a2db30fd9c35, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:22,733 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 872aa134-1451-467e-8eb1-a2db30fd9c35, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:23,041 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:23,042 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:23,043 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 2c39bb80-497d-4e6b-bdc1-b4ca65105167, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:23,044 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 2c39bb80-497d-4e6b-bdc1-b4ca65105167, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:23,047 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 2c39bb80-497d-4e6b-bdc1-b4ca65105167, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:23,409 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:23,410 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638064, 'BlobCount': 256}}
2025-11-19 11:21:23,410 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:23,411 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 92ddcf9b-afec-40f1-adf6-7b608a41d60a, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:23,414 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 92ddcf9b-afec-40f1-adf6-7b608a41d60a, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:23,415 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 92ddcf9b-afec-40f1-adf6-7b608a41d60a, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:23,446 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.421771  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:21:23,447 - DEBUG - ydb.connection.421771  - 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-11-19 11:21:23,484 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.421771  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:21:23,485 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ListEndpoints, 569a16ec-24a4-435c-a14f-0b7bb4ac6b84, localhost:15506): creating call state
2025-11-19 11:21:23,486 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ListEndpoints, 569a16ec-24a4-435c-a14f-0b7bb4ac6b84, localhost:15506): request = { database: "/Root" }
2025-11-19 11:21:23,497 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ListEndpoints, 569a16ec-24a4-435c-a14f-0b7bb4ac6b84, localhost:15506): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alsqrntpxi.auto.internal" port: 15506 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-11-19 11:21:23,498 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.421771  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alsqrntpxi.auto.internal:15506, location 1, ssl: False>]>
2025-11-19 11:21:23,499 - DEBUG - ydb.connection.421771  - close: Closing channel for endpoint localhost:15506
2025-11-19 11:21:26,014 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:26,015 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:26,015 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, d6f5c102-c488-4304-92f2-84a8124cbe53, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:26,016 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, d6f5c102-c488-4304-92f2-84a8124cbe53, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:26,018 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, d6f5c102-c488-4304-92f2-84a8124cbe53, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:26,365 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:26,366 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:26,366 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 5670e66e-223d-4541-9ead-35f6ec53a3bf, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:26,367 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 5670e66e-223d-4541-9ead-35f6ec53a3bf, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:26,369 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 5670e66e-223d-4541-9ead-35f6ec53a3bf, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:26,804 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:26,805 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957088, 'BlobCount': 384}}
2025-11-19 11:21:26,805 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:26,806 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 3ad98891-3bf6-4928-b1f0-7b6393d7f0de, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:26,807 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 3ad98891-3bf6-4928-b1f0-7b6393d7f0de, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:26,809 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 3ad98891-3bf6-4928-b1f0-7b6393d7f0de, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:29,564 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:29,565 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:29,565 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 94ada1c2-9a56-48e7-a316-f51dcb622940, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:29,566 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 94ada1c2-9a56-48e7-a316-f51dcb622940, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:29,568 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 94ada1c2-9a56-48e7-a316-f51dcb622940, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:29,964 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:29,965 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:29,966 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 3c78d144-75df-4e5e-8642-b723a88c1e99, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:29,967 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 3c78d144-75df-4e5e-8642-b723a88c1e99, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:29,969 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 3c78d144-75df-4e5e-8642-b723a88c1e99, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:30,580 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:30,581 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276560, 'BlobCount': 512}}
2025-11-19 11:21:30,582 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:30,582 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, bcc0347f-1c9a-46c3-a990-7fbf530037dc, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:30,583 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, bcc0347f-1c9a-46c3-a990-7fbf530037dc, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:30,585 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, bcc0347f-1c9a-46c3-a990-7fbf530037dc, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:33,572 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:33,573 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:33,574 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, ece6be86-cb43-4068-a0b4-e534a3c0e8b3, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:33,574 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, ece6be86-cb43-4068-a0b4-e534a3c0e8b3, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:33,576 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, ece6be86-cb43-4068-a0b4-e534a3c0e8b3, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:33,828 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:33,829 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:33,829 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 1ce03e55-03cf-49ef-99e2-da06e500f40c, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:33,830 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 1ce03e55-03cf-49ef-99e2-da06e500f40c, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:33,831 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 1ce03e55-03cf-49ef-99e2-da06e500f40c, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:34,357 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:34,358 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595584, 'BlobCount': 640}}
2025-11-19 11:21:34,358 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:34,359 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, aaee398b-cd66-48a9-b95c-c78d0338c15c, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:34,360 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, aaee398b-cd66-48a9-b95c-c78d0338c15c, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:34,362 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, aaee398b-cd66-48a9-b95c-c78d0338c15c, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:37,431 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:37,432 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:37,432 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 38dcd147-f521-45c5-82f2-bcfab3f6852f, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:37,433 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 38dcd147-f521-45c5-82f2-bcfab3f6852f, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:37,435 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 38dcd147-f521-45c5-82f2-bcfab3f6852f, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:37,755 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:37,756 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:37,757 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 69252e97-6498-47aa-bad2-89e2ab5b0072, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:37,758 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 69252e97-6498-47aa-bad2-89e2ab5b0072, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:37,760 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 69252e97-6498-47aa-bad2-89e2ab5b0072, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:38,318 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:38,319 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914960, 'BlobCount': 768}}
2025-11-19 11:21:38,319 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:38,320 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 703512a2-1c90-4e21-9aec-9cd6e970b4f9, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:38,321 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 703512a2-1c90-4e21-9aec-9cd6e970b4f9, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:38,322 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 703512a2-1c90-4e21-9aec-9cd6e970b4f9, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:41,310 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:41,310 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:41,311 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 72a7a3a1-c17a-4e10-aa10-070336a3364a, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:41,312 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 72a7a3a1-c17a-4e10-aa10-070336a3364a, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:41,313 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 72a7a3a1-c17a-4e10-aa10-070336a3364a, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:41,619 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:41,620 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:41,621 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 66b86185-33ed-4798-8351-c956c122a6f5, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:41,622 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 66b86185-33ed-4798-8351-c956c122a6f5, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:41,624 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 66b86185-33ed-4798-8351-c956c122a6f5, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:42,254 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:42,255 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234080, 'BlobCount': 896}}
2025-11-19 11:21:42,258 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:42,259 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, bc509ded-7b02-474b-9ff9-160d3c5436f8, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:42,261 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, bc509ded-7b02-474b-9ff9-160d3c5436f8, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:42,263 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, bc509ded-7b02-474b-9ff9-160d3c5436f8, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:44,764 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:44,764 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:44,765 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, a3bbc8ba-29d8-44b1-ac40-097e64b3fd1e, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:44,766 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, a3bbc8ba-29d8-44b1-ac40-097e64b3fd1e, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:44,767 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, a3bbc8ba-29d8-44b1-ac40-097e64b3fd1e, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:45,069 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:45,070 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:45,071 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 5ccb2f74-92fe-4af6-aed8-dd4987e2c866, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:45,072 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 5ccb2f74-92fe-4af6-aed8-dd4987e2c866, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:45,080 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 5ccb2f74-92fe-4af6-aed8-dd4987e2c866, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:45,678 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:45,679 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553696, 'BlobCount': 1024}}
2025-11-19 11:21:45,679 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:45,680 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 8c5aceb8-3011-403b-86c2-8b2a084eb64f, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:45,681 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 8c5aceb8-3011-403b-86c2-8b2a084eb64f, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:45,682 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 8c5aceb8-3011-403b-86c2-8b2a084eb64f, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:48,314 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:48,314 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:48,315 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, c0d22ae4-cc91-4fdc-bca3-a7d20c3a81a7, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:48,317 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, c0d22ae4-cc91-4fdc-bca3-a7d20c3a81a7, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:48,318 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, c0d22ae4-cc91-4fdc-bca3-a7d20c3a81a7, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:48,601 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:48,602 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:48,602 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 65eaa43d-ede7-4d37-b547-2c24b81f2709, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:48,603 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 65eaa43d-ede7-4d37-b547-2c24b81f2709, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:48,604 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 65eaa43d-ede7-4d37-b547-2c24b81f2709, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:49,201 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:49,202 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872720, 'BlobCount': 1152}}
2025-11-19 11:21:49,203 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:49,203 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, fe117d5f-e5ff-4bb7-bc52-4771c5beca97, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:49,205 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, fe117d5f-e5ff-4bb7-bc52-4771c5beca97, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:49,206 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, fe117d5f-e5ff-4bb7-bc52-4771c5beca97, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:51,444 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:51,444 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:51,445 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 9d9e5fe6-9c05-457d-9bf4-fdf40f1f88d8, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:51,445 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 9d9e5fe6-9c05-457d-9bf4-fdf40f1f88d8, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:51,447 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 9d9e5fe6-9c05-457d-9bf4-fdf40f1f88d8, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:51,750 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:51,751 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:51,752 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, d20c20a8-72b0-4cd8-89be-c0b33b31973e, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:51,753 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, d20c20a8-72b0-4cd8-89be-c0b33b31973e, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:51,754 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, d20c20a8-72b0-4cd8-89be-c0b33b31973e, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:52,373 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:52,373 - INFO - __tests__.alter_compression.421771  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3192432, 'BlobCount': 1280}}
2025-11-19 11:21:52,374 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:52,374 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, ae99d8f1-6b1a-4dcf-8502-51df12026899, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:52,375 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, ae99d8f1-6b1a-4dcf-8502-51df12026899, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_15_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:52,376 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, ae99d8f1-6b1a-4dcf-8502-51df12026899, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:53,328 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:53,329 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:53,330 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 87c3475c-b65b-4b2c-a284-106484007bf2, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:53,330 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 87c3475c-b65b-4b2c-a284-106484007bf2, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:53,332 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 87c3475c-b65b-4b2c-a284-106484007bf2, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:53,595 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:53,595 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:53,596 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 5f61e28e-cee3-44f3-8528-ac72ea5dc343, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:53,597 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 5f61e28e-cee3-44f3-8528-ac72ea5dc343, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:53,598 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 5f61e28e-cee3-44f3-8528-ac72ea5dc343, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:53,887 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:53,888 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:21:53,888 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, e2109371-e801-430f-9bfd-0848842b8719, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:21:53,889 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, e2109371-e801-430f-9bfd-0848842b8719, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:53,891 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, e2109371-e801-430f-9bfd-0848842b8719, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:57,816 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:22:07,893 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:22:07,894 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 2d2d8a45-8716-412c-a845-7308396b51df, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:22:07,895 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 2d2d8a45-8716-412c-a845-7308396b51df, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:07,902 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 2d2d8a45-8716-412c-a845-7308396b51df, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:15,807 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:22:15,901 - INFO - root.421771  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_15_compression`, volumes `value` (8000000, 1596216)
2025-11-19 11:22:15,902 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:22:15,902 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, c9103732-c901-4f8f-ab9e-c38f4fabd407, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:22:15,903 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, c9103732-c901-4f8f-ab9e-c38f4fabd407, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:15,904 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, c9103732-c901-4f8f-ab9e-c38f4fabd407, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:16,870 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:22:16,870 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:22:16,871 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, e12c550f-bf04-49fa-a69f-d84785052b32, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:22:16,872 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, e12c550f-bf04-49fa-a69f-d84785052b32, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:16,879 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, e12c550f-bf04-49fa-a69f-d84785052b32, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:22,143 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:22:32,174 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:22:32,175 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, 40bcb8e8-837a-4d7d-b797-7b0308f80451, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:22:32,175 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, 40bcb8e8-837a-4d7d-b797-7b0308f80451, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:32,177 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, 40bcb8e8-837a-4d7d-b797-7b0308f80451, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:35,093 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:22:45,114 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:22:45,115 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, ef695b81-3561-47ab-8673-01d59ada219b, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:22:45,116 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, ef695b81-3561-47ab-8673-01d59ada219b, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:45,117 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, ef695b81-3561-47ab-8673-01d59ada219b, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:46,281 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:22:56,286 - DEBUG - ydb.query.pool.421771  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:22:56,287 - DEBUG - ydb.connection.421771  - _prepare_call: RpcState(ExecuteQuery, f50e6962-601b-4be0-a231-80ffc06b5ab8, ghrun-alsqrntpxi.auto.internal:15506): creating call state
2025-11-19 11:22:56,287 - DEBUG - ydb.connection.421771  - _log_request: RpcState(ExecuteQuery, f50e6962-601b-4be0-a231-80ffc06b5ab8, ghrun-alsqrntpxi.auto.internal:15506): request = { session_id: "ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:56,289 - DEBUG - ydb.connection.421771  - _log_response: RpcState(ExecuteQuery, f50e6962-601b-4be0-a231-80ffc06b5ab8, ghrun-alsqrntpxi.auto.internal:15506): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:57,825 - DEBUG - ydb.query.pool.421771  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjExNWQyZS0xNWZkNTQ0OC0xZGVmYWZjNy1lNGEzMGJmYw==
2025-11-19 11:22:57,833 - INFO - root.421771  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_15_compression`, volumes `value` (8000000, 1870856)
2025-11-19 11:22:57,834 - INFO - root.421771  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_15_compression` 8092160 / 1870856: 4.325378329491954
2025-11-19 11:22:57,845 - INFO - ya.test.421771  - pytest_runtest_teardown: Test teardown
