2025-11-21 17:36:43,109 - INFO - ya.test.671694  - pytest_runtest_setup: ####################################################################################################
2025-11-21 17:36:43,109 - INFO - ya.test.671694  - pytest_runtest_setup: test_all_supported_compression[lz4_compression-COMPRESSION = "lz4"]
2025-11-21 17:36:43,122 - INFO - ya.test.671694  - pytest_runtest_setup: ####################################################################################################
2025-11-21 17:36:43,122 - INFO - ya.test.671694  - pytest_runtest_setup: Test setup
2025-11-21 17:36:43,125 - DEBUG - ya.test.671694  - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-21 17:36:43,131 - DEBUG - ya.test.671694  - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-21 17:36:43,132 - DEBUG - ya.test.671694  - execute: Executing '['/home/runner/.ya/build/build_root/ue5z/0055cf/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-21 17:36:43,150 - DEBUG - ya.test.671694  - execute: Command pid: 672619
2025-11-21 17:36:44,390 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) rc: 0
2025-11-21 17:36:44,390 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) elapsed time (sec): 1.2380855083465576
2025-11-21 17:36:44,391 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) idrss: 0
2025-11-21 17:36:44,391 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) inblock: 0
2025-11-21 17:36:44,391 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) isrss: 0
2025-11-21 17:36:44,391 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) ixrss: 0
2025-11-21 17:36:44,392 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) majflt: 0
2025-11-21 17:36:44,392 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) maxrss: 490844
2025-11-21 17:36:44,392 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) minflt: 45608
2025-11-21 17:36:44,392 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) msgrcv: 0
2025-11-21 17:36:44,393 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) msgsnd: 0
2025-11-21 17:36:44,393 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) nivcsw: 175
2025-11-21 17:36:44,393 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) nsignals: 0
2025-11-21 17:36:44,395 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) nswap: 0
2025-11-21 17:36:44,396 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) nvcsw: 13
2025-11-21 17:36:44,396 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) oublock: 8
2025-11-21 17:36:44,396 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) stime: 0.270329
2025-11-21 17:36:44,397 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) utime: 0.5157419999999999
2025-11-21 17:36:44,397 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) wtime: 1.256
2025-11-21 17:36:44,397 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) output:
Arc info:
    Branch: unknown-vcs-branch
    Commit: THIS_REVISION_IS_A_DUMMY
    Author: ordinal
    Summary: No VCS
    Last Changed Rev: -1
    Last Changed Date: 2015-03-14T06:05:35.000000Z
Other info:
    Build by: runner
    Top src dir: 
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-zme4sewyte    Host information: 
        Linux ghrun-zme4sewyte 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

     
Build info:
    Compiler: /home/runner/.ya/tools/v4/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-21 17:36:44,398 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672619) errors:

2025-11-21 17:36:44,398 - INFO - __tests__.base.671694  - _setup_ydb: Arc info:
    Branch: unknown-vcs-branch
    Commit: THIS_REVISION_IS_A_DUMMY
    Author: ordinal
    Summary: No VCS
    Last Changed Rev: -1
    Last Changed Date: 2015-03-14T06:05:35.000000Z
Other info:
    Build by: runner
    Top src dir: 
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-zme4sewyte    Host information: 
        Linux ghrun-zme4sewyte 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

     
Build info:
    Compiler: /home/runner/.ya/tools/v4/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-21 17:36:44,548 - DEBUG - ya.test.671694  - get_binary: Binary was found by /home/runner/.ya/build/build_root/ue5z/0055cf/ydb/apps/ydbd/ydbd
2025-11-21 17:36:44,550 - DEBUG - library.python.filelock.671694  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16874
2025-11-21 17:36:44,553 - DEBUG - ydb.tests.library.harness.kikimr_runner.671694  - start: Working directory: /home/runner/.ya/build/build_root/ue5z/0055cf/r3tmp/kikimr_cluster_r859h50k
2025-11-21 17:36:44,599 - DEBUG - ya.test.671694  - get_binary: Binary was found by /home/runner/.ya/build/build_root/ue5z/0055cf/ydb/apps/ydbd/ydbd
2025-11-21 17:36:44,600 - DEBUG - library.python.filelock.671694  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21068
2025-11-21 17:36:44,600 - DEBUG - library.python.filelock.671694  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10334
2025-11-21 17:36:44,601 - DEBUG - library.python.filelock.671694  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16433
2025-11-21 17:36:44,602 - DEBUG - library.python.filelock.671694  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17374
2025-11-21 17:36:44,603 - INFO - ydb.tests.library.harness.kikimr_runner.671694  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ue5z/0055cf/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/kikimr_configs"
2025-11-21 17:36:44,604 - INFO - ydb.tests.library.harness.kikimr_runner.671694  - __make_run_command: Final command: /home/runner/.ya/build/build_root/ue5z/0055cf/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/0055cf/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1/logfile_whl6w0k_.log --grpc-port=21068 --mon-port=10334 --ic-port=16874
2025-11-21 17:36:44,604 - DEBUG - ydb.tests.library.harness.kikimr_runner.671694  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:21068/1, disk_size 68719476736
2025-11-21 17:36:44,605 - INFO - ydb.tests.library.harness.kikimr_runner.671694  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ue5z/0055cf/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/kikimr_configs"
2025-11-21 17:36:44,605 - INFO - ydb.tests.library.harness.kikimr_runner.671694  - __make_run_command: Final command: /home/runner/.ya/build/build_root/ue5z/0055cf/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/0055cf/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1/logfile_whl6w0k_.log --grpc-port=21068 --mon-port=10334 --ic-port=16874
2025-11-21 17:36:44,607 - DEBUG - ya.test.671694  - execute: Executing '('/home/runner/.ya/build/build_root/ue5z/0055cf/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/ue5z/0055cf/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/ue5z/0055cf/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1/logfile_whl6w0k_.log', '--grpc-port=21068', '--mon-port=10334', '--ic-port=16874')' in '/home/runner/.ya/build/build_root/ue5z/0055cf/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1' (no wait)
2025-11-21 17:36:44,622 - DEBUG - ya.test.671694  - execute: Command pid: 672751
2025-11-21 17:36:44,623 - INFO - ydb.tests.library.harness.kikimr_runner.671694  - start: Started node localhost:21068/1
2025-11-21 17:36:44,624 - DEBUG - ya.test.671694  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-21 17:36:44,625 - DEBUG - ya.test.671694  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-21 17:36:44,626 - DEBUG - ya.test.671694  - execute: Executing '['/home/runner/.ya/build/build_root/ue5z/0055cf/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-21 17:36:44,638 - DEBUG - ya.test.671694  - execute: Command pid: 672752
2025-11-21 17:36:45,798 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) rc: 0
2025-11-21 17:36:45,799 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) elapsed time (sec): 1.1587672233581543
2025-11-21 17:36:45,799 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) idrss: 0
2025-11-21 17:36:45,799 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) inblock: 0
2025-11-21 17:36:45,800 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) isrss: 0
2025-11-21 17:36:45,800 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) ixrss: 0
2025-11-21 17:36:45,800 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) majflt: 0
2025-11-21 17:36:45,801 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) maxrss: 492696
2025-11-21 17:36:45,801 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) minflt: 45534
2025-11-21 17:36:45,801 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) msgrcv: 0
2025-11-21 17:36:45,804 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) msgsnd: 0
2025-11-21 17:36:45,804 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) nivcsw: 211
2025-11-21 17:36:45,804 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) nsignals: 0
2025-11-21 17:36:45,805 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) nswap: 0
2025-11-21 17:36:45,805 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) nvcsw: 14
2025-11-21 17:36:45,805 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) oublock: 8
2025-11-21 17:36:45,805 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) stime: 0.23066499999999998
2025-11-21 17:36:45,806 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) utime: 0.556993
2025-11-21 17:36:45,806 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) wtime: 1.172
2025-11-21 17:36:45,806 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) output:
Arc info:
    Branch: unknown-vcs-branch
    Commit: THIS_REVISION_IS_A_DUMMY
    Author: ordinal
    Summary: No VCS
    Last Changed Rev: -1
    Last Changed Date: 2015-03-14T06:05:35.000000Z
Other info:
    Build by: runner
    Top src dir: 
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-zme4sewyte    Host information: 
        Linux ghrun-zme4sewyte 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

     
Build info:
    Compiler: /home/runner/.ya/tools/v4/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-21 17:36:45,807 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 672752) errors:

2025-11-21 17:36:45,807 - INFO - ydb.tests.library.harness.kikimr_runner.671694  - start: Node 1 version:
Arc info:
    Branch: unknown-vcs-branch
    Commit: THIS_REVISION_IS_A_DUMMY
    Author: ordinal
    Summary: No VCS
    Last Changed Rev: -1
    Last Changed Date: 2015-03-14T06:05:35.000000Z
Other info:
    Build by: runner
    Top src dir: 
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-zme4sewyte    Host information: 
        Linux ghrun-zme4sewyte 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-11-21 17:36:51,317 - DEBUG - ya.test.671694  - get_binary: Binary was found by /home/runner/.ya/build/build_root/ue5z/0055cf/ydb/apps/ydbd/ydbd
2025-11-21 17:36:51,317 - DEBUG - ydb.tests.library.harness.kikimr_runner.671694  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/ue5z/0055cf/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:21068', '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: 16874\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-21 17:36:51,319 - DEBUG - ya.test.671694  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-21 17:36:51,319 - DEBUG - ya.test.671694  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-21 17:36:51,320 - DEBUG - ya.test.671694  - execute: Executing '['/home/runner/.ya/build/build_root/ue5z/0055cf/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:21068', '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: 16874\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-21 17:36:51,334 - DEBUG - ya.test.671694  - execute: Command pid: 673880
2025-11-21 17:36:52,802 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) rc: 0
2025-11-21 17:36:52,803 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) elapsed time (sec): 1.4672527313232422
2025-11-21 17:36:52,803 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) idrss: 0
2025-11-21 17:36:52,803 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) inblock: 0
2025-11-21 17:36:52,803 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) isrss: 0
2025-11-21 17:36:52,804 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) ixrss: 0
2025-11-21 17:36:52,804 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) majflt: 0
2025-11-21 17:36:52,804 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) maxrss: 588360
2025-11-21 17:36:52,805 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) minflt: 52068
2025-11-21 17:36:52,805 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) msgrcv: 0
2025-11-21 17:36:52,805 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) msgsnd: 0
2025-11-21 17:36:52,805 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) nivcsw: 218
2025-11-21 17:36:52,806 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) nsignals: 0
2025-11-21 17:36:52,807 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) nswap: 0
2025-11-21 17:36:52,807 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) nvcsw: 580
2025-11-21 17:36:52,807 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) oublock: 8
2025-11-21 17:36:52,807 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) stime: 0.33078399999999997
2025-11-21 17:36:52,808 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) utime: 0.607057
2025-11-21 17:36:52,808 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) wtime: 1.482
2025-11-21 17:36:52,808 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-21 17:36:52,809 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 673880) errors:

2025-11-21 17:36:52,815 - DEBUG - ya.test.671694  - get_binary: Binary was found by /home/runner/.ya/build/build_root/ue5z/0055cf/ydb/apps/ydbd/ydbd
2025-11-21 17:36:52,815 - DEBUG - ydb.tests.library.harness.kikimr_runner.671694  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/ue5z/0055cf/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:21068', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-11-21 17:36:52,816 - DEBUG - ya.test.671694  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-21 17:36:52,817 - DEBUG - ya.test.671694  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-21 17:36:52,827 - DEBUG - ya.test.671694  - execute: Executing '['/home/runner/.ya/build/build_root/ue5z/0055cf/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:21068', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-21 17:36:52,831 - DEBUG - ya.test.671694  - execute: Command pid: 674170
2025-11-21 17:36:54,311 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) rc: 0
2025-11-21 17:36:54,311 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) elapsed time (sec): 1.478628396987915
2025-11-21 17:36:54,312 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) idrss: 0
2025-11-21 17:36:54,312 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) inblock: 0
2025-11-21 17:36:54,312 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) isrss: 0
2025-11-21 17:36:54,312 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) ixrss: 0
2025-11-21 17:36:54,312 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) majflt: 0
2025-11-21 17:36:54,313 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) maxrss: 588412
2025-11-21 17:36:54,313 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) minflt: 52094
2025-11-21 17:36:54,313 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) msgrcv: 0
2025-11-21 17:36:54,315 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) msgsnd: 0
2025-11-21 17:36:54,315 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) nivcsw: 231
2025-11-21 17:36:54,316 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) nsignals: 0
2025-11-21 17:36:54,316 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) nswap: 0
2025-11-21 17:36:54,316 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) nvcsw: 417
2025-11-21 17:36:54,316 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) oublock: 8
2025-11-21 17:36:54,317 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) stime: 0.29236599999999996
2025-11-21 17:36:54,317 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) utime: 0.6278039999999999
2025-11-21 17:36:54,317 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) wtime: 1.482
2025-11-21 17:36:54,318 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-21 17:36:54,318 - DEBUG - ya.test.671694  - _save_outputs: Command (pid 674170) errors:

2025-11-21 17:36:54,318 - INFO - ydb.tests.library.harness.kikimr_runner.671694  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-21 17:36:54,485 - INFO - ydb.tests.library.harness.kikimr_runner.671694  - __run: Cluster started and initialized
2025-11-21 17:36:54,811 - DEBUG - ydb.connection.671694  - 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:36:54,815 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.671694  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 17:36:54,815 - DEBUG - ydb.connection.671694  - 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:36:54,853 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.671694  - context_resolve: Resolving endpoints for database /Root
2025-11-21 17:36:54,854 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ListEndpoints, 8365c4bb-ec7c-44b2-8c43-2b4ac22a704b, localhost:21068): creating call state
2025-11-21 17:36:54,854 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ListEndpoints, 8365c4bb-ec7c-44b2-8c43-2b4ac22a704b, localhost:21068): request = { database: "/Root" }
2025-11-21 17:36:54,864 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ListEndpoints, 8365c4bb-ec7c-44b2-8c43-2b4ac22a704b, localhost:21068): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-zme4sewyte.auto.internal" port: 21068 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:36:54,865 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.671694  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-zme4sewyte.auto.internal:21068, location 1, ssl: False>]>
2025-11-21 17:36:54,869 - DEBUG - ydb.connection.671694  - 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:36:54,893 - DEBUG - ydb.connection.671694  - close: Closing channel for endpoint localhost:21068
2025-11-21 17:36:54,895 - DEBUG - ydb.query.pool.671694  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-21 17:36:54,896 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(CreateSession, ebfe293b-ef9b-4867-b13b-15a165497a5e, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:36:54,897 - DEBUG - ydb.connection.671694  - _log_request: RpcState(CreateSession, ebfe293b-ef9b-4867-b13b-15a165497a5e, ghrun-zme4sewyte.auto.internal:21068): request = {  }
2025-11-21 17:36:54,906 - DEBUG - ydb.connection.671694  - _log_response: RpcState(CreateSession, ebfe293b-ef9b-4867-b13b-15a165497a5e, ghrun-zme4sewyte.auto.internal:21068): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" node_id: 1 }
2025-11-21 17:36:54,907 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(AttachSession, a92e7525-3faa-4a79-ae65-f3df30b30ffd, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:36:54,909 - DEBUG - ydb.connection.671694  - _log_request: RpcState(AttachSession, a92e7525-3faa-4a79-ae65-f3df30b30ffd, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" }
2025-11-21 17:36:54,913 - DEBUG - ydb.connection.671694  - _log_response: RpcState(AttachSession, a92e7525-3faa-4a79-ae65-f3df30b30ffd, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:36:54,926 - DEBUG - ydb.query.pool.671694  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:36:54,928 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, da27e3ed-569d-421f-8028-e304f86b5435, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:36:54,929 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, da27e3ed-569d-421f-8028-e304f86b5435, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:36:54,931 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, da27e3ed-569d-421f-8028-e304f86b5435, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:36:58,405 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:36:58,406 - INFO - __tests__.alter_compression.671694  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-21 17:36:58,406 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:36:58,407 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 80b9e1df-5b01-4e71-a6a5-fdc21efd43c3, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:36:58,408 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 80b9e1df-5b01-4e71-a6a5-fdc21efd43c3, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:36:58,409 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 80b9e1df-5b01-4e71-a6a5-fdc21efd43c3, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:36:59,697 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:36:59,698 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:36:59,699 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 1191e0c2-387e-4572-a71f-11a1a0e8320c, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:36:59,700 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 1191e0c2-387e-4572-a71f-11a1a0e8320c, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:36:59,712 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 1191e0c2-387e-4572-a71f-11a1a0e8320c, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:01,406 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:01,407 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:01,408 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 256bb92e-31b5-47eb-aa0d-e21efd8a0a2b, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:01,409 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 256bb92e-31b5-47eb-aa0d-e21efd8a0a2b, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:01,422 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 256bb92e-31b5-47eb-aa0d-e21efd8a0a2b, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:02,593 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:02,595 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:02,595 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 69d04bbb-b2d9-4bd3-8bfb-2e8efe653eca, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:02,596 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 69d04bbb-b2d9-4bd3-8bfb-2e8efe653eca, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:02,599 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 69d04bbb-b2d9-4bd3-8bfb-2e8efe653eca, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:04,382 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:04,383 - INFO - __tests__.alter_compression.671694  - 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:04,384 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:04,385 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 758a01a5-d0f9-4174-83da-2db4ba41eac3, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:04,386 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 758a01a5-d0f9-4174-83da-2db4ba41eac3, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:04,388 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 758a01a5-d0f9-4174-83da-2db4ba41eac3, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:05,528 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:05,529 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:05,529 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, a0ea9d75-08f7-49a6-93fa-c0e938fc6913, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:05,531 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, a0ea9d75-08f7-49a6-93fa-c0e938fc6913, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:05,532 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, a0ea9d75-08f7-49a6-93fa-c0e938fc6913, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:05,850 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:05,851 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:05,852 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 8ec93985-876c-4acb-8b5e-5cac19c39faa, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:05,853 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 8ec93985-876c-4acb-8b5e-5cac19c39faa, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:05,855 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 8ec93985-876c-4acb-8b5e-5cac19c39faa, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:06,249 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:06,250 - INFO - __tests__.alter_compression.671694  - 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:06,251 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:06,251 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 8b18129c-5965-4b60-9431-30d4d7658f91, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:06,252 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 8b18129c-5965-4b60-9431-30d4d7658f91, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:06,255 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 8b18129c-5965-4b60-9431-30d4d7658f91, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:07,458 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:07,459 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:07,460 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 719b0c58-ff6c-4315-937e-7c02d3c4e938, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:07,461 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 719b0c58-ff6c-4315-937e-7c02d3c4e938, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:37:07,462 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 719b0c58-ff6c-4315-937e-7c02d3c4e938, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:07,844 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:07,845 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:07,845 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 6591c016-3e0d-4c99-9862-7b2a4f75dd84, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:07,846 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 6591c016-3e0d-4c99-9862-7b2a4f75dd84, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:07,848 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 6591c016-3e0d-4c99-9862-7b2a4f75dd84, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:08,317 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:08,318 - INFO - __tests__.alter_compression.671694  - 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:08,318 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:08,319 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, cfc58aa1-9b7e-47f0-96d3-bf745f269660, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:08,320 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, cfc58aa1-9b7e-47f0-96d3-bf745f269660, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:08,322 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, cfc58aa1-9b7e-47f0-96d3-bf745f269660, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:09,439 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:09,440 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:09,441 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 8bb717f5-279e-4d3d-93f7-fe31ecf26e74, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:09,442 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 8bb717f5-279e-4d3d-93f7-fe31ecf26e74, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:09,443 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 8bb717f5-279e-4d3d-93f7-fe31ecf26e74, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:09,796 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:09,797 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:09,798 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, a3d470ba-30a4-4897-bc2a-5dd7611dc394, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:09,799 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, a3d470ba-30a4-4897-bc2a-5dd7611dc394, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:09,801 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, a3d470ba-30a4-4897-bc2a-5dd7611dc394, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:10,374 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:10,375 - INFO - __tests__.alter_compression.671694  - 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:10,375 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:10,376 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, f471641a-8dce-4eb3-8ca6-8ef9e7de6b91, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:10,377 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, f471641a-8dce-4eb3-8ca6-8ef9e7de6b91, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:10,387 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, f471641a-8dce-4eb3-8ca6-8ef9e7de6b91, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:11,811 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:11,812 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:11,812 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, d404f32b-a562-44fb-9888-cab607b8aa45, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:11,813 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, d404f32b-a562-44fb-9888-cab607b8aa45, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:37:11,814 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, d404f32b-a562-44fb-9888-cab607b8aa45, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:12,210 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:12,211 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:12,212 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 26f582f5-c062-4401-a4f7-07a2dfb98d61, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:12,212 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 26f582f5-c062-4401-a4f7-07a2dfb98d61, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:12,214 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 26f582f5-c062-4401-a4f7-07a2dfb98d61, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:13,266 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:13,267 - INFO - __tests__.alter_compression.671694  - 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:13,267 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:13,268 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, dd8e8cc3-b019-4e6b-9edf-191c2ed2a364, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:13,269 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, dd8e8cc3-b019-4e6b-9edf-191c2ed2a364, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:13,270 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, dd8e8cc3-b019-4e6b-9edf-191c2ed2a364, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:14,634 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:14,635 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:14,636 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 9db46691-76a4-4a53-b54e-b700648a617d, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:14,637 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 9db46691-76a4-4a53-b54e-b700648a617d, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:14,638 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 9db46691-76a4-4a53-b54e-b700648a617d, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:14,927 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:14,928 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:14,929 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, d26d7a16-a38a-4d2c-953e-9fca038100f9, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:14,930 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, d26d7a16-a38a-4d2c-953e-9fca038100f9, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:14,931 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, d26d7a16-a38a-4d2c-953e-9fca038100f9, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:15,573 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:15,574 - INFO - __tests__.alter_compression.671694  - 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:15,574 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:15,575 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 383ee47b-7ade-48af-90f0-9c1bbcdc7063, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:15,576 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 383ee47b-7ade-48af-90f0-9c1bbcdc7063, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:15,588 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 383ee47b-7ade-48af-90f0-9c1bbcdc7063, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:16,931 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:16,932 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:16,933 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, c84d691f-af29-47c1-bf88-550012382fcd, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:16,934 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, c84d691f-af29-47c1-bf88-550012382fcd, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:16,935 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, c84d691f-af29-47c1-bf88-550012382fcd, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:17,278 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:17,279 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:17,280 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, d8afa9c2-5ad6-400d-af17-2b4816850df0, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:17,281 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, d8afa9c2-5ad6-400d-af17-2b4816850df0, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:17,286 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, d8afa9c2-5ad6-400d-af17-2b4816850df0, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:17,935 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:17,936 - INFO - __tests__.alter_compression.671694  - 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:17,936 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:17,937 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 1057515a-a5a4-4d9f-8221-e315750556ca, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:17,938 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 1057515a-a5a4-4d9f-8221-e315750556ca, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:17,939 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 1057515a-a5a4-4d9f-8221-e315750556ca, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:19,251 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:19,252 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:19,253 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 3166171a-0819-45b8-abcf-92b63630aec6, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:19,254 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 3166171a-0819-45b8-abcf-92b63630aec6, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:19,255 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 3166171a-0819-45b8-abcf-92b63630aec6, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:19,616 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:19,617 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:19,618 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, c6ab6893-bae3-4019-a53f-77569c619574, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:19,618 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, c6ab6893-bae3-4019-a53f-77569c619574, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:37:19,620 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, c6ab6893-bae3-4019-a53f-77569c619574, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:20,424 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:20,425 - INFO - __tests__.alter_compression.671694  - 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:20,425 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:20,426 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, db99c39f-f7fc-4c34-bdc9-6fc68942fb6a, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:20,427 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, db99c39f-f7fc-4c34-bdc9-6fc68942fb6a, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:20,432 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, db99c39f-f7fc-4c34-bdc9-6fc68942fb6a, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:22,026 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:22,027 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:22,028 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, a7a560c7-b329-4560-b892-8eb19cc8180a, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:22,029 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, a7a560c7-b329-4560-b892-8eb19cc8180a, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:22,045 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, a7a560c7-b329-4560-b892-8eb19cc8180a, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:22,565 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:22,566 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:22,567 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 420dad77-0533-4042-afd6-70510b4f7aa7, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:22,567 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 420dad77-0533-4042-afd6-70510b4f7aa7, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:22,569 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 420dad77-0533-4042-afd6-70510b4f7aa7, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:23,310 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:23,311 - INFO - __tests__.alter_compression.671694  - 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:23,311 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:23,312 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 508fdcc8-0094-451b-a47b-89a329ddcbb6, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:23,313 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 508fdcc8-0094-451b-a47b-89a329ddcbb6, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:23,314 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 508fdcc8-0094-451b-a47b-89a329ddcbb6, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:24,840 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:24,840 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:24,841 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, bf6773a8-e9aa-48c8-9ff3-27141ef70f9f, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:24,842 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, bf6773a8-e9aa-48c8-9ff3-27141ef70f9f, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:24,846 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, bf6773a8-e9aa-48c8-9ff3-27141ef70f9f, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:25,148 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:25,148 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:25,149 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 270ca923-383d-463b-b04c-3c820ca57ff7, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:25,150 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 270ca923-383d-463b-b04c-3c820ca57ff7, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:25,152 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 270ca923-383d-463b-b04c-3c820ca57ff7, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:25,937 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:25,938 - INFO - __tests__.alter_compression.671694  - 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:25,939 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:25,939 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, c7aad6e1-c5f8-4508-9e14-346ea4dc1e2b, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:25,940 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, c7aad6e1-c5f8-4508-9e14-346ea4dc1e2b, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:25,942 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, c7aad6e1-c5f8-4508-9e14-346ea4dc1e2b, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:27,363 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:27,364 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:27,365 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, abe82192-87e2-44c8-862f-39ff9b840052, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:27,365 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, abe82192-87e2-44c8-862f-39ff9b840052, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:37:27,374 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, abe82192-87e2-44c8-862f-39ff9b840052, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:27,700 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:27,701 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:27,702 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, e169946d-12ef-4bd6-bdf1-f72cff570bbf, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:27,703 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, e169946d-12ef-4bd6-bdf1-f72cff570bbf, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:37:27,704 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, e169946d-12ef-4bd6-bdf1-f72cff570bbf, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:28,042 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:28,043 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:28,044 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, c66bf05f-da9f-43a6-a066-eefc894ac814, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:28,045 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, c66bf05f-da9f-43a6-a066-eefc894ac814, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:28,046 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, c66bf05f-da9f-43a6-a066-eefc894ac814, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:33,985 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:44,050 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:44,051 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 2c277eec-4fa3-4bd2-87bc-f9843bc4c628, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:44,052 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 2c277eec-4fa3-4bd2-87bc-f9843bc4c628, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:44,055 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 2c277eec-4fa3-4bd2-87bc-f9843bc4c628, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:48,949 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:49,010 - INFO - root.671694  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-21 17:37:49,011 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:37:49,012 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 48db37f1-68de-41c7-b98d-ddeede0a6526, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:37:49,013 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 48db37f1-68de-41c7-b98d-ddeede0a6526, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:37:49,014 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 48db37f1-68de-41c7-b98d-ddeede0a6526, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:37:53,102 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:03,138 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:03,139 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, e2494b48-0adf-4a81-8e3a-c66136dc63f4, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:03,140 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, e2494b48-0adf-4a81-8e3a-c66136dc63f4, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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,141 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, e2494b48-0adf-4a81-8e3a-c66136dc63f4, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:06,101 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:16,152 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:16,153 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, a143c1c2-3eb2-4848-9d19-23db8cb34f08, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:16,154 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, a143c1c2-3eb2-4848-9d19-23db8cb34f08, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:16,156 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, a143c1c2-3eb2-4848-9d19-23db8cb34f08, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:17,547 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:27,553 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:27,555 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, aae0a821-a36e-43b4-90ee-4f484776c5ce, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:27,570 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, aae0a821-a36e-43b4-90ee-4f484776c5ce, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:27,571 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, aae0a821-a36e-43b4-90ee-4f484776c5ce, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:28,965 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:28,969 - INFO - root.671694  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-11-21 17:38:28,971 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:28,971 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 567d440d-01b9-45b5-9cbf-c8049f8cebca, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:28,972 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 567d440d-01b9-45b5-9cbf-c8049f8cebca, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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:28,973 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 567d440d-01b9-45b5-9cbf-c8049f8cebca, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:29,361 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:29,365 - INFO - ya.test.671694  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[lz4_compression-COMPRESSION = "lz4"])
2025-11-21 17:38:29,367 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:29,368 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 2f6aa442-22f1-4110-8993-bb49692c125b, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:29,369 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 2f6aa442-22f1-4110-8993-bb49692c125b, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/lz4_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"lz4\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:29,372 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 2f6aa442-22f1-4110-8993-bb49692c125b, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:33,557 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:33,558 - INFO - __tests__.alter_compression.671694  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/lz4_compression created
2025-11-21 17:38:33,559 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:33,560 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, ba78c5c2-0ff5-4042-a71f-d2af30b1dfa6, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:33,561 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, ba78c5c2-0ff5-4042-a71f-d2af30b1dfa6, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/lz4_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:33,563 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, ba78c5c2-0ff5-4042-a71f-d2af30b1dfa6, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:34,511 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:34,512 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:34,512 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 4e24b658-d8aa-4bf4-9cdb-a0172650d476, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:34,513 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 4e24b658-d8aa-4bf4-9cdb-a0172650d476, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:34,515 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 4e24b658-d8aa-4bf4-9cdb-a0172650d476, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:35,873 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:35,874 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:35,875 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 8394a550-d31a-4f3c-be0c-aab2712d2803, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:35,877 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 8394a550-d31a-4f3c-be0c-aab2712d2803, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:35,878 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 8394a550-d31a-4f3c-be0c-aab2712d2803, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:36,875 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:36,876 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:36,877 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, ef7ec0ae-459a-4661-8e66-d24b8172045d, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:36,878 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, ef7ec0ae-459a-4661-8e66-d24b8172045d, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:36,880 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, ef7ec0ae-459a-4661-8e66-d24b8172045d, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:37,179 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.671694  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-21 17:38:37,180 - DEBUG - ydb.connection.671694  - 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:37,195 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.671694  - context_resolve: Resolving endpoints for database /Root
2025-11-21 17:38:37,196 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ListEndpoints, 4f810115-c378-44da-8705-e4b1dd26244b, localhost:21068): creating call state
2025-11-21 17:38:37,196 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ListEndpoints, 4f810115-c378-44da-8705-e4b1dd26244b, localhost:21068): request = { database: "/Root" }
2025-11-21 17:38:37,204 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ListEndpoints, 4f810115-c378-44da-8705-e4b1dd26244b, localhost:21068): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-zme4sewyte.auto.internal" port: 21068 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:37,215 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.671694  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-zme4sewyte.auto.internal:21068, location 1, ssl: False>]>
2025-11-21 17:38:37,217 - DEBUG - ydb.connection.671694  - close: Closing channel for endpoint localhost:21068
2025-11-21 17:38:38,905 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:38,906 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 872992, 'BlobCount': 128}}
2025-11-21 17:38:38,907 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:38,907 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 454a5aab-c7ac-473f-be9b-4d4bb593bb72, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:38,908 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 454a5aab-c7ac-473f-be9b-4d4bb593bb72, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:38,916 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 454a5aab-c7ac-473f-be9b-4d4bb593bb72, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:40,054 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:40,055 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:40,056 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 7a56593e-0ce1-4eb9-9ba3-b493a98116c3, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:40,059 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 7a56593e-0ce1-4eb9-9ba3-b493a98116c3, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:40,060 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 7a56593e-0ce1-4eb9-9ba3-b493a98116c3, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:40,423 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:40,424 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:40,425 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, e03beac5-2551-4a99-9fa0-2543391fc0c6, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:40,431 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, e03beac5-2551-4a99-9fa0-2543391fc0c6, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:40,433 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, e03beac5-2551-4a99-9fa0-2543391fc0c6, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:40,891 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:40,891 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 1747088, 'BlobCount': 256}}
2025-11-21 17:38:40,892 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:40,893 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 1d1541e5-4baa-473f-88a6-5003b7d00dcd, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:40,894 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 1d1541e5-4baa-473f-88a6-5003b7d00dcd, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:40,896 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 1d1541e5-4baa-473f-88a6-5003b7d00dcd, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:42,161 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:42,163 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:42,164 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 0131271a-3098-4630-99d4-464c9c7192e6, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:42,164 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 0131271a-3098-4630-99d4-464c9c7192e6, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:42,166 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 0131271a-3098-4630-99d4-464c9c7192e6, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:42,511 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:42,512 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:42,512 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, fc60af1c-c779-4bde-8c6e-00a23acff70c, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:42,513 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, fc60af1c-c779-4bde-8c6e-00a23acff70c, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:42,514 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, fc60af1c-c779-4bde-8c6e-00a23acff70c, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:43,099 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:43,100 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 2620992, 'BlobCount': 384}}
2025-11-21 17:38:43,101 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:43,101 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 90f110b6-69cd-44c0-9377-001344c47e05, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:43,103 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 90f110b6-69cd-44c0-9377-001344c47e05, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:43,104 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 90f110b6-69cd-44c0-9377-001344c47e05, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:44,438 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:44,439 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:44,439 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, d7d1d44c-a252-4d31-94ba-7c011db9cc51, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:44,440 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, d7d1d44c-a252-4d31-94ba-7c011db9cc51, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:44,443 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, d7d1d44c-a252-4d31-94ba-7c011db9cc51, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:44,868 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:44,869 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:44,870 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 922a4243-2bfb-4793-a97e-c10e71c8b637, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:44,872 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 922a4243-2bfb-4793-a97e-c10e71c8b637, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:44,874 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 922a4243-2bfb-4793-a97e-c10e71c8b637, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:45,623 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:45,624 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 3495088, 'BlobCount': 512}}
2025-11-21 17:38:45,625 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:45,626 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 920546f1-ddc3-471e-9aeb-afb53043bb09, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:45,627 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 920546f1-ddc3-471e-9aeb-afb53043bb09, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:45,629 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 920546f1-ddc3-471e-9aeb-afb53043bb09, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:46,898 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:46,899 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:46,900 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, c2de2866-22cc-4275-9666-f4a64e9624f2, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:46,901 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, c2de2866-22cc-4275-9666-f4a64e9624f2, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:46,903 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, c2de2866-22cc-4275-9666-f4a64e9624f2, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:47,191 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:47,192 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:47,193 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, db090055-7c3a-4bbe-a36b-663d461254d6, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:47,194 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, db090055-7c3a-4bbe-a36b-663d461254d6, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:47,195 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, db090055-7c3a-4bbe-a36b-663d461254d6, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:47,682 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:47,683 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 4368976, 'BlobCount': 640}}
2025-11-21 17:38:47,684 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:47,685 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 3f120eb0-d531-4729-9dad-54d9f65416cc, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:47,686 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 3f120eb0-d531-4729-9dad-54d9f65416cc, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:47,687 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 3f120eb0-d531-4729-9dad-54d9f65416cc, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:48,920 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:48,921 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:48,921 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 030996fe-75ca-42d7-889d-38804a1071e3, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:48,922 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 030996fe-75ca-42d7-889d-38804a1071e3, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:48,924 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 030996fe-75ca-42d7-889d-38804a1071e3, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:49,210 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:49,211 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:49,212 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 6cc93f61-ec27-4fdd-9834-fecf3242b1f6, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:49,213 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 6cc93f61-ec27-4fdd-9834-fecf3242b1f6, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:49,215 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 6cc93f61-ec27-4fdd-9834-fecf3242b1f6, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:49,732 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:49,733 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 5242960, 'BlobCount': 768}}
2025-11-21 17:38:49,734 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:49,735 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 256cd861-23dc-4afe-b95d-6a23e969500c, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:49,736 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 256cd861-23dc-4afe-b95d-6a23e969500c, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:49,737 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 256cd861-23dc-4afe-b95d-6a23e969500c, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:50,845 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:50,845 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:50,846 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, be8b4a5e-944b-4ebc-baa6-152f9384c7d9, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:50,847 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, be8b4a5e-944b-4ebc-baa6-152f9384c7d9, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:50,848 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, be8b4a5e-944b-4ebc-baa6-152f9384c7d9, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:51,186 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:51,187 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:51,188 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, b409c173-bca0-4696-af04-e20b8be6e82a, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:51,190 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, b409c173-bca0-4696-af04-e20b8be6e82a, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:51,191 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, b409c173-bca0-4696-af04-e20b8be6e82a, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:51,935 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:51,936 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 6117040, 'BlobCount': 896}}
2025-11-21 17:38:51,936 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:51,937 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, ac149340-8e3b-48a0-81e7-a1c0c70aabf7, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:51,939 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, ac149340-8e3b-48a0-81e7-a1c0c70aabf7, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:51,940 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, ac149340-8e3b-48a0-81e7-a1c0c70aabf7, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:53,330 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:53,331 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:53,332 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, ff5f47fc-87db-49e9-956a-b342c319d3cb, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:53,333 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, ff5f47fc-87db-49e9-956a-b342c319d3cb, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:53,335 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, ff5f47fc-87db-49e9-956a-b342c319d3cb, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:53,753 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:53,754 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:53,755 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 2e234cd7-d5da-45cd-a727-07721b5c8458, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:53,756 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 2e234cd7-d5da-45cd-a727-07721b5c8458, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:53,758 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 2e234cd7-d5da-45cd-a727-07721b5c8458, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:54,603 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:54,604 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 6991184, 'BlobCount': 1024}}
2025-11-21 17:38:54,605 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:54,606 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 1e510dc3-caea-49fa-8efc-42e2d5ce6b1a, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:54,607 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 1e510dc3-caea-49fa-8efc-42e2d5ce6b1a, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:54,608 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 1e510dc3-caea-49fa-8efc-42e2d5ce6b1a, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:56,184 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:56,185 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:56,186 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, d68b9c92-5b7c-4feb-9e12-9b82a191a6d2, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:56,187 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, d68b9c92-5b7c-4feb-9e12-9b82a191a6d2, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:56,188 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, d68b9c92-5b7c-4feb-9e12-9b82a191a6d2, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:56,568 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:56,569 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:56,570 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 5bed58d6-cb32-4f56-8c40-8aabdfb614ee, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:56,571 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 5bed58d6-cb32-4f56-8c40-8aabdfb614ee, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:56,573 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 5bed58d6-cb32-4f56-8c40-8aabdfb614ee, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:57,379 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:57,380 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 7865088, 'BlobCount': 1152}}
2025-11-21 17:38:57,380 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:57,381 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 02679bf0-4df2-4800-bd38-94dee4cf44d9, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:57,382 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 02679bf0-4df2-4800-bd38-94dee4cf44d9, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:57,383 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 02679bf0-4df2-4800-bd38-94dee4cf44d9, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:58,896 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:58,897 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:58,897 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 50089613-3a86-4561-86d2-1fc4d6501444, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:58,898 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 50089613-3a86-4561-86d2-1fc4d6501444, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:58,899 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 50089613-3a86-4561-86d2-1fc4d6501444, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:38:59,312 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:59,313 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:38:59,314 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 6c382f76-c222-405e-996f-dcd2b45c2d45, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:38:59,315 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 6c382f76-c222-405e-996f-dcd2b45c2d45, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-21 17:38:59,317 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 6c382f76-c222-405e-996f-dcd2b45c2d45, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:00,313 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:00,314 - INFO - __tests__.alter_compression.671694  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 8739264, 'BlobCount': 1280}}
2025-11-21 17:39:00,315 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:00,315 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 2d7fda3a-ecae-4564-bdff-c734b7fea804, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:39:00,316 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 2d7fda3a-ecae-4564-bdff-c734b7fea804, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/lz4_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:00,317 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 2d7fda3a-ecae-4564-bdff-c734b7fea804, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:01,648 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:01,649 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:01,649 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 18a44ab1-9fdd-41aa-b8aa-3c39d7d4d2b6, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:39:01,650 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 18a44ab1-9fdd-41aa-b8aa-3c39d7d4d2b6, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:01,654 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 18a44ab1-9fdd-41aa-b8aa-3c39d7d4d2b6, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:02,006 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:02,007 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:02,008 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 6b1dda14-4db9-4fac-8a2c-ebf1b7a8726d, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:39:02,010 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 6b1dda14-4db9-4fac-8a2c-ebf1b7a8726d, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:02,012 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 6b1dda14-4db9-4fac-8a2c-ebf1b7a8726d, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:02,348 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:02,348 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:02,349 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, acb8d57b-d83c-4fb4-917c-9730b967a6e6, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:39:02,350 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, acb8d57b-d83c-4fb4-917c-9730b967a6e6, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:02,352 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, acb8d57b-d83c-4fb4-917c-9730b967a6e6, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:06,604 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:16,670 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:16,671 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 527f1548-c650-46d7-9e31-cece3e74701d, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:39:16,672 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 527f1548-c650-46d7-9e31-cece3e74701d, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:16,674 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 527f1548-c650-46d7-9e31-cece3e74701d, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:20,980 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:21,038 - INFO - root.671694  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4369632)
2025-11-21 17:39:21,039 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:21,040 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, b9de97ba-165b-4ce9-bd94-f4ebea4631cd, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:39:21,040 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, b9de97ba-165b-4ce9-bd94-f4ebea4631cd, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" 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/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:21,042 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, b9de97ba-165b-4ce9-bd94-f4ebea4631cd, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:21,438 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:21,439 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:21,440 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, a688100b-af3b-44e7-ae85-96c5d76e0f36, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:39:21,441 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, a688100b-af3b-44e7-ae85-96c5d76e0f36, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:21,445 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, a688100b-af3b-44e7-ae85-96c5d76e0f36, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:25,973 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:36,023 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:36,024 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, b54c0b43-e859-47aa-b550-1b8c98a1b5b8, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:39:36,025 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, b54c0b43-e859-47aa-b550-1b8c98a1b5b8, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:36,027 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, b54c0b43-e859-47aa-b550-1b8c98a1b5b8, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:39,374 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:49,398 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:39:49,398 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, a2d7f453-5085-4fae-ad64-baa2c03662b1, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:39:49,399 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, a2d7f453-5085-4fae-ad64-baa2c03662b1, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:39:49,401 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, a2d7f453-5085-4fae-ad64-baa2c03662b1, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:39:50,238 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:00,246 - DEBUG - ydb.query.pool.671694  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:00,247 - DEBUG - ydb.connection.671694  - _prepare_call: RpcState(ExecuteQuery, 02416d7e-52d8-470c-86d3-b56268c989a3, ghrun-zme4sewyte.auto.internal:21068): creating call state
2025-11-21 17:40:00,248 - DEBUG - ydb.connection.671694  - _log_request: RpcState(ExecuteQuery, 02416d7e-52d8-470c-86d3-b56268c989a3, ghrun-zme4sewyte.auto.internal:21068): request = { session_id: "ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-21 17:40:00,249 - DEBUG - ydb.connection.671694  - _log_response: RpcState(ExecuteQuery, 02416d7e-52d8-470c-86d3-b56268c989a3, ghrun-zme4sewyte.auto.internal:21068): response = { <_MultiThreadedRendezvous object> }
2025-11-21 17:40:01,013 - DEBUG - ydb.query.pool.671694  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTdkNjhlZGYtZTNiNGIxZDMtMWQ3NGY2ZmItODU2MTQxYWU=
2025-11-21 17:40:01,017 - INFO - root.671694  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4258592)
2025-11-21 17:40:01,018 - INFO - root.671694  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/lz4_compression` 8092160 / 4258592: 1.900196121159294
2025-11-21 17:40:01,020 - INFO - ya.test.671694  - pytest_runtest_teardown: Test teardown
