2025-11-21 17:37:04,033 - INFO - ya.test.675348  - pytest_runtest_setup: ####################################################################################################
2025-11-21 17:37:04,034 - INFO - ya.test.675348  - pytest_runtest_setup: test_all_supported_compression[zstd_18_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 18]
2025-11-21 17:37:04,035 - INFO - ya.test.675348  - pytest_runtest_setup: ####################################################################################################
2025-11-21 17:37:04,035 - INFO - ya.test.675348  - pytest_runtest_setup: Test setup
2025-11-21 17:37:04,038 - DEBUG - ya.test.675348  - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-21 17:37:04,038 - DEBUG - ya.test.675348  - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-21 17:37:04,039 - DEBUG - ya.test.675348  - execute: Executing '['/home/runner/.ya/build/build_root/ue5z/005599/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-21 17:37:04,041 - DEBUG - ya.test.675348  - execute: Command pid: 676229
2025-11-21 17:37:05,064 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) rc: 0
2025-11-21 17:37:05,065 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) elapsed time (sec): 1.0224802494049072
2025-11-21 17:37:05,065 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) idrss: 0
2025-11-21 17:37:05,066 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) inblock: 0
2025-11-21 17:37:05,066 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) isrss: 0
2025-11-21 17:37:05,066 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) ixrss: 0
2025-11-21 17:37:05,067 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) majflt: 0
2025-11-21 17:37:05,067 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) maxrss: 492980
2025-11-21 17:37:05,067 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) minflt: 45856
2025-11-21 17:37:05,067 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) msgrcv: 0
2025-11-21 17:37:05,068 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) msgsnd: 0
2025-11-21 17:37:05,068 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) nivcsw: 178
2025-11-21 17:37:05,068 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) nsignals: 0
2025-11-21 17:37:05,068 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) nswap: 0
2025-11-21 17:37:05,069 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) nvcsw: 15
2025-11-21 17:37:05,069 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) oublock: 8
2025-11-21 17:37:05,070 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) stime: 0.229838
2025-11-21 17:37:05,070 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) utime: 0.539585
2025-11-21 17:37:05,070 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) wtime: 1.025
2025-11-21 17:37:05,071 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) 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:37:05,071 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676229) errors:

2025-11-21 17:37:05,072 - INFO - __tests__.base.675348  - _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:37:05,209 - DEBUG - ya.test.675348  - get_binary: Binary was found by /home/runner/.ya/build/build_root/ue5z/005599/ydb/apps/ydbd/ydbd
2025-11-21 17:37:05,211 - DEBUG - library.python.filelock.675348  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30083
2025-11-21 17:37:05,213 - DEBUG - ydb.tests.library.harness.kikimr_runner.675348  - start: Working directory: /home/runner/.ya/build/build_root/ue5z/005599/r3tmp/kikimr_cluster_nexab1us
2025-11-21 17:37:05,268 - DEBUG - ya.test.675348  - get_binary: Binary was found by /home/runner/.ya/build/build_root/ue5z/005599/ydb/apps/ydbd/ydbd
2025-11-21 17:37:05,269 - DEBUG - library.python.filelock.675348  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10869
2025-11-21 17:37:05,269 - DEBUG - library.python.filelock.675348  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10937
2025-11-21 17:37:05,270 - DEBUG - library.python.filelock.675348  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16273
2025-11-21 17:37:05,271 - DEBUG - library.python.filelock.675348  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12951
2025-11-21 17:37:05,272 - INFO - ydb.tests.library.harness.kikimr_runner.675348  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ue5z/005599/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs"
2025-11-21 17:37:05,272 - INFO - ydb.tests.library.harness.kikimr_runner.675348  - __make_run_command: Final command: /home/runner/.ya/build/build_root/ue5z/005599/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/005599/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_a2mc6yce.log --grpc-port=10869 --mon-port=10937 --ic-port=30083
2025-11-21 17:37:05,273 - DEBUG - ydb.tests.library.harness.kikimr_runner.675348  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:10869/1, disk_size 68719476736
2025-11-21 17:37:05,273 - INFO - ydb.tests.library.harness.kikimr_runner.675348  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ue5z/005599/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs"
2025-11-21 17:37:05,274 - INFO - ydb.tests.library.harness.kikimr_runner.675348  - __make_run_command: Final command: /home/runner/.ya/build/build_root/ue5z/005599/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/005599/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_a2mc6yce.log --grpc-port=10869 --mon-port=10937 --ic-port=30083
2025-11-21 17:37:05,275 - DEBUG - ya.test.675348  - execute: Executing '('/home/runner/.ya/build/build_root/ue5z/005599/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/ue5z/005599/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/ue5z/005599/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_a2mc6yce.log', '--grpc-port=10869', '--mon-port=10937', '--ic-port=30083')' in '/home/runner/.ya/build/build_root/ue5z/005599/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1' (no wait)
2025-11-21 17:37:05,285 - DEBUG - ya.test.675348  - execute: Command pid: 676436
2025-11-21 17:37:05,286 - INFO - ydb.tests.library.harness.kikimr_runner.675348  - start: Started node localhost:10869/1
2025-11-21 17:37:05,288 - DEBUG - ya.test.675348  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-21 17:37:05,288 - DEBUG - ya.test.675348  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-21 17:37:05,289 - DEBUG - ya.test.675348  - execute: Executing '['/home/runner/.ya/build/build_root/ue5z/005599/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-21 17:37:05,294 - DEBUG - ya.test.675348  - execute: Command pid: 676438
2025-11-21 17:37:06,268 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) rc: 0
2025-11-21 17:37:06,268 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) elapsed time (sec): 0.9726583957672119
2025-11-21 17:37:06,269 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) idrss: 0
2025-11-21 17:37:06,269 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) inblock: 0
2025-11-21 17:37:06,269 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) isrss: 0
2025-11-21 17:37:06,270 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) ixrss: 0
2025-11-21 17:37:06,270 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) majflt: 0
2025-11-21 17:37:06,270 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) maxrss: 494392
2025-11-21 17:37:06,271 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) minflt: 45628
2025-11-21 17:37:06,271 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) msgrcv: 0
2025-11-21 17:37:06,271 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) msgsnd: 0
2025-11-21 17:37:06,272 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) nivcsw: 180
2025-11-21 17:37:06,273 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) nsignals: 0
2025-11-21 17:37:06,273 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) nswap: 0
2025-11-21 17:37:06,273 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) nvcsw: 14
2025-11-21 17:37:06,274 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) oublock: 8
2025-11-21 17:37:06,274 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) stime: 0.236754
2025-11-21 17:37:06,274 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) utime: 0.518968
2025-11-21 17:37:06,275 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) wtime: 0.978
2025-11-21 17:37:06,275 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) 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:37:06,275 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 676438) errors:

2025-11-21 17:37:06,276 - INFO - ydb.tests.library.harness.kikimr_runner.675348  - 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:37:11,739 - DEBUG - ya.test.675348  - get_binary: Binary was found by /home/runner/.ya/build/build_root/ue5z/005599/ydb/apps/ydbd/ydbd
2025-11-21 17:37:11,739 - DEBUG - ydb.tests.library.harness.kikimr_runner.675348  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/ue5z/005599/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10869', '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: 30083\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-21 17:37:11,740 - DEBUG - ya.test.675348  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-21 17:37:11,741 - DEBUG - ya.test.675348  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-21 17:37:11,742 - DEBUG - ya.test.675348  - execute: Executing '['/home/runner/.ya/build/build_root/ue5z/005599/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10869', '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: 30083\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-21 17:37:11,746 - DEBUG - ya.test.675348  - execute: Command pid: 677506
2025-11-21 17:37:13,027 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) rc: 0
2025-11-21 17:37:13,028 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) elapsed time (sec): 1.2797482013702393
2025-11-21 17:37:13,028 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) idrss: 0
2025-11-21 17:37:13,029 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) inblock: 0
2025-11-21 17:37:13,029 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) isrss: 0
2025-11-21 17:37:13,029 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) ixrss: 0
2025-11-21 17:37:13,029 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) majflt: 0
2025-11-21 17:37:13,030 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) maxrss: 592944
2025-11-21 17:37:13,030 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) minflt: 52271
2025-11-21 17:37:13,030 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) msgrcv: 0
2025-11-21 17:37:13,030 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) msgsnd: 0
2025-11-21 17:37:13,031 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) nivcsw: 280
2025-11-21 17:37:13,031 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) nsignals: 0
2025-11-21 17:37:13,033 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) nswap: 0
2025-11-21 17:37:13,033 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) nvcsw: 449
2025-11-21 17:37:13,033 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) oublock: 8
2025-11-21 17:37:13,033 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) stime: 0.35473
2025-11-21 17:37:13,034 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) utime: 0.592486
2025-11-21 17:37:13,034 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) wtime: 1.285
2025-11-21 17:37:13,034 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-21 17:37:13,035 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677506) errors:

2025-11-21 17:37:13,036 - DEBUG - ya.test.675348  - get_binary: Binary was found by /home/runner/.ya/build/build_root/ue5z/005599/ydb/apps/ydbd/ydbd
2025-11-21 17:37:13,037 - DEBUG - ydb.tests.library.harness.kikimr_runner.675348  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/ue5z/005599/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10869', '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:37:13,038 - DEBUG - ya.test.675348  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-21 17:37:13,039 - DEBUG - ya.test.675348  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-21 17:37:13,039 - DEBUG - ya.test.675348  - execute: Executing '['/home/runner/.ya/build/build_root/ue5z/005599/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10869', '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:37:13,052 - DEBUG - ya.test.675348  - execute: Command pid: 677614
2025-11-21 17:37:14,570 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) rc: 0
2025-11-21 17:37:14,571 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) elapsed time (sec): 1.5162928104400635
2025-11-21 17:37:14,571 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) idrss: 0
2025-11-21 17:37:14,571 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) inblock: 0
2025-11-21 17:37:14,572 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) isrss: 0
2025-11-21 17:37:14,572 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) ixrss: 0
2025-11-21 17:37:14,572 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) majflt: 0
2025-11-21 17:37:14,572 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) maxrss: 593252
2025-11-21 17:37:14,573 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) minflt: 51976
2025-11-21 17:37:14,573 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) msgrcv: 0
2025-11-21 17:37:14,573 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) msgsnd: 0
2025-11-21 17:37:14,574 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) nivcsw: 233
2025-11-21 17:37:14,574 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) nsignals: 0
2025-11-21 17:37:14,575 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) nswap: 0
2025-11-21 17:37:14,575 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) nvcsw: 395
2025-11-21 17:37:14,575 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) oublock: 8
2025-11-21 17:37:14,575 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) stime: 0.27755799999999997
2025-11-21 17:37:14,576 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) utime: 0.61037
2025-11-21 17:37:14,576 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) wtime: 1.53
2025-11-21 17:37:14,576 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-21 17:37:14,577 - DEBUG - ya.test.675348  - _save_outputs: Command (pid 677614) errors:

2025-11-21 17:37:14,577 - INFO - ydb.tests.library.harness.kikimr_runner.675348  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-21 17:37:15,075 - INFO - ydb.tests.library.harness.kikimr_runner.675348  - __run: Cluster started and initialized
2025-11-21 17:37:15,118 - DEBUG - ydb.connection.675348  - 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:15,126 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.675348  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 17:37:15,127 - DEBUG - ydb.connection.675348  - 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:15,196 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.675348  - context_resolve: Resolving endpoints for database /Root
2025-11-21 17:37:15,198 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ListEndpoints, 7806464a-0aee-4972-abc2-4c3c1343903f, localhost:10869): creating call state
2025-11-21 17:37:15,198 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ListEndpoints, 7806464a-0aee-4972-abc2-4c3c1343903f, localhost:10869): request = { database: "/Root" }
2025-11-21 17:37:15,216 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ListEndpoints, 7806464a-0aee-4972-abc2-4c3c1343903f, localhost:10869): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-zme4sewyte.auto.internal" port: 10869 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:15,217 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.675348  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-zme4sewyte.auto.internal:10869, location 1, ssl: False>]>
2025-11-21 17:37:15,218 - DEBUG - ydb.connection.675348  - 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:15,282 - DEBUG - ydb.connection.675348  - close: Closing channel for endpoint localhost:10869
2025-11-21 17:37:15,283 - DEBUG - ydb.query.pool.675348  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-21 17:37:15,284 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(CreateSession, c9a3cf61-d888-4806-953a-61e35f543ad6, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:15,285 - DEBUG - ydb.connection.675348  - _log_request: RpcState(CreateSession, c9a3cf61-d888-4806-953a-61e35f543ad6, ghrun-zme4sewyte.auto.internal:10869): request = {  }
2025-11-21 17:37:15,293 - DEBUG - ydb.connection.675348  - _log_response: RpcState(CreateSession, c9a3cf61-d888-4806-953a-61e35f543ad6, ghrun-zme4sewyte.auto.internal:10869): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" node_id: 1 }
2025-11-21 17:37:15,294 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(AttachSession, fe63eb53-f15f-4cbf-b24e-2e0b3e7facf7, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:15,295 - DEBUG - ydb.connection.675348  - _log_request: RpcState(AttachSession, fe63eb53-f15f-4cbf-b24e-2e0b3e7facf7, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" }
2025-11-21 17:37:15,301 - DEBUG - ydb.connection.675348  - _log_response: RpcState(AttachSession, fe63eb53-f15f-4cbf-b24e-2e0b3e7facf7, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:15,327 - DEBUG - ydb.query.pool.675348  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:15,328 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 7d65e16c-f45c-401c-98c9-fb5b9f1f2ec7, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:15,329 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 7d65e16c-f45c-401c-98c9-fb5b9f1f2ec7, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:15,331 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 7d65e16c-f45c-401c-98c9-fb5b9f1f2ec7, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:19,359 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:19,359 - INFO - __tests__.alter_compression.675348  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-21 17:37:19,360 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:19,361 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, b878b583-b1e2-4908-a986-5c3c1b653742, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:19,362 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, b878b583-b1e2-4908-a986-5c3c1b653742, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:19,363 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, b878b583-b1e2-4908-a986-5c3c1b653742, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:20,488 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:20,489 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:20,490 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, e98d4486-936d-46c8-9aae-e52db489ad03, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:20,491 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, e98d4486-936d-46c8-9aae-e52db489ad03, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:20,499 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, e98d4486-936d-46c8-9aae-e52db489ad03, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:21,966 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:21,967 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:21,968 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, f9f9364e-dbdd-4e5a-9f43-0b4eb5b8634f, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:21,969 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, f9f9364e-dbdd-4e5a-9f43-0b4eb5b8634f, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:21,971 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, f9f9364e-dbdd-4e5a-9f43-0b4eb5b8634f, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:23,028 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:23,029 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:23,030 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, a63bb832-40b6-467d-809b-c8a359524239, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:23,031 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, a63bb832-40b6-467d-809b-c8a359524239, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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,033 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, a63bb832-40b6-467d-809b-c8a359524239, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:24,894 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:24,895 - INFO - __tests__.alter_compression.675348  - 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:24,896 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:24,897 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 6c4475ac-9888-4dfa-9481-3dc0b90e0e89, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:24,898 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 6c4475ac-9888-4dfa-9481-3dc0b90e0e89, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:24,900 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 6c4475ac-9888-4dfa-9481-3dc0b90e0e89, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:26,023 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:26,024 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:26,025 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 39385431-4f86-4be8-9b7d-3576d58cf9c1, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:26,026 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 39385431-4f86-4be8-9b7d-3576d58cf9c1, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:26,027 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 39385431-4f86-4be8-9b7d-3576d58cf9c1, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:26,341 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:26,342 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:26,343 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, f1f97309-41d4-48aa-88fa-1816d4c2d2de, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:26,344 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, f1f97309-41d4-48aa-88fa-1816d4c2d2de, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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,345 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, f1f97309-41d4-48aa-88fa-1816d4c2d2de, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:26,820 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:26,821 - INFO - __tests__.alter_compression.675348  - 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:26,821 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:26,822 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, b4e2fd12-0c11-4fe9-b3da-2b7bb734ef1c, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:26,823 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, b4e2fd12-0c11-4fe9-b3da-2b7bb734ef1c, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:26,825 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, b4e2fd12-0c11-4fe9-b3da-2b7bb734ef1c, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:28,139 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:28,139 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:28,140 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 0a8f74a0-3e2e-4764-937a-7d51a3f1198c, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:28,141 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 0a8f74a0-3e2e-4764-937a-7d51a3f1198c, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:28,143 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 0a8f74a0-3e2e-4764-937a-7d51a3f1198c, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:28,506 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:28,507 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:28,508 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 2fd37954-2d0b-45b4-a1a6-65c527c7e2e5, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:28,509 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 2fd37954-2d0b-45b4-a1a6-65c527c7e2e5, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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,511 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 2fd37954-2d0b-45b4-a1a6-65c527c7e2e5, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:29,022 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:29,023 - INFO - __tests__.alter_compression.675348  - 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:29,023 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:29,024 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 7e948596-df13-415d-bdef-2f373d305db9, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:29,025 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 7e948596-df13-415d-bdef-2f373d305db9, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:29,027 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 7e948596-df13-415d-bdef-2f373d305db9, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:30,642 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:30,643 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:30,644 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 9c48fb2b-015f-4cb3-b186-e94aed92ad73, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:30,645 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 9c48fb2b-015f-4cb3-b186-e94aed92ad73, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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,647 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 9c48fb2b-015f-4cb3-b186-e94aed92ad73, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:31,039 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:31,040 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:31,040 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 4fcc6bf8-f007-4d69-8019-5d28d1ad4ace, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:31,041 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 4fcc6bf8-f007-4d69-8019-5d28d1ad4ace, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:31,043 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 4fcc6bf8-f007-4d69-8019-5d28d1ad4ace, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:31,800 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:31,801 - INFO - __tests__.alter_compression.675348  - 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:31,801 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:31,802 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 64aaeb7e-313c-46c2-8758-f2fe52a1d06a, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:31,803 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 64aaeb7e-313c-46c2-8758-f2fe52a1d06a, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:31,805 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 64aaeb7e-313c-46c2-8758-f2fe52a1d06a, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:33,022 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:33,023 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:33,023 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 9593dbee-03b0-456a-9860-7402a41b5a9b, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:33,024 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 9593dbee-03b0-456a-9860-7402a41b5a9b, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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,030 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 9593dbee-03b0-456a-9860-7402a41b5a9b, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:33,398 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:33,399 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:33,400 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, b96bf3f4-3912-465b-a460-8844b6d4d5fe, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:33,401 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, b96bf3f4-3912-465b-a460-8844b6d4d5fe, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:33,407 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, b96bf3f4-3912-465b-a460-8844b6d4d5fe, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:34,131 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:34,132 - INFO - __tests__.alter_compression.675348  - 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:34,133 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:34,133 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 17078028-aad2-49aa-9971-b43563faaf83, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:34,134 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 17078028-aad2-49aa-9971-b43563faaf83, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:34,136 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 17078028-aad2-49aa-9971-b43563faaf83, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:35,452 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:35,453 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:35,454 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 3b7f1a35-7b2c-453e-9a5c-4bd926d4880a, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:35,455 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 3b7f1a35-7b2c-453e-9a5c-4bd926d4880a, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:35,462 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 3b7f1a35-7b2c-453e-9a5c-4bd926d4880a, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:35,815 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:35,816 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:35,817 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, e9d90706-227f-4cf5-8725-b850423ccaea, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:35,818 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, e9d90706-227f-4cf5-8725-b850423ccaea, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:35,830 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, e9d90706-227f-4cf5-8725-b850423ccaea, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:36,594 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:36,595 - INFO - __tests__.alter_compression.675348  - 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:36,595 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:36,596 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 8d5e66ba-757d-41be-9e5a-ead214f55c2e, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:36,597 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 8d5e66ba-757d-41be-9e5a-ead214f55c2e, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:36,599 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 8d5e66ba-757d-41be-9e5a-ead214f55c2e, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:37,726 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:37,727 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:37,728 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 53f71946-e131-4020-98f0-058bcde5928f, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:37,729 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 53f71946-e131-4020-98f0-058bcde5928f, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:37,731 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 53f71946-e131-4020-98f0-058bcde5928f, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:38,091 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:38,092 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:38,092 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 61a6c971-4929-4932-a094-ee212222162c, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:38,093 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 61a6c971-4929-4932-a094-ee212222162c, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:38,095 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 61a6c971-4929-4932-a094-ee212222162c, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:38,662 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:38,663 - INFO - __tests__.alter_compression.675348  - 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:38,663 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:38,664 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 1c9c18d1-c3b6-4aa8-aac1-a0f2256866f2, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:38,669 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 1c9c18d1-c3b6-4aa8-aac1-a0f2256866f2, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:38,671 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 1c9c18d1-c3b6-4aa8-aac1-a0f2256866f2, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:39,902 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:39,902 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:39,903 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, b86fc87d-9004-493b-8383-92a4edd952ba, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:39,904 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, b86fc87d-9004-493b-8383-92a4edd952ba, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:39,905 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, b86fc87d-9004-493b-8383-92a4edd952ba, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:40,245 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:40,245 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:40,246 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, f933ef21-64e3-4e55-aeb6-6f2382a72daa, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:40,247 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, f933ef21-64e3-4e55-aeb6-6f2382a72daa, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:40,248 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, f933ef21-64e3-4e55-aeb6-6f2382a72daa, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:40,830 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:40,831 - INFO - __tests__.alter_compression.675348  - 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:40,832 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:40,832 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 2d19a805-77d5-4467-8bf4-f40b528f7038, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:40,833 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 2d19a805-77d5-4467-8bf4-f40b528f7038, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:40,835 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 2d19a805-77d5-4467-8bf4-f40b528f7038, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:42,314 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:42,315 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:42,315 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 5e7dc891-599a-40b4-b1e3-ed576d30f330, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:42,316 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 5e7dc891-599a-40b4-b1e3-ed576d30f330, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:42,319 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 5e7dc891-599a-40b4-b1e3-ed576d30f330, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:42,749 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:42,750 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:42,751 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 40e06996-567b-4af5-a864-12aa3a29ee29, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:42,752 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 40e06996-567b-4af5-a864-12aa3a29ee29, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:42,756 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 40e06996-567b-4af5-a864-12aa3a29ee29, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:43,622 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:43,623 - INFO - __tests__.alter_compression.675348  - 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:43,624 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:43,625 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, b04df647-6129-479e-8165-79949c1db667, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:43,630 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, b04df647-6129-479e-8165-79949c1db667, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:43,632 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, b04df647-6129-479e-8165-79949c1db667, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:45,020 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:45,021 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:45,022 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, d740a2e9-f9a7-4bfa-b585-58e9d03f94be, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:45,023 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, d740a2e9-f9a7-4bfa-b585-58e9d03f94be, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:45,024 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, d740a2e9-f9a7-4bfa-b585-58e9d03f94be, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:45,512 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:45,513 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:45,514 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 76028a01-1ef0-4a5c-9503-46acb7cf5565, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:45,515 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 76028a01-1ef0-4a5c-9503-46acb7cf5565, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:45,516 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 76028a01-1ef0-4a5c-9503-46acb7cf5565, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:46,605 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:46,606 - INFO - __tests__.alter_compression.675348  - 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:46,607 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:46,607 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 1ccbbaf7-a2d0-485f-a6d1-64cf9b4456a7, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:46,608 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 1ccbbaf7-a2d0-485f-a6d1-64cf9b4456a7, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:46,614 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 1ccbbaf7-a2d0-485f-a6d1-64cf9b4456a7, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:47,799 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:47,800 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:47,800 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, e702e04f-6e0a-44fe-920b-cd4a0b86c396, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:47,801 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, e702e04f-6e0a-44fe-920b-cd4a0b86c396, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:47,802 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, e702e04f-6e0a-44fe-920b-cd4a0b86c396, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:48,290 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:48,290 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:48,291 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, cb357723-fb12-43df-9bbc-6be06d40b647, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:48,291 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, cb357723-fb12-43df-9bbc-6be06d40b647, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:48,292 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, cb357723-fb12-43df-9bbc-6be06d40b647, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:48,647 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:48,648 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:37:48,649 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, f2265edd-45f9-437d-b6a6-7f05c647b2af, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:37:48,650 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, f2265edd-45f9-437d-b6a6-7f05c647b2af, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:48,651 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, f2265edd-45f9-437d-b6a6-7f05c647b2af, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:53,509 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:03,554 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:03,554 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, c929bd4c-5867-447e-95be-586b0375b613, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:38:03,555 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, c929bd4c-5867-447e-95be-586b0375b613, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:03,557 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, c929bd4c-5867-447e-95be-586b0375b613, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:09,257 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:09,325 - INFO - root.675348  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-21 17:38:09,326 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:09,327 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, b735e5d1-e4f9-462e-821d-3dc8583a5bb8, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:38:09,328 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, b735e5d1-e4f9-462e-821d-3dc8583a5bb8, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:09,329 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, b735e5d1-e4f9-462e-821d-3dc8583a5bb8, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:14,821 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:24,879 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:24,880 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 76dee2b7-8570-4b70-8af2-52f2bdda4d58, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:38:24,881 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 76dee2b7-8570-4b70-8af2-52f2bdda4d58, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:24,883 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 76dee2b7-8570-4b70-8af2-52f2bdda4d58, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:28,133 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:38,168 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:38,169 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, a8f57c68-6796-43fc-be65-43216d35287b, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:38:38,170 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, a8f57c68-6796-43fc-be65-43216d35287b, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:38,172 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, a8f57c68-6796-43fc-be65-43216d35287b, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:39,061 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:49,066 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:49,067 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, df961ff8-c998-447a-860e-bf9cf15a6f27, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:38:49,068 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, df961ff8-c998-447a-860e-bf9cf15a6f27, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:49,070 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, df961ff8-c998-447a-860e-bf9cf15a6f27, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:49,804 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:49,808 - INFO - root.675348  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-11-21 17:38:49,809 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:49,815 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 1b1822d2-a422-49c8-817f-aa2b52b3392b, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:38:49,816 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 1b1822d2-a422-49c8-817f-aa2b52b3392b, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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:49,830 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 1b1822d2-a422-49c8-817f-aa2b52b3392b, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:49,896 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.675348  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 17:38:49,897 - DEBUG - ydb.connection.675348  - 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:49,918 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.675348  - context_resolve: Resolving endpoints for database /Root
2025-11-21 17:38:49,919 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ListEndpoints, be7122c5-e81c-4a34-a5ff-d51fc85a71d0, localhost:10869): creating call state
2025-11-21 17:38:49,920 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ListEndpoints, be7122c5-e81c-4a34-a5ff-d51fc85a71d0, localhost:10869): request = { database: "/Root" }
2025-11-21 17:38:49,957 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ListEndpoints, be7122c5-e81c-4a34-a5ff-d51fc85a71d0, localhost:10869): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-zme4sewyte.auto.internal" port: 10869 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:49,965 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.675348  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-zme4sewyte.auto.internal:10869, location 1, ssl: False>]>
2025-11-21 17:38:49,966 - DEBUG - ydb.connection.675348  - close: Closing channel for endpoint localhost:10869
2025-11-21 17:38:50,122 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:50,125 - INFO - ya.test.675348  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_18_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 18])
2025-11-21 17:38:50,126 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:50,127 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 1c804996-149b-4a21-b7c4-396c4b711695, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:38:50,128 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 1c804996-149b-4a21-b7c4-396c4b711695, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_18_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 18)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:50,129 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 1c804996-149b-4a21-b7c4-396c4b711695, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:53,986 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:53,987 - INFO - __tests__.alter_compression.675348  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_18_compression created
2025-11-21 17:38:53,988 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:53,988 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 84aa0459-203e-4d2e-9e35-6059326021ab, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:38:53,989 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 84aa0459-203e-4d2e-9e35-6059326021ab, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_18_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:53,992 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 84aa0459-203e-4d2e-9e35-6059326021ab, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:55,178 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:55,179 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:38:55,180 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, ad21ea20-774f-4faa-96bc-bc885d2e4c62, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:38:55,181 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, ad21ea20-774f-4faa-96bc-bc885d2e4c62, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:55,184 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, ad21ea20-774f-4faa-96bc-bc885d2e4c62, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:00,942 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:00,943 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:00,944 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, f71cfc70-2e5c-4692-9895-8ca56c7251ad, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:00,945 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, f71cfc70-2e5c-4692-9895-8ca56c7251ad, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:00,947 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, f71cfc70-2e5c-4692-9895-8ca56c7251ad, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:02,231 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:02,232 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:02,233 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 5b6d832b-c5be-4f10-8887-c201cf0b2297, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:02,234 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 5b6d832b-c5be-4f10-8887-c201cf0b2297, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_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,238 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 5b6d832b-c5be-4f10-8887-c201cf0b2297, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:04,430 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:04,431 - INFO - __tests__.alter_compression.675348  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-11-21 17:39:04,432 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:04,432 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 012b1504-bbbe-45c9-bfa6-84691e449e40, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:04,433 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 012b1504-bbbe-45c9-bfa6-84691e449e40, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:04,435 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 012b1504-bbbe-45c9-bfa6-84691e449e40, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:09,818 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:09,819 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:09,820 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 922ed2ad-b6a4-4602-b1f9-04d6034cdfdc, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:09,821 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 922ed2ad-b6a4-4602-b1f9-04d6034cdfdc, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:09,822 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 922ed2ad-b6a4-4602-b1f9-04d6034cdfdc, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:10,246 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:10,246 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:10,247 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 2438d394-e899-4073-b705-b03766ea709d, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:10,248 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 2438d394-e899-4073-b705-b03766ea709d, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_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:10,251 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 2438d394-e899-4073-b705-b03766ea709d, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:10,861 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:10,862 - INFO - __tests__.alter_compression.675348  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-11-21 17:39:10,862 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:10,866 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, edb82629-b9b5-4a27-afe3-7ede10297694, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:10,867 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, edb82629-b9b5-4a27-afe3-7ede10297694, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:10,869 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, edb82629-b9b5-4a27-afe3-7ede10297694, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:16,391 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:16,392 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:16,393 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, c384f5a0-ed6d-4e97-94c8-1aebda049856, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:16,394 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, c384f5a0-ed6d-4e97-94c8-1aebda049856, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:16,398 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, c384f5a0-ed6d-4e97-94c8-1aebda049856, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:16,770 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:16,771 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:16,772 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 35d948ee-6079-44e4-8163-36c86ac738ec, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:16,773 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 35d948ee-6079-44e4-8163-36c86ac738ec, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_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:16,779 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 35d948ee-6079-44e4-8163-36c86ac738ec, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:17,389 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:17,390 - INFO - __tests__.alter_compression.675348  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-11-21 17:39:17,390 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:17,391 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 87300bde-ef2e-4c31-8f39-309cbedf9ab3, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:17,391 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 87300bde-ef2e-4c31-8f39-309cbedf9ab3, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:17,393 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 87300bde-ef2e-4c31-8f39-309cbedf9ab3, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:22,379 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:22,380 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:22,380 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 17e6f899-1182-4d03-9307-f1fa2849e599, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:22,381 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 17e6f899-1182-4d03-9307-f1fa2849e599, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:22,383 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 17e6f899-1182-4d03-9307-f1fa2849e599, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:22,770 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:22,771 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:22,772 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, f3592e63-fa94-4cd1-bccd-f9fea6178933, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:22,773 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, f3592e63-fa94-4cd1-bccd-f9fea6178933, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_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:22,775 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, f3592e63-fa94-4cd1-bccd-f9fea6178933, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:23,300 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:23,301 - INFO - __tests__.alter_compression.675348  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-11-21 17:39:23,302 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:23,303 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 39a938ee-717a-463b-8471-8801005c32ee, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:23,304 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 39a938ee-717a-463b-8471-8801005c32ee, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:23,309 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 39a938ee-717a-463b-8471-8801005c32ee, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:28,834 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:28,835 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:28,836 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 38c75b16-1f3e-48ab-9e19-c492cfd6f3c6, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:28,837 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 38c75b16-1f3e-48ab-9e19-c492cfd6f3c6, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:28,843 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 38c75b16-1f3e-48ab-9e19-c492cfd6f3c6, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:29,328 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:29,329 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:29,330 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 28b89734-b8fd-4734-b3ce-036c21f83d68, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:29,331 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 28b89734-b8fd-4734-b3ce-036c21f83d68, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_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:29,367 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 28b89734-b8fd-4734-b3ce-036c21f83d68, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:30,121 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:30,122 - INFO - __tests__.alter_compression.675348  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-11-21 17:39:30,123 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:30,124 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 11c0c0dd-96e1-4e26-a2f5-30e3c96b11a9, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:30,124 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 11c0c0dd-96e1-4e26-a2f5-30e3c96b11a9, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:30,139 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 11c0c0dd-96e1-4e26-a2f5-30e3c96b11a9, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:35,931 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:35,931 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:35,932 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 5bb6dbf1-a41b-428c-8dc6-86e041c21663, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:35,933 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 5bb6dbf1-a41b-428c-8dc6-86e041c21663, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:35,937 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 5bb6dbf1-a41b-428c-8dc6-86e041c21663, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:36,378 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:36,379 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:36,380 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 24474d2a-f429-4c6a-b550-72c499a7bb7c, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:36,381 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 24474d2a-f429-4c6a-b550-72c499a7bb7c, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_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:36,383 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 24474d2a-f429-4c6a-b550-72c499a7bb7c, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:37,171 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:37,171 - INFO - __tests__.alter_compression.675348  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-11-21 17:39:37,172 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:37,172 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 0ef2986a-37a2-458e-9d70-c0e0e60dc963, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:37,173 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 0ef2986a-37a2-458e-9d70-c0e0e60dc963, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:37,178 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 0ef2986a-37a2-458e-9d70-c0e0e60dc963, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:43,086 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:43,087 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:43,087 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 4d586100-a3af-4ccf-a0e2-b974ca36f4aa, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:43,088 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 4d586100-a3af-4ccf-a0e2-b974ca36f4aa, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:43,090 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 4d586100-a3af-4ccf-a0e2-b974ca36f4aa, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:43,703 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:43,704 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:43,705 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 773488da-c830-4a8f-af74-b3e39a864da5, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:43,706 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 773488da-c830-4a8f-af74-b3e39a864da5, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_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:43,719 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 773488da-c830-4a8f-af74-b3e39a864da5, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:44,630 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:44,631 - INFO - __tests__.alter_compression.675348  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-11-21 17:39:44,632 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:44,633 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 04524e75-0edc-48bc-a65b-4bc44c5a7c3e, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:44,634 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 04524e75-0edc-48bc-a65b-4bc44c5a7c3e, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:44,636 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 04524e75-0edc-48bc-a65b-4bc44c5a7c3e, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:50,370 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:50,371 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:50,372 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 4b47433b-e28c-404f-9215-7fac11d6e641, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:50,373 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 4b47433b-e28c-404f-9215-7fac11d6e641, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:50,375 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 4b47433b-e28c-404f-9215-7fac11d6e641, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:50,712 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:50,712 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:50,713 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 8ddbba7b-45b8-47fe-bd42-08d663666dde, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:50,714 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 8ddbba7b-45b8-47fe-bd42-08d663666dde, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_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:50,717 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 8ddbba7b-45b8-47fe-bd42-08d663666dde, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:51,348 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:51,349 - INFO - __tests__.alter_compression.675348  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-11-21 17:39:51,349 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:51,350 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, e42738e3-22e9-4c8d-a54d-a34025a5a871, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:51,352 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, e42738e3-22e9-4c8d-a54d-a34025a5a871, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:51,353 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, e42738e3-22e9-4c8d-a54d-a34025a5a871, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:55,748 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:55,749 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:55,750 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, e13b4143-2976-42b4-ba97-bf5acea9abe4, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:55,751 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, e13b4143-2976-42b4-ba97-bf5acea9abe4, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:55,752 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, e13b4143-2976-42b4-ba97-bf5acea9abe4, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:55,999 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:56,000 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:56,001 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, f6ec464e-51d9-4006-9d2b-57841ab86830, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:56,002 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, f6ec464e-51d9-4006-9d2b-57841ab86830, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_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:56,010 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, f6ec464e-51d9-4006-9d2b-57841ab86830, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:56,690 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:56,690 - INFO - __tests__.alter_compression.675348  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-11-21 17:39:56,691 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:39:56,692 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, f0b41a07-e8c2-4290-885c-dac61f20634b, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:39:56,693 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, f0b41a07-e8c2-4290-885c-dac61f20634b, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:56,695 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, f0b41a07-e8c2-4290-885c-dac61f20634b, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:01,026 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:01,027 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:01,028 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 1ed1fbd3-6138-44fe-96fd-4436b983cbec, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:40:01,029 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 1ed1fbd3-6138-44fe-96fd-4436b983cbec, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:01,031 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 1ed1fbd3-6138-44fe-96fd-4436b983cbec, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:01,333 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:01,333 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:01,334 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 7d7aae36-acb2-4ad7-ae9f-73d2a2750c2d, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:40:01,335 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 7d7aae36-acb2-4ad7-ae9f-73d2a2750c2d, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:01,337 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 7d7aae36-acb2-4ad7-ae9f-73d2a2750c2d, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:02,035 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:02,036 - INFO - __tests__.alter_compression.675348  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-11-21 17:40:02,036 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:02,037 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 18ec99f8-49d0-4912-84e7-2a0c808c6c32, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:40:02,039 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 18ec99f8-49d0-4912-84e7-2a0c808c6c32, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_18_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:02,041 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 18ec99f8-49d0-4912-84e7-2a0c808c6c32, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:03,289 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:03,290 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:03,291 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 4a8e2d78-6248-413a-a92a-b8a77b0d42e5, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:40:03,291 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 4a8e2d78-6248-413a-a92a-b8a77b0d42e5, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:03,292 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 4a8e2d78-6248-413a-a92a-b8a77b0d42e5, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:03,638 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:03,639 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:03,640 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 9316b52d-a041-47ab-96a6-ee518a1b078b, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:40:03,641 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 9316b52d-a041-47ab-96a6-ee518a1b078b, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:03,643 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 9316b52d-a041-47ab-96a6-ee518a1b078b, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:04,318 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:04,319 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:04,320 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, cf38dc55-dd49-4faa-869b-bbea6fe4da0e, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:40:04,321 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, cf38dc55-dd49-4faa-869b-bbea6fe4da0e, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:04,323 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, cf38dc55-dd49-4faa-869b-bbea6fe4da0e, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:09,427 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:19,490 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:19,491 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, d358bb94-8bf6-4c63-ae88-ca703acb8913, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:40:19,492 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, d358bb94-8bf6-4c63-ae88-ca703acb8913, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:19,493 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, d358bb94-8bf6-4c63-ae88-ca703acb8913, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:24,928 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:24,978 - INFO - root.675348  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_18_compression`, volumes `value` (8000000, 1595704)
2025-11-21 17:40:24,979 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:24,979 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, 5e612198-ef9f-4ac7-bc30-b41d87e94d05, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:40:24,980 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, 5e612198-ef9f-4ac7-bc30-b41d87e94d05, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:24,984 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, 5e612198-ef9f-4ac7-bc30-b41d87e94d05, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:25,375 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:25,376 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:25,377 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, c9dac467-9791-4af9-a83c-97468cb8dfa6, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:40:25,378 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, c9dac467-9791-4af9-a83c-97468cb8dfa6, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:25,379 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, c9dac467-9791-4af9-a83c-97468cb8dfa6, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:30,847 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:40,894 - DEBUG - ydb.query.pool.675348  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:40,895 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ExecuteQuery, aa6f1f8e-5eee-42c4-b40e-c986da9a580c, ghrun-zme4sewyte.auto.internal:10869): creating call state
2025-11-21 17:40:40,896 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ExecuteQuery, aa6f1f8e-5eee-42c4-b40e-c986da9a580c, ghrun-zme4sewyte.auto.internal:10869): request = { session_id: "ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:40,897 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ExecuteQuery, aa6f1f8e-5eee-42c4-b40e-c986da9a580c, ghrun-zme4sewyte.auto.internal:10869): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:44,326 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.675348  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 17:40:44,327 - DEBUG - ydb.connection.675348  - 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:40:44,359 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.675348  - context_resolve: Resolving endpoints for database /Root
2025-11-21 17:40:44,360 - DEBUG - ydb.connection.675348  - _prepare_call: RpcState(ListEndpoints, 83de6b86-d2f1-4f42-a1e2-df754d192dc1, localhost:10869): creating call state
2025-11-21 17:40:44,360 - DEBUG - ydb.connection.675348  - _log_request: RpcState(ListEndpoints, 83de6b86-d2f1-4f42-a1e2-df754d192dc1, localhost:10869): request = { database: "/Root" }
2025-11-21 17:40:44,371 - DEBUG - ydb.connection.675348  - _log_response: RpcState(ListEndpoints, 83de6b86-d2f1-4f42-a1e2-df754d192dc1, localhost:10869): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-zme4sewyte.auto.internal" port: 10869 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:40:44,372 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.675348  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-zme4sewyte.auto.internal:10869, location 1, ssl: False>]>
2025-11-21 17:40:44,373 - DEBUG - ydb.connection.675348  - close: Closing channel for endpoint localhost:10869
2025-11-21 17:40:46,006 - DEBUG - ydb.query.pool.675348  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDM5NzE1OTMtZDkyNDM4NGEtM2EyNDA4YzMtZDI1MDViYjA=
2025-11-21 17:40:46,091 - INFO - root.675348  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_18_compression`, volumes `value` (8000000, 1595704)
2025-11-21 17:40:46,092 - INFO - root.675348  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_18_compression` 8092160 / 1595704: 5.071216215538722
2025-11-21 17:40:46,094 - INFO - ya.test.675348  - pytest_runtest_teardown: Test teardown
