2025-12-23 19:01:38,097 - INFO - ya.test.1663842  - pytest_runtest_setup: ####################################################################################################
2025-12-23 19:01:38,097 - INFO - ya.test.1663842  - pytest_runtest_setup: test_all_supported_compression[lz4_compression-COMPRESSION = "lz4"]
2025-12-23 19:01:38,097 - INFO - ya.test.1663842  - pytest_runtest_setup: ####################################################################################################
2025-12-23 19:01:38,098 - INFO - ya.test.1663842  - pytest_runtest_setup: Test setup
2025-12-23 19:01:38,100 - DEBUG - ya.test.1663842  - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-23 19:01:38,101 - DEBUG - ya.test.1663842  - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-23 19:01:38,101 - DEBUG - ya.test.1663842  - execute: Executing '['/home/runner/.ya/build/build_root/8ptz/004694/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-23 19:01:38,105 - DEBUG - ya.test.1663842  - execute: Command pid: 1664643
2025-12-23 19:01:39,342 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) rc: 0
2025-12-23 19:01:39,342 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) elapsed time (sec): 1.2351317405700684
2025-12-23 19:01:39,343 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) idrss: 0
2025-12-23 19:01:39,343 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) inblock: 0
2025-12-23 19:01:39,343 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) isrss: 0
2025-12-23 19:01:39,344 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) ixrss: 0
2025-12-23 19:01:39,344 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) majflt: 0
2025-12-23 19:01:39,344 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) maxrss: 481132
2025-12-23 19:01:39,345 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) minflt: 45847
2025-12-23 19:01:39,345 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) msgrcv: 0
2025-12-23 19:01:39,345 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) msgsnd: 0
2025-12-23 19:01:39,345 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) nivcsw: 229
2025-12-23 19:01:39,346 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) nsignals: 0
2025-12-23 19:01:39,346 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) nswap: 0
2025-12-23 19:01:39,346 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) nvcsw: 14
2025-12-23 19:01:39,352 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) oublock: 8
2025-12-23 19:01:39,352 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) stime: 0.17284
2025-12-23 19:01:39,352 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) utime: 0.5511159999999999
2025-12-23 19:01:39,353 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) wtime: 1.24
2025-12-23 19:01:39,353 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) output:
Git info:
    Commit: 09729cc9cf0669d9f027468138c7d2dc45a65d8c
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge ff1b65d8e0d4692b69b059de261ca6f72c94cd74 into 7a46c6293674d1de550a9922546b7e0022176cfa
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ou4bsyjxme    Host information: 
        Linux ghrun-ou4bsyjxme 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-12-23 19:01:39,354 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1664643) errors:

2025-12-23 19:01:39,354 - INFO - __tests__.base.1663842  - _setup_ydb: Git info:
    Commit: 09729cc9cf0669d9f027468138c7d2dc45a65d8c
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge ff1b65d8e0d4692b69b059de261ca6f72c94cd74 into 7a46c6293674d1de550a9922546b7e0022176cfa
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ou4bsyjxme    Host information: 
        Linux ghrun-ou4bsyjxme 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-12-23 19:01:39,679 - DEBUG - ya.test.1663842  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8ptz/004694/ydb/apps/ydbd/ydbd
2025-12-23 19:01:39,680 - DEBUG - library.python.filelock.1663842  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7602
2025-12-23 19:01:39,683 - DEBUG - ydb.tests.library.harness.kikimr_runner.1663842  - start: Working directory: /home/runner/.ya/build/build_root/8ptz/004694/r3tmp/kikimr_cluster_zgh1fzmq
2025-12-23 19:01:39,730 - DEBUG - ya.test.1663842  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8ptz/004694/ydb/apps/ydbd/ydbd
2025-12-23 19:01:39,730 - DEBUG - library.python.filelock.1663842  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5219
2025-12-23 19:01:39,731 - DEBUG - library.python.filelock.1663842  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14375
2025-12-23 19:01:39,732 - DEBUG - library.python.filelock.1663842  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17016
2025-12-23 19:01:39,732 - DEBUG - library.python.filelock.1663842  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27649
2025-12-23 19:01:39,733 - INFO - ydb.tests.library.harness.kikimr_runner.1663842  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/8ptz/004694/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-12-23 19:01:39,733 - INFO - ydb.tests.library.harness.kikimr_runner.1663842  - __make_run_command: Final command: /home/runner/.ya/build/build_root/8ptz/004694/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/8ptz/004694/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_c_fj5nl1.log --grpc-port=5219 --mon-port=14375 --ic-port=7602
2025-12-23 19:01:39,734 - DEBUG - ydb.tests.library.harness.kikimr_runner.1663842  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:5219/1, disk_size 68719476736
2025-12-23 19:01:39,735 - INFO - ydb.tests.library.harness.kikimr_runner.1663842  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/8ptz/004694/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-12-23 19:01:39,736 - INFO - ydb.tests.library.harness.kikimr_runner.1663842  - __make_run_command: Final command: /home/runner/.ya/build/build_root/8ptz/004694/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/8ptz/004694/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_c_fj5nl1.log --grpc-port=5219 --mon-port=14375 --ic-port=7602
2025-12-23 19:01:39,737 - DEBUG - ya.test.1663842  - execute: Executing '('/home/runner/.ya/build/build_root/8ptz/004694/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/8ptz/004694/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/8ptz/004694/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_c_fj5nl1.log', '--grpc-port=5219', '--mon-port=14375', '--ic-port=7602')' in '/home/runner/.ya/build/build_root/8ptz/004694/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-12-23 19:01:39,761 - DEBUG - ya.test.1663842  - execute: Command pid: 1665056
2025-12-23 19:01:39,762 - INFO - ydb.tests.library.harness.kikimr_runner.1663842  - start: Started node localhost:5219/1
2025-12-23 19:01:39,763 - DEBUG - ya.test.1663842  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-23 19:01:39,766 - DEBUG - ya.test.1663842  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-23 19:01:39,766 - DEBUG - ya.test.1663842  - execute: Executing '['/home/runner/.ya/build/build_root/8ptz/004694/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-23 19:01:39,782 - DEBUG - ya.test.1663842  - execute: Command pid: 1665059
2025-12-23 19:01:41,254 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) rc: 0
2025-12-23 19:01:41,255 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) elapsed time (sec): 1.471465826034546
2025-12-23 19:01:41,255 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) idrss: 0
2025-12-23 19:01:41,256 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) inblock: 0
2025-12-23 19:01:41,256 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) isrss: 0
2025-12-23 19:01:41,256 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) ixrss: 0
2025-12-23 19:01:41,256 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) majflt: 0
2025-12-23 19:01:41,257 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) maxrss: 492696
2025-12-23 19:01:41,257 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) minflt: 45880
2025-12-23 19:01:41,257 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) msgrcv: 0
2025-12-23 19:01:41,258 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) msgsnd: 0
2025-12-23 19:01:41,258 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) nivcsw: 211
2025-12-23 19:01:41,262 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) nsignals: 0
2025-12-23 19:01:41,262 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) nswap: 0
2025-12-23 19:01:41,262 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) nvcsw: 14
2025-12-23 19:01:41,263 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) oublock: 8
2025-12-23 19:01:41,263 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) stime: 0.20310699999999998
2025-12-23 19:01:41,263 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) utime: 0.5439809999999999
2025-12-23 19:01:41,264 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) wtime: 1.487
2025-12-23 19:01:41,264 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) output:
Git info:
    Commit: 09729cc9cf0669d9f027468138c7d2dc45a65d8c
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge ff1b65d8e0d4692b69b059de261ca6f72c94cd74 into 7a46c6293674d1de550a9922546b7e0022176cfa
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ou4bsyjxme    Host information: 
        Linux ghrun-ou4bsyjxme 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/10492077879/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10492077879/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-12-23 19:01:41,266 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1665059) errors:

2025-12-23 19:01:41,267 - INFO - ydb.tests.library.harness.kikimr_runner.1663842  - start: Node 1 version:
Git info:
    Commit: 09729cc9cf0669d9f027468138c7d2dc45a65d8c
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge ff1b65d8e0d4692b69b059de261ca6f72c94cd74 into 7a46c6293674d1de550a9922546b7e0022176cfa
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ou4bsyjxme    Host information: 
        Linux ghrun-ou4bsyjxme 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-23 19:01:47,404 - DEBUG - ya.test.1663842  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8ptz/004694/ydb/apps/ydbd/ydbd
2025-12-23 19:01:47,404 - DEBUG - ydb.tests.library.harness.kikimr_runner.1663842  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/8ptz/004694/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5219', '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: 7602\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-23 19:01:47,406 - DEBUG - ya.test.1663842  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-23 19:01:47,406 - DEBUG - ya.test.1663842  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-23 19:01:47,407 - DEBUG - ya.test.1663842  - execute: Executing '['/home/runner/.ya/build/build_root/8ptz/004694/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5219', '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: 7602\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-23 19:01:47,427 - DEBUG - ya.test.1663842  - execute: Command pid: 1666240
2025-12-23 19:01:49,255 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) rc: 0
2025-12-23 19:01:49,256 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) elapsed time (sec): 1.8275506496429443
2025-12-23 19:01:49,256 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) idrss: 0
2025-12-23 19:01:49,256 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) inblock: 0
2025-12-23 19:01:49,257 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) isrss: 0
2025-12-23 19:01:49,257 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) ixrss: 0
2025-12-23 19:01:49,257 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) majflt: 0
2025-12-23 19:01:49,257 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) maxrss: 593912
2025-12-23 19:01:49,258 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) minflt: 52288
2025-12-23 19:01:49,258 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) msgrcv: 0
2025-12-23 19:01:49,258 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) msgsnd: 0
2025-12-23 19:01:49,258 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) nivcsw: 249
2025-12-23 19:01:49,259 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) nsignals: 0
2025-12-23 19:01:49,259 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) nswap: 0
2025-12-23 19:01:49,259 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) nvcsw: 458
2025-12-23 19:01:49,260 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) oublock: 8
2025-12-23 19:01:49,260 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) stime: 0.315254
2025-12-23 19:01:49,260 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) utime: 0.582175
2025-12-23 19:01:49,261 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) wtime: 1.847
2025-12-23 19:01:49,261 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-23 19:01:49,261 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666240) errors:

2025-12-23 19:01:49,263 - DEBUG - ya.test.1663842  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8ptz/004694/ydb/apps/ydbd/ydbd
2025-12-23 19:01:49,263 - DEBUG - ydb.tests.library.harness.kikimr_runner.1663842  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/8ptz/004694/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5219', '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-12-23 19:01:49,265 - DEBUG - ya.test.1663842  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-23 19:01:49,265 - DEBUG - ya.test.1663842  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-23 19:01:49,266 - DEBUG - ya.test.1663842  - execute: Executing '['/home/runner/.ya/build/build_root/8ptz/004694/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5219', '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-12-23 19:01:49,289 - DEBUG - ya.test.1663842  - execute: Command pid: 1666728
2025-12-23 19:01:50,842 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) rc: 0
2025-12-23 19:01:50,842 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) elapsed time (sec): 1.5514814853668213
2025-12-23 19:01:50,842 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) idrss: 0
2025-12-23 19:01:50,843 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) inblock: 0
2025-12-23 19:01:50,843 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) isrss: 0
2025-12-23 19:01:50,843 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) ixrss: 0
2025-12-23 19:01:50,844 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) majflt: 0
2025-12-23 19:01:50,844 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) maxrss: 594052
2025-12-23 19:01:50,844 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) minflt: 52304
2025-12-23 19:01:50,844 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) msgrcv: 0
2025-12-23 19:01:50,845 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) msgsnd: 0
2025-12-23 19:01:50,845 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) nivcsw: 240
2025-12-23 19:01:50,845 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) nsignals: 0
2025-12-23 19:01:50,845 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) nswap: 0
2025-12-23 19:01:50,846 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) nvcsw: 694
2025-12-23 19:01:50,846 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) oublock: 8
2025-12-23 19:01:50,847 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) stime: 0.253936
2025-12-23 19:01:50,847 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) utime: 0.564053
2025-12-23 19:01:50,847 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) wtime: 1.575
2025-12-23 19:01:50,848 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-23 19:01:50,848 - DEBUG - ya.test.1663842  - _save_outputs: Command (pid 1666728) errors:

2025-12-23 19:01:50,849 - INFO - ydb.tests.library.harness.kikimr_runner.1663842  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-23 19:01:51,023 - INFO - ydb.tests.library.harness.kikimr_runner.1663842  - __run: Cluster started and initialized
2025-12-23 19:01:51,081 - DEBUG - ydb.connection.1663842  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 19:01:51,092 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1663842  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 19:01:51,093 - DEBUG - ydb.connection.1663842  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 19:01:51,136 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1663842  - context_resolve: Resolving endpoints for database /Root
2025-12-23 19:01:51,137 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ListEndpoints, 7e56360e-18c5-4d8a-99bd-c897549be2a9, localhost:5219): creating call state
2025-12-23 19:01:51,138 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ListEndpoints, 7e56360e-18c5-4d8a-99bd-c897549be2a9, localhost:5219): request = { database: "/Root" }
2025-12-23 19:01:51,154 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ListEndpoints, 7e56360e-18c5-4d8a-99bd-c897549be2a9, localhost:5219): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ou4bsyjxme.auto.internal" port: 5219 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-12-23 19:01:51,155 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1663842  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ou4bsyjxme.auto.internal:5219, location 1, ssl: False>]>
2025-12-23 19:01:51,155 - DEBUG - ydb.connection.1663842  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 19:01:51,183 - DEBUG - ydb.connection.1663842  - close: Closing channel for endpoint localhost:5219
2025-12-23 19:01:51,184 - DEBUG - ydb.query.pool.1663842  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-23 19:01:51,186 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(CreateSession, 5d9c6aa6-40a9-495c-bb47-0b7a2f61cd24, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:01:51,186 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(CreateSession, 5d9c6aa6-40a9-495c-bb47-0b7a2f61cd24, ghrun-ou4bsyjxme.auto.internal:5219): request = {  }
2025-12-23 19:01:51,198 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(CreateSession, 5d9c6aa6-40a9-495c-bb47-0b7a2f61cd24, ghrun-ou4bsyjxme.auto.internal:5219): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" node_id: 1 }
2025-12-23 19:01:51,199 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(AttachSession, d99d4eec-b6ec-460b-81a3-6545990b716c, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:01:51,199 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(AttachSession, d99d4eec-b6ec-460b-81a3-6545990b716c, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" }
2025-12-23 19:01:51,204 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(AttachSession, d99d4eec-b6ec-460b-81a3-6545990b716c, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:51,223 - DEBUG - ydb.query.pool.1663842  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:01:51,224 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 1b9f70c8-196c-419b-a0dd-352d77f5957d, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:01:51,225 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 1b9f70c8-196c-419b-a0dd-352d77f5957d, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:01:51,237 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 1b9f70c8-196c-419b-a0dd-352d77f5957d, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:56,931 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:01:56,931 - INFO - __tests__.alter_compression.1663842  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-12-23 19:01:56,932 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:01:56,933 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 58b8a2a9-d7ee-44d4-aa2a-5703dcea8583, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:01:56,933 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 58b8a2a9-d7ee-44d4-aa2a-5703dcea8583, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:01:56,946 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 58b8a2a9-d7ee-44d4-aa2a-5703dcea8583, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:59,388 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:01:59,389 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:01:59,390 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 382b4236-c222-4441-af0a-de5bd29943ee, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:01:59,392 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 382b4236-c222-4441-af0a-de5bd29943ee, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:01:59,394 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 382b4236-c222-4441-af0a-de5bd29943ee, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:01,418 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:01,419 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:01,420 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, adbb9b90-7f99-4a7a-9b41-81ba720e5abe, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:01,421 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, adbb9b90-7f99-4a7a-9b41-81ba720e5abe, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:01,431 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, adbb9b90-7f99-4a7a-9b41-81ba720e5abe, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:02,930 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:02,931 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:02,932 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, e9cb6e41-124e-43d1-b205-5e40b27681fe, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:02,934 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, e9cb6e41-124e-43d1-b205-5e40b27681fe, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:02,945 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, e9cb6e41-124e-43d1-b205-5e40b27681fe, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:05,431 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:05,432 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:02:05,433 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:05,434 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, c3d12308-5594-442c-a2b5-8a50e8bbe845, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:05,445 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, c3d12308-5594-442c-a2b5-8a50e8bbe845, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:05,447 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, c3d12308-5594-442c-a2b5-8a50e8bbe845, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:06,948 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:06,949 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:06,950 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 527efb0b-744d-49bc-b183-ebd3a3b01859, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:06,951 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 527efb0b-744d-49bc-b183-ebd3a3b01859, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:06,954 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 527efb0b-744d-49bc-b183-ebd3a3b01859, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:07,466 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:07,467 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:07,468 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 1249768d-ae0f-4183-86a9-6eeee3b6dde1, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:07,469 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 1249768d-ae0f-4183-86a9-6eeee3b6dde1, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:07,470 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 1249768d-ae0f-4183-86a9-6eeee3b6dde1, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:08,147 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:08,148 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:02:08,149 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:08,149 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 30156c8b-1cc7-4768-bb2e-4e0c9db4b339, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:08,164 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 30156c8b-1cc7-4768-bb2e-4e0c9db4b339, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:08,166 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 30156c8b-1cc7-4768-bb2e-4e0c9db4b339, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:10,011 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:10,012 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:10,013 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 87694fd6-438e-4201-998f-c5b9e58742f5, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:10,014 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 87694fd6-438e-4201-998f-c5b9e58742f5, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:10,017 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 87694fd6-438e-4201-998f-c5b9e58742f5, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:10,496 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:10,497 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:10,498 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 2e503b4b-5478-4471-a4c0-209b923b76dd, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:10,499 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 2e503b4b-5478-4471-a4c0-209b923b76dd, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:10,501 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 2e503b4b-5478-4471-a4c0-209b923b76dd, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:11,764 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:11,765 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:02:11,765 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:11,768 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, afcf8a15-5a37-4790-95fd-b94d4cf7d7f8, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:11,769 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, afcf8a15-5a37-4790-95fd-b94d4cf7d7f8, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:11,771 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, afcf8a15-5a37-4790-95fd-b94d4cf7d7f8, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:13,442 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:13,443 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:13,444 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, e49b7616-853a-4f34-ae30-1833c91a76c3, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:13,445 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, e49b7616-853a-4f34-ae30-1833c91a76c3, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:13,446 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, e49b7616-853a-4f34-ae30-1833c91a76c3, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:14,147 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:14,148 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:14,149 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 86bf6b0f-a826-40fa-ab82-825db3225a03, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:14,150 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 86bf6b0f-a826-40fa-ab82-825db3225a03, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:14,172 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 86bf6b0f-a826-40fa-ab82-825db3225a03, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:14,829 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:14,830 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:02:14,831 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:14,831 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 370e759a-c431-4e9b-982b-2436d3b56cf8, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:14,833 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 370e759a-c431-4e9b-982b-2436d3b56cf8, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:14,834 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 370e759a-c431-4e9b-982b-2436d3b56cf8, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:16,829 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:16,830 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:16,831 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, d01331ac-3ccd-4604-aaf2-bb9d6dddae9b, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:16,832 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, d01331ac-3ccd-4604-aaf2-bb9d6dddae9b, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:16,836 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, d01331ac-3ccd-4604-aaf2-bb9d6dddae9b, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:17,364 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:17,365 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:17,365 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 3a384111-0a0a-404f-a833-80f9e2d98e39, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:17,366 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 3a384111-0a0a-404f-a833-80f9e2d98e39, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:17,370 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 3a384111-0a0a-404f-a833-80f9e2d98e39, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:18,916 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:18,917 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:02:18,917 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:18,918 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, d38a54c5-cfdb-4464-aa1b-53ad568e9ed7, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:18,928 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, d38a54c5-cfdb-4464-aa1b-53ad568e9ed7, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:18,930 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, d38a54c5-cfdb-4464-aa1b-53ad568e9ed7, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:20,801 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:20,801 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:20,802 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 07e184b3-83a2-46eb-9fc2-530cc6abd712, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:20,803 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 07e184b3-83a2-46eb-9fc2-530cc6abd712, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:20,805 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 07e184b3-83a2-46eb-9fc2-530cc6abd712, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:21,249 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:21,250 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:21,251 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, ab07024b-c58e-4da0-ab09-f45d8b81efda, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:21,252 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, ab07024b-c58e-4da0-ab09-f45d8b81efda, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:21,278 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, ab07024b-c58e-4da0-ab09-f45d8b81efda, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:22,766 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:22,767 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:02:22,767 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:22,768 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, ef42d35d-e66c-4e41-978f-e27e60301d6d, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:22,769 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, ef42d35d-e66c-4e41-978f-e27e60301d6d, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:22,774 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, ef42d35d-e66c-4e41-978f-e27e60301d6d, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:24,905 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:24,906 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:24,906 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 52759f94-93d5-4064-8132-a4469e8bb431, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:24,907 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 52759f94-93d5-4064-8132-a4469e8bb431, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:24,909 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 52759f94-93d5-4064-8132-a4469e8bb431, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:25,380 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:25,380 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:25,381 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 12c5056e-b8b2-429a-8752-93236a191bab, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:25,382 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 12c5056e-b8b2-429a-8752-93236a191bab, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:25,384 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 12c5056e-b8b2-429a-8752-93236a191bab, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:26,431 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:26,432 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:02:26,432 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:26,433 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, b346bc66-7c38-484d-8b18-89ccc53faec0, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:26,434 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, b346bc66-7c38-484d-8b18-89ccc53faec0, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:26,436 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, b346bc66-7c38-484d-8b18-89ccc53faec0, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:28,092 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:28,092 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:28,093 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, c8afd31a-d59f-489b-8512-19b9b49399dc, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:28,094 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, c8afd31a-d59f-489b-8512-19b9b49399dc, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:28,096 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, c8afd31a-d59f-489b-8512-19b9b49399dc, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:28,653 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:28,654 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:28,655 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 48ee2c64-3b08-4516-8951-33ecd0967129, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:28,656 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 48ee2c64-3b08-4516-8951-33ecd0967129, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:28,657 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 48ee2c64-3b08-4516-8951-33ecd0967129, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:29,694 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:29,695 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:02:29,696 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:29,697 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 837c46b0-4dac-4212-a47b-1e9745af4088, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:29,698 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 837c46b0-4dac-4212-a47b-1e9745af4088, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:29,700 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 837c46b0-4dac-4212-a47b-1e9745af4088, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:30,941 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:30,941 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:30,942 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, d794df33-1481-49a8-ad20-70252673f009, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:30,944 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, d794df33-1481-49a8-ad20-70252673f009, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:30,957 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, d794df33-1481-49a8-ad20-70252673f009, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:31,440 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:31,441 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:31,442 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, d0bad705-d7c8-442e-a92d-9db53540658c, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:31,443 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, d0bad705-d7c8-442e-a92d-9db53540658c, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:31,445 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, d0bad705-d7c8-442e-a92d-9db53540658c, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:32,505 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:32,506 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:02:32,506 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:32,507 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 82516703-7a47-45ef-b4c3-9f3e01880011, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:32,508 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 82516703-7a47-45ef-b4c3-9f3e01880011, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:32,523 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 82516703-7a47-45ef-b4c3-9f3e01880011, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:33,876 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:33,877 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:33,878 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 88e318c9-979f-4d81-b9f2-4381f1721ef6, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:33,879 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 88e318c9-979f-4d81-b9f2-4381f1721ef6, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:33,881 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 88e318c9-979f-4d81-b9f2-4381f1721ef6, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:34,469 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:34,470 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:34,471 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 11d8d474-42cd-4137-9cd2-a1762bae6613, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:34,472 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 11d8d474-42cd-4137-9cd2-a1762bae6613, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:34,476 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 11d8d474-42cd-4137-9cd2-a1762bae6613, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:35,478 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:35,479 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:02:35,480 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:35,481 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 1e43cd7b-29ed-46d2-ba66-6101c8e07f87, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:35,482 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 1e43cd7b-29ed-46d2-ba66-6101c8e07f87, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:35,486 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 1e43cd7b-29ed-46d2-ba66-6101c8e07f87, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:37,791 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:37,792 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:37,793 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 9b5fa0ab-c4b3-45fb-803b-04cffcac4a75, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:37,794 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 9b5fa0ab-c4b3-45fb-803b-04cffcac4a75, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:37,796 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 9b5fa0ab-c4b3-45fb-803b-04cffcac4a75, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:38,380 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:38,381 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:38,382 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 22967169-c071-4da8-8fe7-939b4008b834, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:38,383 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 22967169-c071-4da8-8fe7-939b4008b834, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:38,384 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 22967169-c071-4da8-8fe7-939b4008b834, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:38,832 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:38,833 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:38,834 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, b5cf83c1-ebe8-4abd-8370-801dc64a618b, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:38,835 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, b5cf83c1-ebe8-4abd-8370-801dc64a618b, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:38,838 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, b5cf83c1-ebe8-4abd-8370-801dc64a618b, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:45,632 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:55,721 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:02:55,722 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 39bf4e43-ea72-46bd-a2b4-8345af816aef, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:02:55,723 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 39bf4e43-ea72-46bd-a2b4-8345af816aef, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:02:55,724 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 39bf4e43-ea72-46bd-a2b4-8345af816aef, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:59,631 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:09,672 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:09,673 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, a8b5bc58-3fcc-47b2-9cd9-86a7aba0b232, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:09,674 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, a8b5bc58-3fcc-47b2-9cd9-86a7aba0b232, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:09,676 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, a8b5bc58-3fcc-47b2-9cd9-86a7aba0b232, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:10,783 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:20,789 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:20,791 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 632d5692-fa13-404a-a3ab-45b63dc21224, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:20,792 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 632d5692-fa13-404a-a3ab-45b63dc21224, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:20,793 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 632d5692-fa13-404a-a3ab-45b63dc21224, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:21,726 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:21,732 - INFO - root.1663842  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-12-23 19:03:21,733 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:21,733 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 5166c88c-3a79-4580-8bb8-35a2e24f9774, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:21,734 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 5166c88c-3a79-4580-8bb8-35a2e24f9774, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:21,737 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 5166c88c-3a79-4580-8bb8-35a2e24f9774, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:23,097 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:33,102 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:33,103 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, f569f2d0-4341-4e53-bde4-4ddedcf8dbec, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:33,104 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, f569f2d0-4341-4e53-bde4-4ddedcf8dbec, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:33,105 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, f569f2d0-4341-4e53-bde4-4ddedcf8dbec, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:34,019 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:34,024 - INFO - root.1663842  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-12-23 19:03:34,024 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:34,025 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 2d4af336-abfd-4744-bcc8-f1e9fd3ed276, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:34,026 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 2d4af336-abfd-4744-bcc8-f1e9fd3ed276, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:34,035 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 2d4af336-abfd-4744-bcc8-f1e9fd3ed276, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:34,375 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:34,378 - INFO - ya.test.1663842  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[lz4_compression-COMPRESSION = "lz4"])
2025-12-23 19:03:34,383 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:34,384 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 98c9c7b4-92ca-4cd9-b072-874ff6dbdc2d, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:34,385 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 98c9c7b4-92ca-4cd9-b072-874ff6dbdc2d, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:34,386 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 98c9c7b4-92ca-4cd9-b072-874ff6dbdc2d, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:37,888 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:37,889 - INFO - __tests__.alter_compression.1663842  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/lz4_compression created
2025-12-23 19:03:37,890 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:37,890 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 075bc37f-9dd2-47c5-a3cb-5873957e24ba, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:37,891 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 075bc37f-9dd2-47c5-a3cb-5873957e24ba, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:37,892 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 075bc37f-9dd2-47c5-a3cb-5873957e24ba, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:39,136 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:39,137 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:39,138 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 1f68956e-91d4-4f96-8b02-2b8e7f6235b5, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:39,139 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 1f68956e-91d4-4f96-8b02-2b8e7f6235b5, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:39,140 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 1f68956e-91d4-4f96-8b02-2b8e7f6235b5, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:40,360 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:40,360 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:40,361 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 86e80b61-02fb-44e0-9c3a-65427773c143, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:40,362 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 86e80b61-02fb-44e0-9c3a-65427773c143, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:40,363 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 86e80b61-02fb-44e0-9c3a-65427773c143, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:41,654 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:41,655 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:41,656 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, f0858f96-1641-458c-a459-e4a70a703447, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:41,657 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, f0858f96-1641-458c-a459-e4a70a703447, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:41,658 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, f0858f96-1641-458c-a459-e4a70a703447, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:43,963 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:43,964 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:03:43,964 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:43,965 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 2c7defb5-b087-4bb1-998a-c500634cd555, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:43,966 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 2c7defb5-b087-4bb1-998a-c500634cd555, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:43,969 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 2c7defb5-b087-4bb1-998a-c500634cd555, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:45,375 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:45,375 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:45,376 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, ddc33913-42e7-4630-b301-98c3dfe199cc, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:45,377 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, ddc33913-42e7-4630-b301-98c3dfe199cc, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:45,379 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, ddc33913-42e7-4630-b301-98c3dfe199cc, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:45,991 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:45,992 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:45,993 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 582accd1-c358-4a2b-960f-bdcf4010dc6b, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:45,993 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 582accd1-c358-4a2b-960f-bdcf4010dc6b, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:45,999 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 582accd1-c358-4a2b-960f-bdcf4010dc6b, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:46,714 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1663842  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 19:03:46,715 - DEBUG - ydb.connection.1663842  - 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', 10000), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-23 19:03:46,748 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1663842  - context_resolve: Resolving endpoints for database /Root
2025-12-23 19:03:46,749 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ListEndpoints, 8b10508e-2cac-4b44-a071-59e990451a19, localhost:5219): creating call state
2025-12-23 19:03:46,749 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ListEndpoints, 8b10508e-2cac-4b44-a071-59e990451a19, localhost:5219): request = { database: "/Root" }
2025-12-23 19:03:46,795 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ListEndpoints, 8b10508e-2cac-4b44-a071-59e990451a19, localhost:5219): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ou4bsyjxme.auto.internal" port: 5219 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-12-23 19:03:46,796 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1663842  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ou4bsyjxme.auto.internal:5219, location 1, ssl: False>]>
2025-12-23 19:03:46,797 - DEBUG - ydb.connection.1663842  - close: Closing channel for endpoint localhost:5219
2025-12-23 19:03:46,923 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:46,924 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:03:46,924 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:46,925 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 81088795-1cc0-42a0-8eef-56b0a06f4eee, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:46,926 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 81088795-1cc0-42a0-8eef-56b0a06f4eee, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:46,928 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 81088795-1cc0-42a0-8eef-56b0a06f4eee, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:48,414 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:48,415 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:48,416 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 0dd0c717-c646-43be-b219-25d47ae5075b, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:48,417 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 0dd0c717-c646-43be-b219-25d47ae5075b, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:48,418 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 0dd0c717-c646-43be-b219-25d47ae5075b, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:48,760 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:48,761 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:48,761 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, b2f6a744-db50-451c-9118-611f3549bc5e, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:48,762 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, b2f6a744-db50-451c-9118-611f3549bc5e, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:48,766 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, b2f6a744-db50-451c-9118-611f3549bc5e, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:49,467 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:49,468 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:03:49,468 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:49,469 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, d737ec0b-72bc-4e7e-9441-386acbccebb2, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:49,470 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, d737ec0b-72bc-4e7e-9441-386acbccebb2, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:49,478 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, d737ec0b-72bc-4e7e-9441-386acbccebb2, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:50,603 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:50,604 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:50,604 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, f0fe21b0-2e91-4827-8804-d5fe9776a749, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:50,605 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, f0fe21b0-2e91-4827-8804-d5fe9776a749, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:50,608 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, f0fe21b0-2e91-4827-8804-d5fe9776a749, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:51,039 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:51,040 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:51,040 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 052ff6fe-df0c-4fe6-a4e7-c8d751c68446, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:51,041 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 052ff6fe-df0c-4fe6-a4e7-c8d751c68446, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:51,043 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 052ff6fe-df0c-4fe6-a4e7-c8d751c68446, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:51,735 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:51,735 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:03:51,736 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:51,737 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 01be280b-da81-4717-89ea-77d9d1f49b94, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:51,738 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 01be280b-da81-4717-89ea-77d9d1f49b94, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:51,739 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 01be280b-da81-4717-89ea-77d9d1f49b94, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:53,359 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:53,359 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:53,360 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, eed178cb-6941-4a26-b689-d0739bbcc2b5, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:53,361 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, eed178cb-6941-4a26-b689-d0739bbcc2b5, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:53,367 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, eed178cb-6941-4a26-b689-d0739bbcc2b5, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:53,851 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:53,852 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:53,852 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, c7b3fa73-50f0-4f97-91ad-3d1c282c6903, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:53,853 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, c7b3fa73-50f0-4f97-91ad-3d1c282c6903, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:53,856 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, c7b3fa73-50f0-4f97-91ad-3d1c282c6903, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:54,595 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:54,596 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:03:54,597 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:54,597 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 0cdaed3f-9781-45e3-9395-83b52ca6bf02, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:54,598 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 0cdaed3f-9781-45e3-9395-83b52ca6bf02, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:54,600 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 0cdaed3f-9781-45e3-9395-83b52ca6bf02, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:56,095 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:56,096 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:56,097 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 3836895b-a364-4031-9540-f648e51e250c, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:56,097 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 3836895b-a364-4031-9540-f648e51e250c, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:56,107 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 3836895b-a364-4031-9540-f648e51e250c, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:56,546 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:56,547 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:56,547 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, ef159b45-4d22-45ec-b8f8-17f7868ac668, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:56,548 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, ef159b45-4d22-45ec-b8f8-17f7868ac668, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:56,549 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, ef159b45-4d22-45ec-b8f8-17f7868ac668, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:57,551 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:57,552 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:03:57,553 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:57,553 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, c36b7937-318a-44d5-a780-3e640bbe4f00, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:57,560 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, c36b7937-318a-44d5-a780-3e640bbe4f00, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:57,561 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, c36b7937-318a-44d5-a780-3e640bbe4f00, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:58,910 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:58,911 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:58,911 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, bec135b3-073f-4775-b9eb-40d32d9df1d8, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:58,912 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, bec135b3-073f-4775-b9eb-40d32d9df1d8, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:58,914 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, bec135b3-073f-4775-b9eb-40d32d9df1d8, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:59,371 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:59,371 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:03:59,372 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, e6905fc2-4c5a-4203-a045-58ffa7d2c2ab, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:03:59,373 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, e6905fc2-4c5a-4203-a045-58ffa7d2c2ab, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:59,375 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, e6905fc2-4c5a-4203-a045-58ffa7d2c2ab, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:00,486 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:00,486 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:04:00,487 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:00,488 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, a2903c8b-ae83-47fa-bd11-41b19743a4f2, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:00,488 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, a2903c8b-ae83-47fa-bd11-41b19743a4f2, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:00,495 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, a2903c8b-ae83-47fa-bd11-41b19743a4f2, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:01,935 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:01,935 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:01,936 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 82d765e2-c2f9-4b1b-886f-07bb1aacb294, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:01,937 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 82d765e2-c2f9-4b1b-886f-07bb1aacb294, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:01,938 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 82d765e2-c2f9-4b1b-886f-07bb1aacb294, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:02,336 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:02,337 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:02,338 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 6cdc0ed4-eb82-41d2-b8ce-b93cdb007fba, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:02,339 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 6cdc0ed4-eb82-41d2-b8ce-b93cdb007fba, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:02,340 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 6cdc0ed4-eb82-41d2-b8ce-b93cdb007fba, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:03,419 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:03,420 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:04:03,421 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:03,422 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, ef1aa1e3-fb6b-44ac-9e98-7b2e6e4718c8, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:03,423 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, ef1aa1e3-fb6b-44ac-9e98-7b2e6e4718c8, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:03,425 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, ef1aa1e3-fb6b-44ac-9e98-7b2e6e4718c8, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:04,892 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:04,893 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:04,894 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 71bf80fc-1687-4de2-bdc6-1f89128afd33, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:04,895 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 71bf80fc-1687-4de2-bdc6-1f89128afd33, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:04,897 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 71bf80fc-1687-4de2-bdc6-1f89128afd33, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:05,428 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:05,429 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:05,430 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, a9b4732a-9694-4094-873f-391256f7e99b, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:05,436 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, a9b4732a-9694-4094-873f-391256f7e99b, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:05,438 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, a9b4732a-9694-4094-873f-391256f7e99b, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:06,567 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:06,568 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:04:06,569 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:06,569 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, c53563c5-0022-45a1-9545-4b5294b08df0, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:06,574 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, c53563c5-0022-45a1-9545-4b5294b08df0, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:06,576 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, c53563c5-0022-45a1-9545-4b5294b08df0, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:08,076 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:08,077 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:08,078 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, a2fa5f4a-5728-4a81-8b0e-5fdbbf70031b, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:08,079 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, a2fa5f4a-5728-4a81-8b0e-5fdbbf70031b, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:08,082 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, a2fa5f4a-5728-4a81-8b0e-5fdbbf70031b, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:08,539 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:08,540 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:08,541 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, c4717340-4a20-499a-b1fb-a0c408987d32, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:08,542 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, c4717340-4a20-499a-b1fb-a0c408987d32, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:08,548 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, c4717340-4a20-499a-b1fb-a0c408987d32, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:09,457 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:09,458 - INFO - __tests__.alter_compression.1663842  - 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-12-23 19:04:09,458 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:09,459 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, ba69da1d-8ad1-413d-ae22-6b3f069a446c, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:09,460 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, ba69da1d-8ad1-413d-ae22-6b3f069a446c, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:09,470 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, ba69da1d-8ad1-413d-ae22-6b3f069a446c, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:11,432 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:11,433 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:11,433 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, e2c25e2a-67f1-4c63-9d38-9767f91dd6f5, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:11,434 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, e2c25e2a-67f1-4c63-9d38-9767f91dd6f5, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:11,436 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, e2c25e2a-67f1-4c63-9d38-9767f91dd6f5, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:12,142 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:12,143 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:12,143 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 86afc00c-301b-4aa5-a8ca-b8db9612c7c4, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:12,144 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 86afc00c-301b-4aa5-a8ca-b8db9612c7c4, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:12,155 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 86afc00c-301b-4aa5-a8ca-b8db9612c7c4, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:12,655 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:12,656 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:12,656 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 809045d0-e843-41fd-b968-f699bece9d6d, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:12,657 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 809045d0-e843-41fd-b968-f699bece9d6d, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:12,659 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 809045d0-e843-41fd-b968-f699bece9d6d, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:18,358 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:28,415 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:28,416 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 15ae4c5c-6a43-4e08-a0b5-f3ec24002451, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:28,417 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 15ae4c5c-6a43-4e08-a0b5-f3ec24002451, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:28,425 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 15ae4c5c-6a43-4e08-a0b5-f3ec24002451, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:35,000 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:35,127 - INFO - root.1663842  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4369632)
2025-12-23 19:04:35,128 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:35,129 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 24344756-11ac-4348-bd28-b6e5b2e4ffe5, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:35,130 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 24344756-11ac-4348-bd28-b6e5b2e4ffe5, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:35,133 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 24344756-11ac-4348-bd28-b6e5b2e4ffe5, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:35,849 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:35,849 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:35,850 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 07911e61-03db-465b-ac12-aa7eae1e3052, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:35,851 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 07911e61-03db-465b-ac12-aa7eae1e3052, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:35,854 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 07911e61-03db-465b-ac12-aa7eae1e3052, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:42,358 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:52,407 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:04:52,408 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, c91476fa-418f-4808-9526-1b5c458c43bb, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:04:52,409 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, c91476fa-418f-4808-9526-1b5c458c43bb, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:04:52,411 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, c91476fa-418f-4808-9526-1b5c458c43bb, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:53,436 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:05:03,443 - DEBUG - ydb.query.pool.1663842  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:05:03,443 - DEBUG - ydb.connection.1663842  - _prepare_call: RpcState(ExecuteQuery, 8289d689-dd3b-4cd2-b20d-36034d28cf37, ghrun-ou4bsyjxme.auto.internal:5219): creating call state
2025-12-23 19:05:03,444 - DEBUG - ydb.connection.1663842  - _log_request: RpcState(ExecuteQuery, 8289d689-dd3b-4cd2-b20d-36034d28cf37, ghrun-ou4bsyjxme.auto.internal:5219): request = { session_id: "ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:05:03,446 - DEBUG - ydb.connection.1663842  - _log_response: RpcState(ExecuteQuery, 8289d689-dd3b-4cd2-b20d-36034d28cf37, ghrun-ou4bsyjxme.auto.internal:5219): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:04,702 - DEBUG - ydb.query.pool.1663842  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWYyMmNlZGItZTdlZGY4ZjAtZjE5OWY3Y2MtNTM0NjhlZTY=
2025-12-23 19:05:04,707 - INFO - root.1663842  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4258592)
2025-12-23 19:05:04,707 - INFO - root.1663842  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/lz4_compression` 8009216 / 4258592: 1.8807192612018244
2025-12-23 19:05:04,710 - INFO - ya.test.1663842  - pytest_runtest_teardown: Test teardown
