2025-11-21 17:36:49,074 - INFO - ya.test.672590  - pytest_runtest_setup: ####################################################################################################
2025-11-21 17:36:49,075 - INFO - ya.test.672590  - pytest_runtest_setup: test_all_supported_compression[zstd_2_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 2]
2025-11-21 17:36:49,075 - INFO - ya.test.672590  - pytest_runtest_setup: ####################################################################################################
2025-11-21 17:36:49,075 - INFO - ya.test.672590  - pytest_runtest_setup: Test setup
2025-11-21 17:36:49,078 - DEBUG - ya.test.672590  - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-21 17:36:49,079 - DEBUG - ya.test.672590  - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-21 17:36:49,080 - DEBUG - ya.test.672590  - execute: Executing '['/home/runner/.ya/build/build_root/ue5z/0055ce/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-21 17:36:49,086 - DEBUG - ya.test.672590  - execute: Command pid: 673775
2025-11-21 17:36:50,382 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) rc: 0
2025-11-21 17:36:50,383 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) elapsed time (sec): 1.29477858543396
2025-11-21 17:36:50,383 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) idrss: 0
2025-11-21 17:36:50,383 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) inblock: 0
2025-11-21 17:36:50,384 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) isrss: 0
2025-11-21 17:36:50,384 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) ixrss: 0
2025-11-21 17:36:50,384 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) majflt: 0
2025-11-21 17:36:50,385 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) maxrss: 484124
2025-11-21 17:36:50,385 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) minflt: 45600
2025-11-21 17:36:50,385 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) msgrcv: 0
2025-11-21 17:36:50,387 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) msgsnd: 0
2025-11-21 17:36:50,388 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) nivcsw: 204
2025-11-21 17:36:50,391 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) nsignals: 0
2025-11-21 17:36:50,391 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) nswap: 0
2025-11-21 17:36:50,391 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) nvcsw: 14
2025-11-21 17:36:50,392 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) oublock: 8
2025-11-21 17:36:50,392 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) stime: 0.254385
2025-11-21 17:36:50,392 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) utime: 0.515765
2025-11-21 17:36:50,393 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) wtime: 1.302
2025-11-21 17:36:50,393 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) 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-zme4sewyte    Host information: 
        Linux ghrun-zme4sewyte 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 17:36:50,394 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673775) errors:

2025-11-21 17:36:50,394 - INFO - __tests__.base.672590  - _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-zme4sewyte    Host information: 
        Linux ghrun-zme4sewyte 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 17:36:50,566 - DEBUG - ya.test.672590  - get_binary: Binary was found by /home/runner/.ya/build/build_root/ue5z/0055ce/ydb/apps/ydbd/ydbd
2025-11-21 17:36:50,567 - DEBUG - library.python.filelock.672590  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10671
2025-11-21 17:36:50,569 - DEBUG - ydb.tests.library.harness.kikimr_runner.672590  - start: Working directory: /home/runner/.ya/build/build_root/ue5z/0055ce/r3tmp/kikimr_cluster_5pr5h7mt
2025-11-21 17:36:50,614 - DEBUG - ya.test.672590  - get_binary: Binary was found by /home/runner/.ya/build/build_root/ue5z/0055ce/ydb/apps/ydbd/ydbd
2025-11-21 17:36:50,615 - DEBUG - library.python.filelock.672590  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30197
2025-11-21 17:36:50,615 - DEBUG - library.python.filelock.672590  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15636
2025-11-21 17:36:50,616 - DEBUG - library.python.filelock.672590  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28236
2025-11-21 17:36:50,616 - DEBUG - library.python.filelock.672590  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3371
2025-11-21 17:36:50,617 - INFO - ydb.tests.library.harness.kikimr_runner.672590  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ue5z/0055ce/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs"
2025-11-21 17:36:50,618 - INFO - ydb.tests.library.harness.kikimr_runner.672590  - __make_run_command: Final command: /home/runner/.ya/build/build_root/ue5z/0055ce/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/ue5z/0055ce/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_iih_evhs.log --grpc-port=30197 --mon-port=15636 --ic-port=10671
2025-11-21 17:36:50,619 - DEBUG - ydb.tests.library.harness.kikimr_runner.672590  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:30197/1, disk_size 68719476736
2025-11-21 17:36:50,619 - INFO - ydb.tests.library.harness.kikimr_runner.672590  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ue5z/0055ce/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs"
2025-11-21 17:36:50,619 - INFO - ydb.tests.library.harness.kikimr_runner.672590  - __make_run_command: Final command: /home/runner/.ya/build/build_root/ue5z/0055ce/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/ue5z/0055ce/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_iih_evhs.log --grpc-port=30197 --mon-port=15636 --ic-port=10671
2025-11-21 17:36:50,621 - DEBUG - ya.test.672590  - execute: Executing '('/home/runner/.ya/build/build_root/ue5z/0055ce/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/ue5z/0055ce/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/ue5z/0055ce/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_iih_evhs.log', '--grpc-port=30197', '--mon-port=15636', '--ic-port=10671')' in '/home/runner/.ya/build/build_root/ue5z/0055ce/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1' (no wait)
2025-11-21 17:36:50,633 - DEBUG - ya.test.672590  - execute: Command pid: 673855
2025-11-21 17:36:50,634 - INFO - ydb.tests.library.harness.kikimr_runner.672590  - start: Started node localhost:30197/1
2025-11-21 17:36:50,636 - DEBUG - ya.test.672590  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-21 17:36:50,637 - DEBUG - ya.test.672590  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-21 17:36:50,637 - DEBUG - ya.test.672590  - execute: Executing '['/home/runner/.ya/build/build_root/ue5z/0055ce/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-21 17:36:50,646 - DEBUG - ya.test.672590  - execute: Command pid: 673856
2025-11-21 17:36:51,859 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) rc: 0
2025-11-21 17:36:51,860 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) elapsed time (sec): 1.2124261856079102
2025-11-21 17:36:51,860 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) idrss: 0
2025-11-21 17:36:51,861 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) inblock: 0
2025-11-21 17:36:51,861 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) isrss: 0
2025-11-21 17:36:51,861 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) ixrss: 0
2025-11-21 17:36:51,862 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) majflt: 0
2025-11-21 17:36:51,862 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) maxrss: 485576
2025-11-21 17:36:51,862 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) minflt: 45656
2025-11-21 17:36:51,863 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) msgrcv: 0
2025-11-21 17:36:51,863 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) msgsnd: 0
2025-11-21 17:36:51,863 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) nivcsw: 208
2025-11-21 17:36:51,864 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) nsignals: 0
2025-11-21 17:36:51,864 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) nswap: 0
2025-11-21 17:36:51,864 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) nvcsw: 14
2025-11-21 17:36:51,865 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) oublock: 8
2025-11-21 17:36:51,865 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) stime: 0.21864599999999998
2025-11-21 17:36:51,865 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) utime: 0.555191
2025-11-21 17:36:51,866 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) wtime: 1.221
2025-11-21 17:36:51,867 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) 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-zme4sewyte    Host information: 
        Linux ghrun-zme4sewyte 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 17:36:51,867 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 673856) errors:

2025-11-21 17:36:51,867 - INFO - ydb.tests.library.harness.kikimr_runner.672590  - 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-zme4sewyte    Host information: 
        Linux ghrun-zme4sewyte 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-11-21 17:36:57,593 - DEBUG - ya.test.672590  - get_binary: Binary was found by /home/runner/.ya/build/build_root/ue5z/0055ce/ydb/apps/ydbd/ydbd
2025-11-21 17:36:57,594 - DEBUG - ydb.tests.library.harness.kikimr_runner.672590  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/ue5z/0055ce/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:30197', '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: 10671\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-21 17:36:57,595 - DEBUG - ya.test.672590  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-21 17:36:57,596 - DEBUG - ya.test.672590  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-21 17:36:57,597 - DEBUG - ya.test.672590  - execute: Executing '['/home/runner/.ya/build/build_root/ue5z/0055ce/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:30197', '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: 10671\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-21 17:36:57,602 - DEBUG - ya.test.672590  - execute: Command pid: 675114
2025-11-21 17:36:59,362 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) rc: 0
2025-11-21 17:36:59,363 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) elapsed time (sec): 1.758863925933838
2025-11-21 17:36:59,363 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) idrss: 0
2025-11-21 17:36:59,363 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) inblock: 0
2025-11-21 17:36:59,364 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) isrss: 0
2025-11-21 17:36:59,364 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) ixrss: 0
2025-11-21 17:36:59,364 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) majflt: 0
2025-11-21 17:36:59,364 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) maxrss: 597460
2025-11-21 17:36:59,365 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) minflt: 52107
2025-11-21 17:36:59,365 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) msgrcv: 0
2025-11-21 17:36:59,365 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) msgsnd: 0
2025-11-21 17:36:59,366 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) nivcsw: 232
2025-11-21 17:36:59,366 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) nsignals: 0
2025-11-21 17:36:59,366 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) nswap: 0
2025-11-21 17:36:59,366 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) nvcsw: 505
2025-11-21 17:36:59,367 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) oublock: 8
2025-11-21 17:36:59,367 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) stime: 0.30764199999999997
2025-11-21 17:36:59,367 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) utime: 0.594003
2025-11-21 17:36:59,368 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) wtime: 1.765
2025-11-21 17:36:59,368 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-21 17:36:59,368 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675114) errors:

2025-11-21 17:36:59,370 - DEBUG - ya.test.672590  - get_binary: Binary was found by /home/runner/.ya/build/build_root/ue5z/0055ce/ydb/apps/ydbd/ydbd
2025-11-21 17:36:59,371 - DEBUG - ydb.tests.library.harness.kikimr_runner.672590  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/ue5z/0055ce/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:30197', '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 17:36:59,372 - DEBUG - ya.test.672590  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-21 17:36:59,373 - DEBUG - ya.test.672590  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-21 17:36:59,373 - DEBUG - ya.test.672590  - execute: Executing '['/home/runner/.ya/build/build_root/ue5z/0055ce/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:30197', '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 17:36:59,382 - DEBUG - ya.test.672590  - execute: Command pid: 675609
2025-11-21 17:37:00,589 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) rc: 0
2025-11-21 17:37:00,590 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) elapsed time (sec): 1.2061374187469482
2025-11-21 17:37:00,591 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) idrss: 0
2025-11-21 17:37:00,591 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) inblock: 0
2025-11-21 17:37:00,591 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) isrss: 0
2025-11-21 17:37:00,592 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) ixrss: 0
2025-11-21 17:37:00,592 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) majflt: 0
2025-11-21 17:37:00,592 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) maxrss: 598144
2025-11-21 17:37:00,593 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) minflt: 51943
2025-11-21 17:37:00,593 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) msgrcv: 0
2025-11-21 17:37:00,593 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) msgsnd: 0
2025-11-21 17:37:00,593 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) nivcsw: 206
2025-11-21 17:37:00,595 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) nsignals: 0
2025-11-21 17:37:00,595 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) nswap: 0
2025-11-21 17:37:00,596 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) nvcsw: 549
2025-11-21 17:37:00,596 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) oublock: 8
2025-11-21 17:37:00,596 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) stime: 0.300236
2025-11-21 17:37:00,596 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) utime: 0.567635
2025-11-21 17:37:00,597 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) wtime: 1.215
2025-11-21 17:37:00,597 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-21 17:37:00,599 - DEBUG - ya.test.672590  - _save_outputs: Command (pid 675609) errors:

2025-11-21 17:37:00,600 - INFO - ydb.tests.library.harness.kikimr_runner.672590  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-21 17:37:00,695 - INFO - ydb.tests.library.harness.kikimr_runner.672590  - __run: Cluster started and initialized
2025-11-21 17:37:00,736 - DEBUG - ydb.connection.672590  - 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 17:37:00,742 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.672590  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 17:37:00,742 - DEBUG - ydb.connection.672590  - 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 17:37:00,799 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.672590  - context_resolve: Resolving endpoints for database /Root
2025-11-21 17:37:00,800 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ListEndpoints, 833eac2c-6354-4fd2-960f-662765496baf, localhost:30197): creating call state
2025-11-21 17:37:00,801 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ListEndpoints, 833eac2c-6354-4fd2-960f-662765496baf, localhost:30197): request = { database: "/Root" }
2025-11-21 17:37:00,811 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ListEndpoints, 833eac2c-6354-4fd2-960f-662765496baf, localhost:30197): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-zme4sewyte.auto.internal" port: 30197 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 17:37:00,812 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.672590  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-zme4sewyte.auto.internal:30197, location 1, ssl: False>]>
2025-11-21 17:37:00,812 - DEBUG - ydb.connection.672590  - 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 17:37:00,857 - DEBUG - ydb.connection.672590  - close: Closing channel for endpoint localhost:30197
2025-11-21 17:37:00,858 - DEBUG - ydb.query.pool.672590  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-21 17:37:00,860 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(CreateSession, 62bfe0c2-bc38-4f12-acc7-b35371bf3cec, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:00,860 - DEBUG - ydb.connection.672590  - _log_request: RpcState(CreateSession, 62bfe0c2-bc38-4f12-acc7-b35371bf3cec, ghrun-zme4sewyte.auto.internal:30197): request = {  }
2025-11-21 17:37:00,868 - DEBUG - ydb.connection.672590  - _log_response: RpcState(CreateSession, 62bfe0c2-bc38-4f12-acc7-b35371bf3cec, ghrun-zme4sewyte.auto.internal:30197): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" node_id: 1 }
2025-11-21 17:37:00,870 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(AttachSession, 03ef4588-23a7-4462-b74f-2eba91dce519, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:00,870 - DEBUG - ydb.connection.672590  - _log_request: RpcState(AttachSession, 03ef4588-23a7-4462-b74f-2eba91dce519, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" }
2025-11-21 17:37:00,874 - DEBUG - ydb.connection.672590  - _log_response: RpcState(AttachSession, 03ef4588-23a7-4462-b74f-2eba91dce519, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:00,887 - DEBUG - ydb.query.pool.672590  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:00,888 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 537020f9-d8bb-487a-b8b3-391dea0fd195, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:00,889 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 537020f9-d8bb-487a-b8b3-391dea0fd195, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:00,891 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 537020f9-d8bb-487a-b8b3-391dea0fd195, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:04,645 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:04,645 - INFO - __tests__.alter_compression.672590  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-21 17:37:04,646 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:04,647 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 16b3b438-dd26-424c-97cf-4d4f8c2126d3, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:04,648 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 16b3b438-dd26-424c-97cf-4d4f8c2126d3, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:04,649 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 16b3b438-dd26-424c-97cf-4d4f8c2126d3, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:05,767 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:05,768 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:05,769 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, f3d10a29-7a23-4fb5-af7d-06dc0ff4916c, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:05,770 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, f3d10a29-7a23-4fb5-af7d-06dc0ff4916c, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:05,778 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, f3d10a29-7a23-4fb5-af7d-06dc0ff4916c, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:07,028 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:07,029 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:07,030 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, b97b4ffc-e5f0-4c17-b70c-9d5b21f7075b, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:07,031 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, b97b4ffc-e5f0-4c17-b70c-9d5b21f7075b, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:07,034 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, b97b4ffc-e5f0-4c17-b70c-9d5b21f7075b, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:08,101 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:08,102 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:08,103 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 4b293018-7959-4462-a1b4-f3d91aa72743, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:08,103 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 4b293018-7959-4462-a1b4-f3d91aa72743, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:08,107 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 4b293018-7959-4462-a1b4-f3d91aa72743, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:09,986 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:09,986 - INFO - __tests__.alter_compression.672590  - 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 17:37:09,987 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:09,988 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 7a3b6539-3fda-44a5-bc06-c79bd940fcd3, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:09,989 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 7a3b6539-3fda-44a5-bc06-c79bd940fcd3, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:09,990 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 7a3b6539-3fda-44a5-bc06-c79bd940fcd3, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:11,220 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:11,220 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:11,221 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, c576b23a-414b-438f-a459-03ebefb20c78, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:11,222 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, c576b23a-414b-438f-a459-03ebefb20c78, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:11,223 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, c576b23a-414b-438f-a459-03ebefb20c78, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:11,716 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:11,717 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:11,717 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, fc27946f-a846-4016-8249-a76b97ad1f03, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:11,718 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, fc27946f-a846-4016-8249-a76b97ad1f03, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:11,721 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, fc27946f-a846-4016-8249-a76b97ad1f03, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:12,118 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:12,118 - INFO - __tests__.alter_compression.672590  - 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 17:37:12,119 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:12,120 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 7c28432f-d13c-4d57-8e83-568a867150c4, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:12,121 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 7c28432f-d13c-4d57-8e83-568a867150c4, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:12,122 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 7c28432f-d13c-4d57-8e83-568a867150c4, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:13,312 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:13,312 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:13,313 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 0bb2538c-c853-4608-b920-8f6b38446611, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:13,314 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 0bb2538c-c853-4608-b920-8f6b38446611, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:13,316 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 0bb2538c-c853-4608-b920-8f6b38446611, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:13,795 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:13,796 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:13,796 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 8777749d-9f81-4cbc-947a-244cbaae8f64, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:13,797 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 8777749d-9f81-4cbc-947a-244cbaae8f64, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:13,811 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 8777749d-9f81-4cbc-947a-244cbaae8f64, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:14,441 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:14,442 - INFO - __tests__.alter_compression.672590  - 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 17:37:14,442 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:14,443 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 31b151d9-07ed-4665-b165-37428a0a3643, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:14,444 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 31b151d9-07ed-4665-b165-37428a0a3643, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:14,453 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 31b151d9-07ed-4665-b165-37428a0a3643, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:15,663 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:15,664 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:15,664 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 30b72a24-6c58-4afc-8ac5-cc90c4add11a, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:15,665 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 30b72a24-6c58-4afc-8ac5-cc90c4add11a, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:15,667 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 30b72a24-6c58-4afc-8ac5-cc90c4add11a, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:16,840 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:16,840 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:16,841 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, ff9fc6c7-25c9-4a46-b226-9e8ca0f5d508, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:16,842 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, ff9fc6c7-25c9-4a46-b226-9e8ca0f5d508, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:16,843 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, ff9fc6c7-25c9-4a46-b226-9e8ca0f5d508, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:17,446 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:17,447 - INFO - __tests__.alter_compression.672590  - 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 17:37:17,447 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:17,448 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 6cd79822-b9dd-4923-8029-e3f7a00145d9, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:17,448 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 6cd79822-b9dd-4923-8029-e3f7a00145d9, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:17,454 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 6cd79822-b9dd-4923-8029-e3f7a00145d9, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:18,661 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:18,662 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:18,663 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 33638318-0a10-4296-a61f-363968c880ae, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:18,664 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 33638318-0a10-4296-a61f-363968c880ae, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:18,665 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 33638318-0a10-4296-a61f-363968c880ae, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:18,999 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:18,999 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:19,000 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 9f04c7ec-51b0-4d98-a188-5d7b97f9beed, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:19,001 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 9f04c7ec-51b0-4d98-a188-5d7b97f9beed, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:19,010 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 9f04c7ec-51b0-4d98-a188-5d7b97f9beed, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:19,681 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:19,682 - INFO - __tests__.alter_compression.672590  - 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 17:37:19,682 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:19,683 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 836119d1-e295-4c05-8c94-64f9382881b2, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:19,684 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 836119d1-e295-4c05-8c94-64f9382881b2, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:19,685 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 836119d1-e295-4c05-8c94-64f9382881b2, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:20,790 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:20,792 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:20,792 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 3202b758-2eda-4d26-b522-6e0f6158ea43, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:20,793 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 3202b758-2eda-4d26-b522-6e0f6158ea43, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:20,795 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 3202b758-2eda-4d26-b522-6e0f6158ea43, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:21,146 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:21,147 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:21,148 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 44e8c46d-ca38-4368-a4ab-556fd3fb28b3, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:21,149 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 44e8c46d-ca38-4368-a4ab-556fd3fb28b3, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:21,150 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 44e8c46d-ca38-4368-a4ab-556fd3fb28b3, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:21,891 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:21,891 - INFO - __tests__.alter_compression.672590  - 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 17:37:21,892 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:21,893 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, f455ed4d-c7b6-40e5-98c8-16ad09023728, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:21,894 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, f455ed4d-c7b6-40e5-98c8-16ad09023728, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:21,903 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, f455ed4d-c7b6-40e5-98c8-16ad09023728, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:23,111 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:23,112 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:23,112 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 784a4c4a-8dd3-4d03-a982-d5ec60ff17c9, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:23,113 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 784a4c4a-8dd3-4d03-a982-d5ec60ff17c9, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:23,115 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 784a4c4a-8dd3-4d03-a982-d5ec60ff17c9, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:23,512 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:23,513 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:23,513 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, c8c770b9-e50e-47a2-a893-bf8a2780c60e, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:23,514 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, c8c770b9-e50e-47a2-a893-bf8a2780c60e, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:23,516 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, c8c770b9-e50e-47a2-a893-bf8a2780c60e, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:24,196 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:24,196 - INFO - __tests__.alter_compression.672590  - 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 17:37:24,197 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:24,198 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 165508ef-cee4-49da-8083-49414efd8286, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:24,199 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 165508ef-cee4-49da-8083-49414efd8286, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:24,202 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 165508ef-cee4-49da-8083-49414efd8286, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:25,547 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:25,548 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:25,549 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, f7f1e726-9d3d-4fec-be9e-c4bf366416b9, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:25,550 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, f7f1e726-9d3d-4fec-be9e-c4bf366416b9, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:25,551 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, f7f1e726-9d3d-4fec-be9e-c4bf366416b9, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:26,024 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:26,025 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:26,026 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, d004e284-852c-4101-96d6-e0fa2bbd8bd2, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:26,027 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, d004e284-852c-4101-96d6-e0fa2bbd8bd2, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:26,029 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, d004e284-852c-4101-96d6-e0fa2bbd8bd2, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:26,784 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:26,784 - INFO - __tests__.alter_compression.672590  - 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 17:37:26,785 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:26,786 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 69dc7597-e65f-4590-978a-d0d13f9239d5, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:26,787 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 69dc7597-e65f-4590-978a-d0d13f9239d5, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:26,788 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 69dc7597-e65f-4590-978a-d0d13f9239d5, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:27,973 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:27,974 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:27,975 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, d3d17711-8eb3-4e6f-83f5-03605904c789, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:27,976 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, d3d17711-8eb3-4e6f-83f5-03605904c789, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:27,984 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, d3d17711-8eb3-4e6f-83f5-03605904c789, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:28,310 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:28,311 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:28,312 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, ff9dc487-fc30-436f-9988-a8464fa3e88e, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:28,313 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, ff9dc487-fc30-436f-9988-a8464fa3e88e, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:28,314 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, ff9dc487-fc30-436f-9988-a8464fa3e88e, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:29,330 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:29,331 - INFO - __tests__.alter_compression.672590  - 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 17:37:29,331 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:29,332 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 1491347c-042f-46d1-9a58-8bc80d442744, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:29,337 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 1491347c-042f-46d1-9a58-8bc80d442744, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:29,339 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 1491347c-042f-46d1-9a58-8bc80d442744, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:30,599 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:30,600 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:30,601 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 60a2c0ce-04c2-4568-b907-4e901baa2d7a, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:30,602 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 60a2c0ce-04c2-4568-b907-4e901baa2d7a, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:30,603 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 60a2c0ce-04c2-4568-b907-4e901baa2d7a, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:30,926 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:30,927 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:30,927 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 1faacbcb-01e3-4e84-b7f2-15f91b946d42, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:30,928 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 1faacbcb-01e3-4e84-b7f2-15f91b946d42, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:30,929 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 1faacbcb-01e3-4e84-b7f2-15f91b946d42, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:31,887 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:31,888 - INFO - __tests__.alter_compression.672590  - 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 17:37:31,889 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:31,889 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 7f8e2979-a4ba-4bf8-bc69-f925e8feb394, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:31,890 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 7f8e2979-a4ba-4bf8-bc69-f925e8feb394, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:31,892 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 7f8e2979-a4ba-4bf8-bc69-f925e8feb394, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:33,387 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:33,387 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:33,388 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 44e03a3f-79c0-4e90-9eab-89ebae591e86, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:33,389 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 44e03a3f-79c0-4e90-9eab-89ebae591e86, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:33,403 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 44e03a3f-79c0-4e90-9eab-89ebae591e86, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:33,926 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:33,927 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:33,928 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 78061a87-5619-452a-81b1-aac3fe41406a, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:33,929 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 78061a87-5619-452a-81b1-aac3fe41406a, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:33,932 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 78061a87-5619-452a-81b1-aac3fe41406a, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:34,267 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:34,268 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:34,269 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 5b7fe6e8-85c9-4a57-b183-552cfd4830a3, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:34,270 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 5b7fe6e8-85c9-4a57-b183-552cfd4830a3, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:34,275 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 5b7fe6e8-85c9-4a57-b183-552cfd4830a3, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:38,955 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:48,998 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:48,999 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 5c3484f9-67c9-4221-bf60-124aff31ba9e, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:49,000 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 5c3484f9-67c9-4221-bf60-124aff31ba9e, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:49,001 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 5c3484f9-67c9-4221-bf60-124aff31ba9e, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:53,472 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:53,542 - INFO - root.672590  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-21 17:37:53,543 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:37:53,544 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 3744a4e6-7870-4c9a-97ac-5a9d348b4e20, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:37:53,545 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 3744a4e6-7870-4c9a-97ac-5a9d348b4e20, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:37:53,547 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 3744a4e6-7870-4c9a-97ac-5a9d348b4e20, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:58,416 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:08,466 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:08,467 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 4610dda5-554e-489f-8a28-31007aacd109, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:08,468 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 4610dda5-554e-489f-8a28-31007aacd109, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:38:08,469 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 4610dda5-554e-489f-8a28-31007aacd109, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:12,743 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:22,766 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:22,770 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 0d533056-24cf-42e6-95b3-8e912dce64d6, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:22,771 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 0d533056-24cf-42e6-95b3-8e912dce64d6, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:38:22,773 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 0d533056-24cf-42e6-95b3-8e912dce64d6, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:23,840 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:33,850 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:33,851 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 0a019d62-c331-4556-a764-6f95ba1ae944, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:33,857 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 0a019d62-c331-4556-a764-6f95ba1ae944, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:38:33,859 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 0a019d62-c331-4556-a764-6f95ba1ae944, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:34,801 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:34,806 - INFO - root.672590  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-11-21 17:38:34,807 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:34,807 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 0ff60cbc-b943-4346-8935-64f68e5c62e6, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:34,810 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 0ff60cbc-b943-4346-8935-64f68e5c62e6, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" 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 17:38:34,811 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 0ff60cbc-b943-4346-8935-64f68e5c62e6, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:35,155 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:35,159 - INFO - ya.test.672590  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_2_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 2])
2025-11-21 17:38:35,162 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:35,164 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 34e70340-c301-4767-a06a-c85d0ffafbb0, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:35,165 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 34e70340-c301-4767-a06a-c85d0ffafbb0, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_2_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 2)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:35,167 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 34e70340-c301-4767-a06a-c85d0ffafbb0, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:38,326 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:38,327 - INFO - __tests__.alter_compression.672590  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_2_compression created
2025-11-21 17:38:38,328 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:38,329 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 4cf7129b-db20-4c66-9623-a6dee7127992, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:38,331 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 4cf7129b-db20-4c66-9623-a6dee7127992, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_2_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:38,333 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 4cf7129b-db20-4c66-9623-a6dee7127992, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:39,289 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:39,289 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:39,290 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, e8a2db35-6535-4da6-aed9-8a7e612fb60b, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:39,291 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, e8a2db35-6535-4da6-aed9-8a7e612fb60b, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:39,293 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, e8a2db35-6535-4da6-aed9-8a7e612fb60b, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:41,142 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:41,143 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:41,144 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 62fda75e-70d3-4d0a-bbe2-656ef2bd85f5, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:41,145 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 62fda75e-70d3-4d0a-bbe2-656ef2bd85f5, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:41,146 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 62fda75e-70d3-4d0a-bbe2-656ef2bd85f5, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:42,395 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:42,396 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:42,397 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, af4f8935-026d-4409-a2cb-35e0ae9a6967, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:42,397 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, af4f8935-026d-4409-a2cb-35e0ae9a6967, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:42,399 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, af4f8935-026d-4409-a2cb-35e0ae9a6967, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:44,395 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:44,396 - INFO - __tests__.alter_compression.672590  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318880, 'BlobCount': 128}}
2025-11-21 17:38:44,396 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:44,397 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, c3835501-67e1-4e76-a9c7-7c2543b79fe6, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:44,398 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, c3835501-67e1-4e76-a9c7-7c2543b79fe6, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:44,403 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, c3835501-67e1-4e76-a9c7-7c2543b79fe6, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:46,018 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:46,018 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:46,019 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, e613c95f-1aed-4c78-88e0-8586d00d3004, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:46,020 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, e613c95f-1aed-4c78-88e0-8586d00d3004, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:46,021 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, e613c95f-1aed-4c78-88e0-8586d00d3004, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:46,318 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:46,319 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:46,320 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 9642fbb5-9b87-4bf4-ae4d-ac522395cfd5, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:46,320 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 9642fbb5-9b87-4bf4-ae4d-ac522395cfd5, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:46,323 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 9642fbb5-9b87-4bf4-ae4d-ac522395cfd5, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:46,875 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:46,876 - INFO - __tests__.alter_compression.672590  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638656, 'BlobCount': 256}}
2025-11-21 17:38:46,877 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:46,882 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 68be7abe-9830-4c2d-8288-94f32b033c78, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:46,887 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 68be7abe-9830-4c2d-8288-94f32b033c78, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:46,888 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 68be7abe-9830-4c2d-8288-94f32b033c78, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:48,115 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:48,116 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:48,116 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, c4605310-f9fd-490d-bc3f-0f7133d369ab, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:48,117 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, c4605310-f9fd-490d-bc3f-0f7133d369ab, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:48,119 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, c4605310-f9fd-490d-bc3f-0f7133d369ab, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:48,356 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:48,357 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:48,358 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 43023909-146a-4fad-aa2f-7fd26a6c6136, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:48,359 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 43023909-146a-4fad-aa2f-7fd26a6c6136, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:48,360 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 43023909-146a-4fad-aa2f-7fd26a6c6136, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:48,818 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:48,819 - INFO - __tests__.alter_compression.672590  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957984, 'BlobCount': 384}}
2025-11-21 17:38:48,820 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:48,820 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 9a9c0ebc-8d77-46fc-be91-0ddd530d2b0e, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:48,821 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 9a9c0ebc-8d77-46fc-be91-0ddd530d2b0e, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:48,823 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 9a9c0ebc-8d77-46fc-be91-0ddd530d2b0e, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:50,023 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:50,024 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:50,024 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, fbcade55-efa9-479e-8ebc-ffdbce9d51f5, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:50,025 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, fbcade55-efa9-479e-8ebc-ffdbce9d51f5, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:50,027 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, fbcade55-efa9-479e-8ebc-ffdbce9d51f5, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:50,279 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:50,280 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:50,281 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, abdfe198-06b6-45f5-91ce-45d1ae6e7911, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:50,281 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, abdfe198-06b6-45f5-91ce-45d1ae6e7911, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:50,283 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, abdfe198-06b6-45f5-91ce-45d1ae6e7911, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:50,751 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:50,752 - INFO - __tests__.alter_compression.672590  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1277728, 'BlobCount': 512}}
2025-11-21 17:38:50,752 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:50,753 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 376c66d9-3836-4faf-b747-e82297cf02dc, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:50,754 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 376c66d9-3836-4faf-b747-e82297cf02dc, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:50,762 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 376c66d9-3836-4faf-b747-e82297cf02dc, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:52,430 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:52,431 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:52,431 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, d6bf42cd-560b-400c-ba4e-1bb1448a9770, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:52,432 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, d6bf42cd-560b-400c-ba4e-1bb1448a9770, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:52,434 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, d6bf42cd-560b-400c-ba4e-1bb1448a9770, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:52,930 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:52,931 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:52,932 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 056a07fc-017e-442f-bddb-a059a26255ba, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:52,933 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 056a07fc-017e-442f-bddb-a059a26255ba, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:52,935 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 056a07fc-017e-442f-bddb-a059a26255ba, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:53,546 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:53,547 - INFO - __tests__.alter_compression.672590  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1597184, 'BlobCount': 640}}
2025-11-21 17:38:53,548 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:53,548 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 1a43a543-9489-4ec8-bed2-ae401a075e7f, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:53,549 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 1a43a543-9489-4ec8-bed2-ae401a075e7f, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:53,551 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 1a43a543-9489-4ec8-bed2-ae401a075e7f, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:54,855 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:54,856 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:54,857 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, b7ef03e8-8223-4feb-8359-996a616b6f4c, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:54,858 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, b7ef03e8-8223-4feb-8359-996a616b6f4c, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:54,859 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, b7ef03e8-8223-4feb-8359-996a616b6f4c, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:55,323 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:55,324 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:55,325 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, f6b47cb6-d777-48d8-b461-1e05d3978216, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:55,326 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, f6b47cb6-d777-48d8-b461-1e05d3978216, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:55,327 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, f6b47cb6-d777-48d8-b461-1e05d3978216, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:56,042 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:56,042 - INFO - __tests__.alter_compression.672590  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1916800, 'BlobCount': 768}}
2025-11-21 17:38:56,043 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:56,044 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 9513128c-2ff3-4995-8fc4-cb11e3933b87, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:56,045 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 9513128c-2ff3-4995-8fc4-cb11e3933b87, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:56,049 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 9513128c-2ff3-4995-8fc4-cb11e3933b87, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:57,390 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:57,391 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:57,392 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, d8fa82e8-0490-43c0-a949-1999f2782ccc, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:57,392 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, d8fa82e8-0490-43c0-a949-1999f2782ccc, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:57,394 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, d8fa82e8-0490-43c0-a949-1999f2782ccc, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:57,862 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:57,863 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:57,864 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 5bf5dafd-1d6f-4c7c-89a5-8616ec413d45, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:57,865 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 5bf5dafd-1d6f-4c7c-89a5-8616ec413d45, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:57,874 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 5bf5dafd-1d6f-4c7c-89a5-8616ec413d45, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:58,491 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:58,492 - INFO - __tests__.alter_compression.672590  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2236496, 'BlobCount': 896}}
2025-11-21 17:38:58,493 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:58,493 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 35b9de41-c554-440e-800b-6721170e3206, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:58,495 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 35b9de41-c554-440e-800b-6721170e3206, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:58,496 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 35b9de41-c554-440e-800b-6721170e3206, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:58,970 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.672590  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 17:38:58,971 - DEBUG - ydb.connection.672590  - 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 17:38:59,030 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.672590  - context_resolve: Resolving endpoints for database /Root
2025-11-21 17:38:59,031 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ListEndpoints, b393b98a-6791-4e80-aa74-1424c8311ecf, localhost:30197): creating call state
2025-11-21 17:38:59,031 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ListEndpoints, b393b98a-6791-4e80-aa74-1424c8311ecf, localhost:30197): request = { database: "/Root" }
2025-11-21 17:38:59,043 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ListEndpoints, b393b98a-6791-4e80-aa74-1424c8311ecf, localhost:30197): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-zme4sewyte.auto.internal" port: 30197 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 17:38:59,044 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.672590  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-zme4sewyte.auto.internal:30197, location 1, ssl: False>]>
2025-11-21 17:38:59,045 - DEBUG - ydb.connection.672590  - close: Closing channel for endpoint localhost:30197
2025-11-21 17:38:59,868 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:59,869 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:38:59,869 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 012226c6-f4bc-40e9-925f-a35d9c50011c, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:38:59,870 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 012226c6-f4bc-40e9-925f-a35d9c50011c, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:59,872 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 012226c6-f4bc-40e9-925f-a35d9c50011c, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:00,237 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:00,237 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:00,238 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, f46c75ee-52b4-4fa4-b6ab-c55fd44ed07d, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:39:00,239 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, f46c75ee-52b4-4fa4-b6ab-c55fd44ed07d, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:00,242 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, f46c75ee-52b4-4fa4-b6ab-c55fd44ed07d, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:01,116 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:01,116 - INFO - __tests__.alter_compression.672590  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2556272, 'BlobCount': 1024}}
2025-11-21 17:39:01,117 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:01,118 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 13ebeddc-4ebb-4783-af72-622fc9d3a6f5, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:39:01,119 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 13ebeddc-4ebb-4783-af72-622fc9d3a6f5, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:01,120 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 13ebeddc-4ebb-4783-af72-622fc9d3a6f5, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:02,592 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:02,593 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:02,593 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 2bccaeef-82f6-43b7-8fbb-5922427a7704, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:39:02,594 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 2bccaeef-82f6-43b7-8fbb-5922427a7704, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:02,595 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 2bccaeef-82f6-43b7-8fbb-5922427a7704, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:02,933 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:02,933 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:02,934 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, d6c9b677-0c6e-46eb-9612-003eca9731db, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:39:02,935 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, d6c9b677-0c6e-46eb-9612-003eca9731db, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:02,937 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, d6c9b677-0c6e-46eb-9612-003eca9731db, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:04,230 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:04,231 - INFO - __tests__.alter_compression.672590  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2875584, 'BlobCount': 1152}}
2025-11-21 17:39:04,231 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:04,232 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 3fb57e24-5708-4e80-8c69-dcf816bdf574, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:39:04,233 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 3fb57e24-5708-4e80-8c69-dcf816bdf574, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:04,234 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 3fb57e24-5708-4e80-8c69-dcf816bdf574, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:05,662 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:05,662 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:05,663 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 2c373992-a005-4e6b-888b-e93dafc1f9e4, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:39:05,664 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 2c373992-a005-4e6b-888b-e93dafc1f9e4, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:05,665 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 2c373992-a005-4e6b-888b-e93dafc1f9e4, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:06,008 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:06,008 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:06,009 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 2f814b50-57cc-4d54-b3b4-c8dd16a23033, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:39:06,010 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 2f814b50-57cc-4d54-b3b4-c8dd16a23033, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:06,013 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 2f814b50-57cc-4d54-b3b4-c8dd16a23033, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:06,893 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:06,894 - INFO - __tests__.alter_compression.672590  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3195568, 'BlobCount': 1280}}
2025-11-21 17:39:06,894 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:06,895 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 8bd393dd-8f93-4948-a31c-122009762b6d, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:39:06,895 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 8bd393dd-8f93-4948-a31c-122009762b6d, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_2_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:06,898 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 8bd393dd-8f93-4948-a31c-122009762b6d, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:08,353 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:08,353 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:08,354 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, b2f47967-f2d2-45ee-99d7-781ab227fef9, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:39:08,355 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, b2f47967-f2d2-45ee-99d7-781ab227fef9, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:08,357 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, b2f47967-f2d2-45ee-99d7-781ab227fef9, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:08,783 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:08,784 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:08,784 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, cf3aa70a-3eb2-4f39-a746-a1e553c7729d, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:39:08,786 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, cf3aa70a-3eb2-4f39-a746-a1e553c7729d, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:08,787 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, cf3aa70a-3eb2-4f39-a746-a1e553c7729d, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:09,242 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:09,243 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:09,244 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 974a8a0b-0b23-4970-8f24-7de0ea33a9b8, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:39:09,244 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 974a8a0b-0b23-4970-8f24-7de0ea33a9b8, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:09,251 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 974a8a0b-0b23-4970-8f24-7de0ea33a9b8, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:14,509 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:24,562 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:24,563 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 8dae7689-ae71-459a-a97d-0ec46380136a, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:39:24,563 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 8dae7689-ae71-459a-a97d-0ec46380136a, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:24,565 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 8dae7689-ae71-459a-a97d-0ec46380136a, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:28,963 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:29,023 - INFO - root.672590  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_2_compression`, volumes `value` (8000000, 1597784)
2025-11-21 17:39:29,024 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:29,025 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, aad49ce7-670a-474e-8508-aea3b6d6cefe, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:39:29,026 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, aad49ce7-670a-474e-8508-aea3b6d6cefe, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:29,028 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, aad49ce7-670a-474e-8508-aea3b6d6cefe, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:29,750 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:29,751 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:29,752 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 17f6baf3-a441-4242-9b10-9c1363f2f032, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:39:29,753 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 17f6baf3-a441-4242-9b10-9c1363f2f032, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:29,754 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 17f6baf3-a441-4242-9b10-9c1363f2f032, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:34,669 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:44,711 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:44,712 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, d0c22248-bfb4-4456-952d-2826a06ca2f8, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:39:44,713 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, d0c22248-bfb4-4456-952d-2826a06ca2f8, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:44,718 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, d0c22248-bfb4-4456-952d-2826a06ca2f8, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:47,520 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:57,558 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:39:57,559 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 9e3d578a-1ea3-46c7-9dae-185bc1ccd2c1, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:39:57,560 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 9e3d578a-1ea3-46c7-9dae-185bc1ccd2c1, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:57,561 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 9e3d578a-1ea3-46c7-9dae-185bc1ccd2c1, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:58,464 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:40:08,469 - DEBUG - ydb.query.pool.672590  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:40:08,470 - DEBUG - ydb.connection.672590  - _prepare_call: RpcState(ExecuteQuery, 37aa7aec-6d8c-4207-aea3-1872be907050, ghrun-zme4sewyte.auto.internal:30197): creating call state
2025-11-21 17:40:08,471 - DEBUG - ydb.connection.672590  - _log_request: RpcState(ExecuteQuery, 37aa7aec-6d8c-4207-aea3-1872be907050, ghrun-zme4sewyte.auto.internal:30197): request = { session_id: "ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:08,472 - DEBUG - ydb.connection.672590  - _log_response: RpcState(ExecuteQuery, 37aa7aec-6d8c-4207-aea3-1872be907050, ghrun-zme4sewyte.auto.internal:30197): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:09,528 - DEBUG - ydb.query.pool.672590  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWNjM2Y4OGYtY2VhYTIwYmEtNjI2MDc1ZmYtYjgyNDc1Yjk=
2025-11-21 17:40:09,534 - INFO - root.672590  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_2_compression`, volumes `value` (8000000, 1465320)
2025-11-21 17:40:09,535 - INFO - root.672590  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_2_compression` 8092160 / 1465320: 5.522452433598122
2025-11-21 17:40:09,537 - INFO - ya.test.672590  - pytest_runtest_teardown: Test teardown
