2025-11-21 12:08:32,279 - INFO - ya.test.1724271  - pytest_runtest_setup: ####################################################################################################
2025-11-21 12:08:32,280 - INFO - ya.test.1724271  - pytest_runtest_setup: test_all_supported_compression[zstd_4_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 4]
2025-11-21 12:08:32,280 - INFO - ya.test.1724271  - pytest_runtest_setup: ####################################################################################################
2025-11-21 12:08:32,280 - INFO - ya.test.1724271  - pytest_runtest_setup: Test setup
2025-11-21 12:08:32,283 - DEBUG - ya.test.1724271  - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-21 12:08:32,284 - DEBUG - ya.test.1724271  - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-21 12:08:32,284 - DEBUG - ya.test.1724271  - execute: Executing '['/home/runner/.ya/build/build_root/vsfo/002687/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-21 12:08:32,299 - DEBUG - ya.test.1724271  - execute: Command pid: 1726194
2025-11-21 12:08:33,616 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) rc: 0
2025-11-21 12:08:33,616 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) elapsed time (sec): 1.3148555755615234
2025-11-21 12:08:33,617 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) idrss: 0
2025-11-21 12:08:33,617 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) inblock: 0
2025-11-21 12:08:33,617 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) isrss: 0
2025-11-21 12:08:33,618 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) ixrss: 0
2025-11-21 12:08:33,618 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) majflt: 0
2025-11-21 12:08:33,618 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) maxrss: 486432
2025-11-21 12:08:33,619 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) minflt: 45581
2025-11-21 12:08:33,619 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) msgrcv: 0
2025-11-21 12:08:33,619 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) msgsnd: 0
2025-11-21 12:08:33,620 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) nivcsw: 215
2025-11-21 12:08:33,620 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) nsignals: 0
2025-11-21 12:08:33,620 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) nswap: 0
2025-11-21 12:08:33,621 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) nvcsw: 14
2025-11-21 12:08:33,621 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) oublock: 8
2025-11-21 12:08:33,621 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) stime: 0.23999199999999998
2025-11-21 12:08:33,622 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) utime: 0.524844
2025-11-21 12:08:33,622 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) wtime: 1.33
2025-11-21 12:08:33,622 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) 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-ts5cnd6jre    Host information: 
        Linux ghrun-ts5cnd6jre 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-21 12:08:33,623 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726194) errors:

2025-11-21 12:08:33,624 - INFO - __tests__.base.1724271  - _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-ts5cnd6jre    Host information: 
        Linux ghrun-ts5cnd6jre 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-21 12:08:33,781 - DEBUG - ya.test.1724271  - get_binary: Binary was found by /home/runner/.ya/build/build_root/vsfo/002687/ydb/apps/ydbd/ydbd
2025-11-21 12:08:33,783 - DEBUG - library.python.filelock.1724271  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17461
2025-11-21 12:08:33,785 - DEBUG - ydb.tests.library.harness.kikimr_runner.1724271  - start: Working directory: /home/runner/.ya/build/build_root/vsfo/002687/r3tmp/kikimr_cluster_4z384cd7
2025-11-21 12:08:33,841 - DEBUG - ya.test.1724271  - get_binary: Binary was found by /home/runner/.ya/build/build_root/vsfo/002687/ydb/apps/ydbd/ydbd
2025-11-21 12:08:33,842 - DEBUG - library.python.filelock.1724271  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19315
2025-11-21 12:08:33,842 - DEBUG - library.python.filelock.1724271  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13074
2025-11-21 12:08:33,850 - DEBUG - library.python.filelock.1724271  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22358
2025-11-21 12:08:33,851 - DEBUG - library.python.filelock.1724271  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25316
2025-11-21 12:08:33,852 - INFO - ydb.tests.library.harness.kikimr_runner.1724271  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vsfo/002687/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/kikimr_configs"
2025-11-21 12:08:33,852 - INFO - ydb.tests.library.harness.kikimr_runner.1724271  - __make_run_command: Final command: /home/runner/.ya/build/build_root/vsfo/002687/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/vsfo/002687/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/node_1/logfile_m230q_ee.log --grpc-port=19315 --mon-port=13074 --ic-port=17461
2025-11-21 12:08:33,853 - DEBUG - ydb.tests.library.harness.kikimr_runner.1724271  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:19315/1, disk_size 68719476736
2025-11-21 12:08:33,853 - INFO - ydb.tests.library.harness.kikimr_runner.1724271  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/vsfo/002687/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/kikimr_configs"
2025-11-21 12:08:33,854 - INFO - ydb.tests.library.harness.kikimr_runner.1724271  - __make_run_command: Final command: /home/runner/.ya/build/build_root/vsfo/002687/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/vsfo/002687/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/node_1/logfile_m230q_ee.log --grpc-port=19315 --mon-port=13074 --ic-port=17461
2025-11-21 12:08:33,856 - DEBUG - ya.test.1724271  - execute: Executing '('/home/runner/.ya/build/build_root/vsfo/002687/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/vsfo/002687/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/vsfo/002687/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/node_1/logfile_m230q_ee.log', '--grpc-port=19315', '--mon-port=13074', '--ic-port=17461')' in '/home/runner/.ya/build/build_root/vsfo/002687/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/node_1' (no wait)
2025-11-21 12:08:33,864 - DEBUG - ya.test.1724271  - execute: Command pid: 1726520
2025-11-21 12:08:33,866 - INFO - ydb.tests.library.harness.kikimr_runner.1724271  - start: Started node localhost:19315/1
2025-11-21 12:08:33,874 - DEBUG - ya.test.1724271  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-21 12:08:33,875 - DEBUG - ya.test.1724271  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-21 12:08:33,875 - DEBUG - ya.test.1724271  - execute: Executing '['/home/runner/.ya/build/build_root/vsfo/002687/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-21 12:08:33,898 - DEBUG - ya.test.1724271  - execute: Command pid: 1726522
2025-11-21 12:08:35,532 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) rc: 0
2025-11-21 12:08:35,533 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) elapsed time (sec): 1.632680892944336
2025-11-21 12:08:35,534 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) idrss: 0
2025-11-21 12:08:35,534 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) inblock: 0
2025-11-21 12:08:35,534 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) isrss: 0
2025-11-21 12:08:35,534 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) ixrss: 0
2025-11-21 12:08:35,535 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) majflt: 0
2025-11-21 12:08:35,539 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) maxrss: 487452
2025-11-21 12:08:35,539 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) minflt: 45625
2025-11-21 12:08:35,540 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) msgrcv: 0
2025-11-21 12:08:35,540 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) msgsnd: 0
2025-11-21 12:08:35,540 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) nivcsw: 226
2025-11-21 12:08:35,541 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) nsignals: 0
2025-11-21 12:08:35,541 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) nswap: 0
2025-11-21 12:08:35,541 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) nvcsw: 14
2025-11-21 12:08:35,542 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) oublock: 8
2025-11-21 12:08:35,542 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) stime: 0.197489
2025-11-21 12:08:35,542 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) utime: 0.589681
2025-11-21 12:08:35,543 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) wtime: 1.656
2025-11-21 12:08:35,550 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) 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-ts5cnd6jre    Host information: 
        Linux ghrun-ts5cnd6jre 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-21 12:08:35,550 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1726522) errors:

2025-11-21 12:08:35,551 - INFO - ydb.tests.library.harness.kikimr_runner.1724271  - 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-ts5cnd6jre    Host information: 
        Linux ghrun-ts5cnd6jre 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-11-21 12:08:41,872 - DEBUG - ya.test.1724271  - get_binary: Binary was found by /home/runner/.ya/build/build_root/vsfo/002687/ydb/apps/ydbd/ydbd
2025-11-21 12:08:41,872 - DEBUG - ydb.tests.library.harness.kikimr_runner.1724271  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/vsfo/002687/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:19315', '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: 17461\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-21 12:08:41,874 - DEBUG - ya.test.1724271  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-21 12:08:41,875 - DEBUG - ya.test.1724271  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-21 12:08:41,875 - DEBUG - ya.test.1724271  - execute: Executing '['/home/runner/.ya/build/build_root/vsfo/002687/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:19315', '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: 17461\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-21 12:08:41,877 - DEBUG - ya.test.1724271  - execute: Command pid: 1727313
2025-11-21 12:08:43,414 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) rc: 0
2025-11-21 12:08:43,414 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) elapsed time (sec): 1.5355932712554932
2025-11-21 12:08:43,415 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) idrss: 0
2025-11-21 12:08:43,415 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) inblock: 0
2025-11-21 12:08:43,415 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) isrss: 0
2025-11-21 12:08:43,416 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) ixrss: 0
2025-11-21 12:08:43,416 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) majflt: 0
2025-11-21 12:08:43,416 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) maxrss: 585508
2025-11-21 12:08:43,416 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) minflt: 51953
2025-11-21 12:08:43,417 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) msgrcv: 0
2025-11-21 12:08:43,417 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) msgsnd: 0
2025-11-21 12:08:43,417 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) nivcsw: 332
2025-11-21 12:08:43,417 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) nsignals: 0
2025-11-21 12:08:43,418 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) nswap: 0
2025-11-21 12:08:43,418 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) nvcsw: 588
2025-11-21 12:08:43,418 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) oublock: 8
2025-11-21 12:08:43,418 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) stime: 0.317829
2025-11-21 12:08:43,419 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) utime: 0.584515
2025-11-21 12:08:43,420 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) wtime: 1.538
2025-11-21 12:08:43,420 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-21 12:08:43,420 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727313) errors:

2025-11-21 12:08:43,422 - DEBUG - ya.test.1724271  - get_binary: Binary was found by /home/runner/.ya/build/build_root/vsfo/002687/ydb/apps/ydbd/ydbd
2025-11-21 12:08:43,422 - DEBUG - ydb.tests.library.harness.kikimr_runner.1724271  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/vsfo/002687/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:19315', '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-21 12:08:43,424 - DEBUG - ya.test.1724271  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-21 12:08:43,425 - DEBUG - ya.test.1724271  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-21 12:08:43,425 - DEBUG - ya.test.1724271  - execute: Executing '['/home/runner/.ya/build/build_root/vsfo/002687/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:19315', '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-21 12:08:43,443 - DEBUG - ya.test.1724271  - execute: Command pid: 1727479
2025-11-21 12:08:44,722 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) rc: 0
2025-11-21 12:08:44,722 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) elapsed time (sec): 1.277400255203247
2025-11-21 12:08:44,723 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) idrss: 0
2025-11-21 12:08:44,723 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) inblock: 0
2025-11-21 12:08:44,723 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) isrss: 0
2025-11-21 12:08:44,723 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) ixrss: 0
2025-11-21 12:08:44,724 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) majflt: 0
2025-11-21 12:08:44,724 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) maxrss: 586508
2025-11-21 12:08:44,724 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) minflt: 52076
2025-11-21 12:08:44,724 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) msgrcv: 0
2025-11-21 12:08:44,725 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) msgsnd: 0
2025-11-21 12:08:44,725 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) nivcsw: 410
2025-11-21 12:08:44,725 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) nsignals: 0
2025-11-21 12:08:44,725 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) nswap: 0
2025-11-21 12:08:44,727 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) nvcsw: 504
2025-11-21 12:08:44,728 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) oublock: 8
2025-11-21 12:08:44,728 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) stime: 0.28995
2025-11-21 12:08:44,728 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) utime: 0.558373
2025-11-21 12:08:44,728 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) wtime: 1.295
2025-11-21 12:08:44,729 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-21 12:08:44,729 - DEBUG - ya.test.1724271  - _save_outputs: Command (pid 1727479) errors:

2025-11-21 12:08:44,730 - INFO - ydb.tests.library.harness.kikimr_runner.1724271  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-21 12:08:44,864 - INFO - ydb.tests.library.harness.kikimr_runner.1724271  - __run: Cluster started and initialized
2025-11-21 12:08:44,937 - DEBUG - ydb.connection.1724271  - 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-21 12:08:44,947 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1724271  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 12:08:44,948 - DEBUG - ydb.connection.1724271  - 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-21 12:08:45,030 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1724271  - context_resolve: Resolving endpoints for database /Root
2025-11-21 12:08:45,031 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ListEndpoints, 6a650b8c-d9b2-489d-b394-e558cb575a39, localhost:19315): creating call state
2025-11-21 12:08:45,032 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ListEndpoints, 6a650b8c-d9b2-489d-b394-e558cb575a39, localhost:19315): request = { database: "/Root" }
2025-11-21 12:08:45,045 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ListEndpoints, 6a650b8c-d9b2-489d-b394-e558cb575a39, localhost:19315): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ts5cnd6jre.auto.internal" port: 19315 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-21 12:08:45,047 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1724271  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ts5cnd6jre.auto.internal:19315, location 1, ssl: False>]>
2025-11-21 12:08:45,048 - DEBUG - ydb.connection.1724271  - 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-21 12:08:45,113 - DEBUG - ydb.connection.1724271  - close: Closing channel for endpoint localhost:19315
2025-11-21 12:08:45,115 - DEBUG - ydb.query.pool.1724271  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-21 12:08:45,116 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(CreateSession, 54279bcb-95bd-4ea7-bd0a-db0bc278a5d2, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:08:45,117 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(CreateSession, 54279bcb-95bd-4ea7-bd0a-db0bc278a5d2, ghrun-ts5cnd6jre.auto.internal:19315): request = {  }
2025-11-21 12:08:45,434 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(CreateSession, 54279bcb-95bd-4ea7-bd0a-db0bc278a5d2, ghrun-ts5cnd6jre.auto.internal:19315): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" node_id: 1 }
2025-11-21 12:08:45,435 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(AttachSession, b9627864-a88c-435b-a6cc-5727d4c53ca2, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:08:45,435 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(AttachSession, b9627864-a88c-435b-a6cc-5727d4c53ca2, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" }
2025-11-21 12:08:45,447 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(AttachSession, b9627864-a88c-435b-a6cc-5727d4c53ca2, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:08:45,475 - DEBUG - ydb.query.pool.1724271  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:08:45,476 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, f4825466-32c7-44ef-99cd-7f471de13482, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:08:45,477 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, f4825466-32c7-44ef-99cd-7f471de13482, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:08:45,479 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, f4825466-32c7-44ef-99cd-7f471de13482, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:08:49,844 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:08:49,845 - INFO - __tests__.alter_compression.1724271  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-21 12:08:49,845 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:08:49,846 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 2d6f686c-1705-446f-a8f6-8c7937965d2f, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:08:49,847 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 2d6f686c-1705-446f-a8f6-8c7937965d2f, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:08:49,848 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 2d6f686c-1705-446f-a8f6-8c7937965d2f, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:08:51,549 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:08:51,550 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:08:51,551 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, e71718a4-e4d8-44d3-a569-83b4be3a89d3, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:08:51,552 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, e71718a4-e4d8-44d3-a569-83b4be3a89d3, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:08:51,554 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, e71718a4-e4d8-44d3-a569-83b4be3a89d3, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:08:53,193 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:08:53,194 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:08:53,194 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 6def0cdc-2111-459b-a069-229d221c5b65, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:08:53,195 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 6def0cdc-2111-459b-a069-229d221c5b65, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:08:53,197 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 6def0cdc-2111-459b-a069-229d221c5b65, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:08:55,173 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:08:55,174 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:08:55,175 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 60ed3612-4b75-47e6-b586-d89bb8a38896, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:08:55,176 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 60ed3612-4b75-47e6-b586-d89bb8a38896, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:08:55,178 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 60ed3612-4b75-47e6-b586-d89bb8a38896, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:08:57,682 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:08:57,683 - INFO - __tests__.alter_compression.1724271  - 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-21 12:08:57,684 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:08:57,684 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, cf889952-7eab-41cb-b74d-39eaf8269d9b, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:08:57,685 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, cf889952-7eab-41cb-b74d-39eaf8269d9b, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:08:57,686 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, cf889952-7eab-41cb-b74d-39eaf8269d9b, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:08:59,071 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:08:59,072 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:08:59,073 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 3572500c-3d80-48e9-a021-343795ee2c0a, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:08:59,074 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 3572500c-3d80-48e9-a021-343795ee2c0a, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:08:59,075 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 3572500c-3d80-48e9-a021-343795ee2c0a, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:08:59,513 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:08:59,514 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:08:59,515 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, aad502fb-47c8-4281-9b2d-b42d5ea5266a, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:08:59,516 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, aad502fb-47c8-4281-9b2d-b42d5ea5266a, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:08:59,517 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, aad502fb-47c8-4281-9b2d-b42d5ea5266a, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:00,145 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:00,146 - INFO - __tests__.alter_compression.1724271  - 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-21 12:09:00,147 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:00,148 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, f31dacec-eaab-4d70-824b-b7293ce6847d, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:00,149 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, f31dacec-eaab-4d70-824b-b7293ce6847d, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:00,150 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, f31dacec-eaab-4d70-824b-b7293ce6847d, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:01,743 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:01,744 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:01,745 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 74c3f5c2-1aa5-446f-bba1-cb28f127fc46, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:01,746 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 74c3f5c2-1aa5-446f-bba1-cb28f127fc46, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:01,748 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 74c3f5c2-1aa5-446f-bba1-cb28f127fc46, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:02,153 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:02,154 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:02,155 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 83c12be7-1c73-4888-a8e2-3197bd1a6754, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:02,155 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 83c12be7-1c73-4888-a8e2-3197bd1a6754, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:02,156 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 83c12be7-1c73-4888-a8e2-3197bd1a6754, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:02,700 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:02,701 - INFO - __tests__.alter_compression.1724271  - 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-21 12:09:02,702 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:02,703 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, ab73bc13-f80b-440f-bfea-1854ccf9c1c9, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:02,704 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, ab73bc13-f80b-440f-bfea-1854ccf9c1c9, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:02,706 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, ab73bc13-f80b-440f-bfea-1854ccf9c1c9, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:04,070 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:04,070 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:04,071 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 2c823ca2-b4aa-4015-8788-45c9a216a7b0, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:04,072 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 2c823ca2-b4aa-4015-8788-45c9a216a7b0, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:04,073 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 2c823ca2-b4aa-4015-8788-45c9a216a7b0, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:04,669 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:04,670 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:04,671 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 1a0dbb81-93ff-434a-95b8-b6e11442d850, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:04,672 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 1a0dbb81-93ff-434a-95b8-b6e11442d850, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:04,682 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 1a0dbb81-93ff-434a-95b8-b6e11442d850, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:05,437 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:05,438 - INFO - __tests__.alter_compression.1724271  - 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-21 12:09:05,438 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:05,439 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 45f2c645-5b77-441e-85f8-dc1683613c69, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:05,440 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 45f2c645-5b77-441e-85f8-dc1683613c69, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:05,441 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 45f2c645-5b77-441e-85f8-dc1683613c69, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:07,203 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:07,204 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:07,205 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 279df6a8-7d0c-4698-a81d-fe34278749b8, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:07,206 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 279df6a8-7d0c-4698-a81d-fe34278749b8, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:07,208 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 279df6a8-7d0c-4698-a81d-fe34278749b8, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:07,799 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:07,800 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:07,801 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 9c9ae29e-b322-4af9-9db5-58f3a50900b1, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:07,802 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 9c9ae29e-b322-4af9-9db5-58f3a50900b1, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:07,807 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 9c9ae29e-b322-4af9-9db5-58f3a50900b1, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:08,677 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:08,678 - INFO - __tests__.alter_compression.1724271  - 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-21 12:09:08,678 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:08,679 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, d853a951-26df-4287-83ec-db5a9edf53db, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:08,680 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, d853a951-26df-4287-83ec-db5a9edf53db, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:08,688 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, d853a951-26df-4287-83ec-db5a9edf53db, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:10,107 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:10,108 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:10,109 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 5afc5e2f-b5e4-4321-9427-664bad35292a, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:10,110 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 5afc5e2f-b5e4-4321-9427-664bad35292a, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:10,112 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 5afc5e2f-b5e4-4321-9427-664bad35292a, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:10,677 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:10,678 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:10,678 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 16db5ce2-0f43-43d3-a64d-4e0d1d9d7333, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:10,679 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 16db5ce2-0f43-43d3-a64d-4e0d1d9d7333, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:10,696 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 16db5ce2-0f43-43d3-a64d-4e0d1d9d7333, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:11,629 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:11,630 - INFO - __tests__.alter_compression.1724271  - 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-21 12:09:11,630 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:11,631 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 8ad34c64-ce20-4829-98da-accb5c8c8f61, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:11,632 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 8ad34c64-ce20-4829-98da-accb5c8c8f61, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:11,633 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 8ad34c64-ce20-4829-98da-accb5c8c8f61, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:12,812 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:12,813 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:12,814 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 1a3b2b5c-dc57-444f-9fca-69118bfede66, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:12,815 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 1a3b2b5c-dc57-444f-9fca-69118bfede66, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:12,816 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 1a3b2b5c-dc57-444f-9fca-69118bfede66, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:13,186 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:13,187 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:13,188 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 3d09cb7f-180d-4a48-8720-ae527dceccd6, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:13,189 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 3d09cb7f-180d-4a48-8720-ae527dceccd6, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:13,190 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 3d09cb7f-180d-4a48-8720-ae527dceccd6, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:13,813 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:13,813 - INFO - __tests__.alter_compression.1724271  - 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-21 12:09:13,814 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:13,814 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, fffaaede-4bfd-4784-9c2c-5bd0c70526bc, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:13,815 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, fffaaede-4bfd-4784-9c2c-5bd0c70526bc, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:13,817 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, fffaaede-4bfd-4784-9c2c-5bd0c70526bc, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:15,203 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:15,204 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:15,205 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 9e5a2f52-29a8-4cf7-8bbb-401ee1e5540c, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:15,206 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 9e5a2f52-29a8-4cf7-8bbb-401ee1e5540c, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:15,207 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 9e5a2f52-29a8-4cf7-8bbb-401ee1e5540c, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:15,567 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:15,568 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:15,569 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, ec0540a4-21d1-440b-8336-fb4112e8ef7e, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:15,570 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, ec0540a4-21d1-440b-8336-fb4112e8ef7e, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:15,572 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, ec0540a4-21d1-440b-8336-fb4112e8ef7e, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:16,420 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:16,421 - INFO - __tests__.alter_compression.1724271  - 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-21 12:09:16,421 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:16,422 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 85feba80-ea5b-4e13-8f5c-f6f300bdbc55, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:16,423 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 85feba80-ea5b-4e13-8f5c-f6f300bdbc55, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:16,429 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 85feba80-ea5b-4e13-8f5c-f6f300bdbc55, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:18,024 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:18,025 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:18,025 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, bbf3d974-e2a2-4aca-8a12-de7a5eb1c64c, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:18,026 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, bbf3d974-e2a2-4aca-8a12-de7a5eb1c64c, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:18,028 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, bbf3d974-e2a2-4aca-8a12-de7a5eb1c64c, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:18,562 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:18,563 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:18,564 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 9f680c22-19ee-4208-9942-4aa1c06d4383, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:18,565 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 9f680c22-19ee-4208-9942-4aa1c06d4383, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:18,566 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 9f680c22-19ee-4208-9942-4aa1c06d4383, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:19,736 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:19,737 - INFO - __tests__.alter_compression.1724271  - 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-21 12:09:19,737 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:19,738 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 06c8bce8-f17f-447b-8209-eb095220d551, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:19,739 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 06c8bce8-f17f-447b-8209-eb095220d551, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:19,741 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 06c8bce8-f17f-447b-8209-eb095220d551, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:21,383 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:21,384 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:21,385 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 52c24676-cbb8-4a59-b332-8909705367bf, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:21,386 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 52c24676-cbb8-4a59-b332-8909705367bf, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:21,392 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 52c24676-cbb8-4a59-b332-8909705367bf, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:21,833 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:21,834 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:21,835 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, d49e890a-192a-4f30-afcd-b7c590088abc, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:21,836 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, d49e890a-192a-4f30-afcd-b7c590088abc, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:21,837 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, d49e890a-192a-4f30-afcd-b7c590088abc, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:22,866 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:22,867 - INFO - __tests__.alter_compression.1724271  - 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-21 12:09:22,867 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:22,868 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 1c10fc24-2a0c-47ab-b677-34f883c25961, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:22,869 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 1c10fc24-2a0c-47ab-b677-34f883c25961, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:22,871 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 1c10fc24-2a0c-47ab-b677-34f883c25961, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:24,406 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:24,408 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:24,409 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, f795bb83-ca2e-48eb-95d8-001f9fb708ab, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:24,410 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, f795bb83-ca2e-48eb-95d8-001f9fb708ab, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:24,411 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, f795bb83-ca2e-48eb-95d8-001f9fb708ab, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:24,820 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:24,821 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:24,821 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, d3f38fd5-e226-4326-b697-7b92c98fff3c, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:24,822 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, d3f38fd5-e226-4326-b697-7b92c98fff3c, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:24,824 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, d3f38fd5-e226-4326-b697-7b92c98fff3c, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:25,247 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:25,247 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:25,248 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 2f0d375a-00c9-4760-9f29-42886844913d, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:25,249 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 2f0d375a-00c9-4760-9f29-42886844913d, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:25,250 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 2f0d375a-00c9-4760-9f29-42886844913d, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:30,669 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:40,731 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:40,732 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, c4e695bc-caee-4c15-8c72-381198e3d3d0, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:40,733 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, c4e695bc-caee-4c15-8c72-381198e3d3d0, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:40,734 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, c4e695bc-caee-4c15-8c72-381198e3d3d0, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:45,659 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:45,740 - INFO - root.1724271  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-21 12:09:45,741 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:09:45,742 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, fa6dc6bf-454b-41c3-9e75-ec29c12aa4a5, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:09:45,742 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, fa6dc6bf-454b-41c3-9e75-ec29c12aa4a5, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:09:45,748 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, fa6dc6bf-454b-41c3-9e75-ec29c12aa4a5, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:09:50,922 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:00,970 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:00,971 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 6c5250a6-95bb-4290-8347-f41324ecd8e7, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:00,972 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 6c5250a6-95bb-4290-8347-f41324ecd8e7, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:10:00,973 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 6c5250a6-95bb-4290-8347-f41324ecd8e7, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:02,084 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:12,091 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:12,092 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, fe99097b-1d73-49a1-bd6f-09e9baab3229, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:12,093 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, fe99097b-1d73-49a1-bd6f-09e9baab3229, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:10:12,095 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, fe99097b-1d73-49a1-bd6f-09e9baab3229, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:13,544 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:13,548 - INFO - root.1724271  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-11-21 12:10:13,548 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:13,549 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, dc1b0f93-7711-4d91-886d-678d38dcb689, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:13,550 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, dc1b0f93-7711-4d91-886d-678d38dcb689, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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-21 12:10:13,552 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, dc1b0f93-7711-4d91-886d-678d38dcb689, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:14,188 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:14,192 - INFO - ya.test.1724271  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_4_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 4])
2025-11-21 12:10:14,193 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:14,194 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 986adceb-e623-4b9d-9876-1752a833b4ca, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:14,195 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 986adceb-e623-4b9d-9876-1752a833b4ca, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_4_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 4)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:14,197 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 986adceb-e623-4b9d-9876-1752a833b4ca, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:18,373 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:18,373 - INFO - __tests__.alter_compression.1724271  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_4_compression created
2025-11-21 12:10:18,374 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:18,375 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 75d36b53-38d6-45f9-adb7-b2b5075b3e18, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:18,376 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 75d36b53-38d6-45f9-adb7-b2b5075b3e18, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_4_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:18,377 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 75d36b53-38d6-45f9-adb7-b2b5075b3e18, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:19,348 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:19,349 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:19,350 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, f528eedf-45d4-4c65-98cb-fd271f135c4c, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:19,351 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, f528eedf-45d4-4c65-98cb-fd271f135c4c, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:19,354 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, f528eedf-45d4-4c65-98cb-fd271f135c4c, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:20,055 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1724271  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 12:10:20,056 - DEBUG - ydb.connection.1724271  - 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-21 12:10:20,120 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1724271  - context_resolve: Resolving endpoints for database /Root
2025-11-21 12:10:20,121 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ListEndpoints, 69547eb7-dc69-449c-84e8-180f963c579a, localhost:19315): creating call state
2025-11-21 12:10:20,121 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ListEndpoints, 69547eb7-dc69-449c-84e8-180f963c579a, localhost:19315): request = { database: "/Root" }
2025-11-21 12:10:20,133 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ListEndpoints, 69547eb7-dc69-449c-84e8-180f963c579a, localhost:19315): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ts5cnd6jre.auto.internal" port: 19315 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-21 12:10:20,134 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1724271  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ts5cnd6jre.auto.internal:19315, location 1, ssl: False>]>
2025-11-21 12:10:20,134 - DEBUG - ydb.connection.1724271  - close: Closing channel for endpoint localhost:19315
2025-11-21 12:10:21,264 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:21,265 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:21,266 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, d5870ec7-e5d2-4821-9301-03c2225f17cc, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:21,267 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, d5870ec7-e5d2-4821-9301-03c2225f17cc, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:21,268 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, d5870ec7-e5d2-4821-9301-03c2225f17cc, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:22,359 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:22,360 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:22,361 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 71e40cbc-0fa9-4457-a3a1-b64aa8f56355, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:22,362 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 71e40cbc-0fa9-4457-a3a1-b64aa8f56355, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:22,364 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 71e40cbc-0fa9-4457-a3a1-b64aa8f56355, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:24,424 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:24,425 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318960, 'BlobCount': 128}}
2025-11-21 12:10:24,425 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:24,426 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 80e45b1a-d55b-493f-84d4-ce9736875e57, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:24,427 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 80e45b1a-d55b-493f-84d4-ce9736875e57, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:24,430 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 80e45b1a-d55b-493f-84d4-ce9736875e57, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:25,964 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:25,965 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:25,966 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 9d707ad2-b043-4747-aac8-98fd2d3b4d33, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:25,967 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 9d707ad2-b043-4747-aac8-98fd2d3b4d33, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:25,968 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 9d707ad2-b043-4747-aac8-98fd2d3b4d33, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:26,389 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:26,390 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:26,390 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, f39170f3-850f-44ee-b7fa-228892e48a03, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:26,391 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, f39170f3-850f-44ee-b7fa-228892e48a03, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:26,393 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, f39170f3-850f-44ee-b7fa-228892e48a03, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:26,976 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:26,977 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638256, 'BlobCount': 256}}
2025-11-21 12:10:26,977 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:26,978 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 9a1d2a04-a847-4f5a-b324-70a2613ea609, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:26,979 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 9a1d2a04-a847-4f5a-b324-70a2613ea609, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:26,981 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 9a1d2a04-a847-4f5a-b324-70a2613ea609, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:28,705 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:28,706 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:28,707 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 96328e3e-9fd6-4cd3-8868-edd0f15fd4b9, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:28,708 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 96328e3e-9fd6-4cd3-8868-edd0f15fd4b9, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:28,709 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 96328e3e-9fd6-4cd3-8868-edd0f15fd4b9, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:29,036 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:29,037 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:29,037 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, cb999290-09ea-4c91-af72-7618fbcfddda, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:29,038 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, cb999290-09ea-4c91-af72-7618fbcfddda, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:29,044 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, cb999290-09ea-4c91-af72-7618fbcfddda, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:29,616 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:29,617 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957296, 'BlobCount': 384}}
2025-11-21 12:10:29,617 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:29,618 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, a3ba153f-6f45-4497-8002-8a257061f16c, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:29,619 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, a3ba153f-6f45-4497-8002-8a257061f16c, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:29,621 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, a3ba153f-6f45-4497-8002-8a257061f16c, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:30,913 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:30,914 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:30,915 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 8dd0b8db-30a5-4205-b8cd-1031b6cd9be1, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:30,916 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 8dd0b8db-30a5-4205-b8cd-1031b6cd9be1, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:30,917 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 8dd0b8db-30a5-4205-b8cd-1031b6cd9be1, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:31,324 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:31,325 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:31,325 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 2a14865c-38e7-4c8d-bcbc-4f38a3551174, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:31,326 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 2a14865c-38e7-4c8d-bcbc-4f38a3551174, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:31,328 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 2a14865c-38e7-4c8d-bcbc-4f38a3551174, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:32,017 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:32,018 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276656, 'BlobCount': 512}}
2025-11-21 12:10:32,018 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:32,019 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, e070ce62-b836-4bf5-95c5-4041dad583bb, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:32,020 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, e070ce62-b836-4bf5-95c5-4041dad583bb, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:32,021 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, e070ce62-b836-4bf5-95c5-4041dad583bb, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:33,529 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:33,529 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:33,530 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 1c828414-963d-4365-a44c-474afa39934c, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:33,531 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 1c828414-963d-4365-a44c-474afa39934c, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:33,532 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 1c828414-963d-4365-a44c-474afa39934c, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:34,045 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:34,045 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:34,046 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 90703160-f1cf-4ce9-8366-b3e65ffa2394, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:34,048 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 90703160-f1cf-4ce9-8366-b3e65ffa2394, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:34,049 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 90703160-f1cf-4ce9-8366-b3e65ffa2394, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:34,631 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:34,631 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595616, 'BlobCount': 640}}
2025-11-21 12:10:34,632 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:34,633 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 7694be01-a8d8-4be2-b8d8-e3bb0b4086c6, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:34,633 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 7694be01-a8d8-4be2-b8d8-e3bb0b4086c6, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:34,637 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 7694be01-a8d8-4be2-b8d8-e3bb0b4086c6, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:35,876 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:35,877 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:35,878 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 26405b91-726a-4f1c-af09-5f1394080c07, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:35,879 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 26405b91-726a-4f1c-af09-5f1394080c07, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:35,880 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 26405b91-726a-4f1c-af09-5f1394080c07, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:36,320 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:36,321 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:36,321 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, c6a4826f-6afa-4d07-a16c-b7538c9c4865, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:36,322 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, c6a4826f-6afa-4d07-a16c-b7538c9c4865, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:36,323 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, c6a4826f-6afa-4d07-a16c-b7538c9c4865, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:37,093 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:37,094 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914800, 'BlobCount': 768}}
2025-11-21 12:10:37,094 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:37,095 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, a763709b-f8aa-41b6-938d-47ad2127ae1d, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:37,096 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, a763709b-f8aa-41b6-938d-47ad2127ae1d, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:37,104 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, a763709b-f8aa-41b6-938d-47ad2127ae1d, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:38,592 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:38,593 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:38,594 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, c433c843-7623-4c21-8021-24cea6c6b6c9, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:38,595 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, c433c843-7623-4c21-8021-24cea6c6b6c9, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:38,596 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, c433c843-7623-4c21-8021-24cea6c6b6c9, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:38,985 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:38,986 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:38,987 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, a7b8feff-2893-4142-b976-3a892ed0157f, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:38,988 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, a7b8feff-2893-4142-b976-3a892ed0157f, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:38,989 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, a7b8feff-2893-4142-b976-3a892ed0157f, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:39,633 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:39,633 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233936, 'BlobCount': 896}}
2025-11-21 12:10:39,634 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:39,634 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, a226f3c8-ecaf-47d0-855b-01205e35a701, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:39,636 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, a226f3c8-ecaf-47d0-855b-01205e35a701, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:39,637 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, a226f3c8-ecaf-47d0-855b-01205e35a701, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:41,347 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:41,348 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:41,349 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, da93b7f2-f541-47ee-9e21-917b50b750a0, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:41,350 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, da93b7f2-f541-47ee-9e21-917b50b750a0, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:41,356 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, da93b7f2-f541-47ee-9e21-917b50b750a0, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:42,055 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:42,056 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:42,056 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, a639c859-f9a6-41f7-bfd2-cae080571eb6, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:42,057 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, a639c859-f9a6-41f7-bfd2-cae080571eb6, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:42,060 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, a639c859-f9a6-41f7-bfd2-cae080571eb6, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:42,993 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:42,993 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553424, 'BlobCount': 1024}}
2025-11-21 12:10:42,994 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:42,995 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 8caad580-80f7-45a4-875f-b89022ef5b5a, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:42,996 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 8caad580-80f7-45a4-875f-b89022ef5b5a, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:42,997 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 8caad580-80f7-45a4-875f-b89022ef5b5a, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:44,432 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:44,432 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:44,433 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, dd789a8e-ee2c-4c0e-ac5a-09084c425833, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:44,434 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, dd789a8e-ee2c-4c0e-ac5a-09084c425833, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:44,435 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, dd789a8e-ee2c-4c0e-ac5a-09084c425833, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:44,805 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:44,805 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:44,806 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 82cfe1d6-bdcc-4436-80d6-be973fb2c39c, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:44,807 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 82cfe1d6-bdcc-4436-80d6-be973fb2c39c, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:44,809 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 82cfe1d6-bdcc-4436-80d6-be973fb2c39c, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:45,559 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:45,560 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872432, 'BlobCount': 1152}}
2025-11-21 12:10:45,560 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:45,561 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, f756bc8c-98c3-41dc-bbdb-467475949e3e, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:45,562 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, f756bc8c-98c3-41dc-bbdb-467475949e3e, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:45,568 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, f756bc8c-98c3-41dc-bbdb-467475949e3e, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:47,123 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:47,124 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:47,125 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, a9f05ddb-20d5-47e8-aea2-44acd627bade, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:47,126 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, a9f05ddb-20d5-47e8-aea2-44acd627bade, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:47,128 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, a9f05ddb-20d5-47e8-aea2-44acd627bade, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:47,472 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:47,473 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:47,474 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, ab2b33c3-6680-4ae6-bc32-2dd6989ce50d, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:47,475 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, ab2b33c3-6680-4ae6-bc32-2dd6989ce50d, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:47,476 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, ab2b33c3-6680-4ae6-bc32-2dd6989ce50d, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:49,108 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:49,109 - INFO - __tests__.alter_compression.1724271  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191936, 'BlobCount': 1280}}
2025-11-21 12:10:49,109 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:49,110 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 6859a04a-ebd9-4772-ba37-d74b63f8c43f, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:49,111 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 6859a04a-ebd9-4772-ba37-d74b63f8c43f, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_4_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:49,112 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 6859a04a-ebd9-4772-ba37-d74b63f8c43f, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:51,415 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:51,416 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:51,417 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 747931aa-ac3d-4c0f-8689-28777ad39303, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:51,417 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 747931aa-ac3d-4c0f-8689-28777ad39303, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:51,419 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 747931aa-ac3d-4c0f-8689-28777ad39303, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:51,756 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:51,757 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:51,757 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 2c0b0342-0cd1-42cf-b8a9-0461f783590f, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:51,758 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 2c0b0342-0cd1-42cf-b8a9-0461f783590f, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:51,760 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 2c0b0342-0cd1-42cf-b8a9-0461f783590f, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:52,300 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:52,301 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:10:52,301 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 3ad73670-7ea9-42c2-b27c-4b7c1daf8761, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:10:52,302 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 3ad73670-7ea9-42c2-b27c-4b7c1daf8761, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:10:52,304 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 3ad73670-7ea9-42c2-b27c-4b7c1daf8761, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:10:57,674 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:07,740 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:07,740 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 5a465fa1-50c4-4a35-bdbe-051bd4d869a9, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:11:07,741 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 5a465fa1-50c4-4a35-bdbe-051bd4d869a9, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:11:07,743 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 5a465fa1-50c4-4a35-bdbe-051bd4d869a9, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:11:13,429 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:13,483 - INFO - root.1724271  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_4_compression`, volumes `value` (8000000, 1595968)
2025-11-21 12:11:13,484 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:13,485 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, d8eacd6e-2ec6-4119-b63c-d5d0ec7187ec, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:11:13,486 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, d8eacd6e-2ec6-4119-b63c-d5d0ec7187ec, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:11:13,488 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, d8eacd6e-2ec6-4119-b63c-d5d0ec7187ec, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:11:14,181 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:14,182 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:14,183 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 9b963755-f559-40f4-8aba-c9e1f9b2b756, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:11:14,184 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 9b963755-f559-40f4-8aba-c9e1f9b2b756, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:11:14,199 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 9b963755-f559-40f4-8aba-c9e1f9b2b756, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:11:20,250 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:30,319 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:30,320 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, b76b2c04-8492-4d88-b0d8-bec254ce7d3b, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:11:30,321 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, b76b2c04-8492-4d88-b0d8-bec254ce7d3b, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:11:30,323 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, b76b2c04-8492-4d88-b0d8-bec254ce7d3b, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:11:32,028 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:42,054 - DEBUG - ydb.query.pool.1724271  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:42,055 - DEBUG - ydb.connection.1724271  - _prepare_call: RpcState(ExecuteQuery, 4ee4b47a-ca51-4e30-96fa-9a4c4f48e72b, ghrun-ts5cnd6jre.auto.internal:19315): creating call state
2025-11-21 12:11:42,056 - DEBUG - ydb.connection.1724271  - _log_request: RpcState(ExecuteQuery, 4ee4b47a-ca51-4e30-96fa-9a4c4f48e72b, ghrun-ts5cnd6jre.auto.internal:19315): request = { session_id: "ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 12:11:42,058 - DEBUG - ydb.connection.1724271  - _log_response: RpcState(ExecuteQuery, 4ee4b47a-ca51-4e30-96fa-9a4c4f48e72b, ghrun-ts5cnd6jre.auto.internal:19315): response = { <_MultiThreadedRendezvous object> }
2025-11-21 12:11:43,087 - DEBUG - ydb.query.pool.1724271  - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzc5NWIyZDQtMzY0ZDAyMzAtNmEyMjY3YWMtNzUyZGNjMg==
2025-11-21 12:11:43,096 - INFO - root.1724271  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_4_compression`, volumes `value` (8000000, 1466456)
2025-11-21 12:11:43,097 - INFO - root.1724271  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_4_compression` 8092160 / 1466456: 5.518174428690666
2025-11-21 12:11:43,099 - INFO - ya.test.1724271  - pytest_runtest_teardown: Test teardown
