2025-12-23 19:01:16,963 - INFO - ya.test.1657331  - pytest_runtest_setup: ####################################################################################################
2025-12-23 19:01:16,964 - INFO - ya.test.1657331  - pytest_runtest_setup: test_all_supported_compression[zstd_compression-COMPRESSION = "zstd"]
2025-12-23 19:01:16,965 - INFO - ya.test.1657331  - pytest_runtest_setup: ####################################################################################################
2025-12-23 19:01:16,965 - INFO - ya.test.1657331  - pytest_runtest_setup: Test setup
2025-12-23 19:01:16,968 - DEBUG - ya.test.1657331  - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-23 19:01:16,969 - DEBUG - ya.test.1657331  - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-23 19:01:16,971 - DEBUG - ya.test.1657331  - execute: Executing '['/home/runner/.ya/build/build_root/8ptz/0046b5/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-23 19:01:16,980 - DEBUG - ya.test.1657331  - execute: Command pid: 1660872
2025-12-23 19:01:18,485 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) rc: 0
2025-12-23 19:01:18,486 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) elapsed time (sec): 1.5034630298614502
2025-12-23 19:01:18,486 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) idrss: 0
2025-12-23 19:01:18,486 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) inblock: 0
2025-12-23 19:01:18,488 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) isrss: 0
2025-12-23 19:01:18,488 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) ixrss: 0
2025-12-23 19:01:18,488 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) majflt: 0
2025-12-23 19:01:18,489 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) maxrss: 493088
2025-12-23 19:01:18,489 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) minflt: 45810
2025-12-23 19:01:18,489 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) msgrcv: 0
2025-12-23 19:01:18,490 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) msgsnd: 0
2025-12-23 19:01:18,490 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) nivcsw: 235
2025-12-23 19:01:18,490 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) nsignals: 0
2025-12-23 19:01:18,499 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) nswap: 0
2025-12-23 19:01:18,500 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) nvcsw: 14
2025-12-23 19:01:18,500 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) oublock: 8
2025-12-23 19:01:18,500 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) stime: 0.238667
2025-12-23 19:01:18,501 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) utime: 0.558383
2025-12-23 19:01:18,501 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) wtime: 1.514
2025-12-23 19:01:18,501 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) 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:18,502 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1660872) errors:

2025-12-23 19:01:18,502 - INFO - __tests__.base.1657331  - _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:18,864 - DEBUG - ya.test.1657331  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8ptz/0046b5/ydb/apps/ydbd/ydbd
2025-12-23 19:01:18,867 - DEBUG - library.python.filelock.1657331  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25233
2025-12-23 19:01:18,870 - DEBUG - ydb.tests.library.harness.kikimr_runner.1657331  - start: Working directory: /home/runner/.ya/build/build_root/8ptz/0046b5/r3tmp/kikimr_cluster_t_2kuhxt
2025-12-23 19:01:19,027 - DEBUG - ya.test.1657331  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8ptz/0046b5/ydb/apps/ydbd/ydbd
2025-12-23 19:01:19,029 - DEBUG - library.python.filelock.1657331  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18136
2025-12-23 19:01:19,029 - DEBUG - library.python.filelock.1657331  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25766
2025-12-23 19:01:19,030 - DEBUG - library.python.filelock.1657331  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/32744
2025-12-23 19:01:19,035 - DEBUG - library.python.filelock.1657331  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7366
2025-12-23 19:01:19,036 - INFO - ydb.tests.library.harness.kikimr_runner.1657331  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/8ptz/0046b5/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/kikimr_configs"
2025-12-23 19:01:19,036 - INFO - ydb.tests.library.harness.kikimr_runner.1657331  - __make_run_command: Final command: /home/runner/.ya/build/build_root/8ptz/0046b5/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/0046b5/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/node_1/logfile_y_jmf6v5.log --grpc-port=18136 --mon-port=25766 --ic-port=25233
2025-12-23 19:01:19,037 - DEBUG - ydb.tests.library.harness.kikimr_runner.1657331  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:18136/1, disk_size 68719476736
2025-12-23 19:01:19,037 - INFO - ydb.tests.library.harness.kikimr_runner.1657331  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/8ptz/0046b5/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/kikimr_configs"
2025-12-23 19:01:19,052 - INFO - ydb.tests.library.harness.kikimr_runner.1657331  - __make_run_command: Final command: /home/runner/.ya/build/build_root/8ptz/0046b5/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/0046b5/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/node_1/logfile_y_jmf6v5.log --grpc-port=18136 --mon-port=25766 --ic-port=25233
2025-12-23 19:01:19,054 - DEBUG - ya.test.1657331  - execute: Executing '('/home/runner/.ya/build/build_root/8ptz/0046b5/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/8ptz/0046b5/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/8ptz/0046b5/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/node_1/logfile_y_jmf6v5.log', '--grpc-port=18136', '--mon-port=25766', '--ic-port=25233')' in '/home/runner/.ya/build/build_root/8ptz/0046b5/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/node_1' (no wait)
2025-12-23 19:01:19,099 - DEBUG - ya.test.1657331  - execute: Command pid: 1661667
2025-12-23 19:01:19,100 - INFO - ydb.tests.library.harness.kikimr_runner.1657331  - start: Started node localhost:18136/1
2025-12-23 19:01:19,107 - DEBUG - ya.test.1657331  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-23 19:01:19,108 - DEBUG - ya.test.1657331  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-23 19:01:19,109 - DEBUG - ya.test.1657331  - execute: Executing '['/home/runner/.ya/build/build_root/8ptz/0046b5/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-23 19:01:19,123 - DEBUG - ya.test.1657331  - execute: Command pid: 1661694
2025-12-23 19:01:20,595 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) rc: 0
2025-12-23 19:01:20,596 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) elapsed time (sec): 1.4704697132110596
2025-12-23 19:01:20,596 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) idrss: 0
2025-12-23 19:01:20,596 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) inblock: 0
2025-12-23 19:01:20,597 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) isrss: 0
2025-12-23 19:01:20,597 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) ixrss: 0
2025-12-23 19:01:20,597 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) majflt: 0
2025-12-23 19:01:20,597 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) maxrss: 504040
2025-12-23 19:01:20,598 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) minflt: 45936
2025-12-23 19:01:20,598 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) msgrcv: 0
2025-12-23 19:01:20,599 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) msgsnd: 0
2025-12-23 19:01:20,599 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) nivcsw: 209
2025-12-23 19:01:20,599 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) nsignals: 0
2025-12-23 19:01:20,600 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) nswap: 0
2025-12-23 19:01:20,600 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) nvcsw: 14
2025-12-23 19:01:20,600 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) oublock: 8
2025-12-23 19:01:20,600 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) stime: 0.21590199999999998
2025-12-23 19:01:20,601 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) utime: 0.594633
2025-12-23 19:01:20,601 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) wtime: 1.486
2025-12-23 19:01:20,601 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) 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:20,607 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1661694) errors:

2025-12-23 19:01:20,607 - INFO - ydb.tests.library.harness.kikimr_runner.1657331  - 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:26,822 - DEBUG - ya.test.1657331  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8ptz/0046b5/ydb/apps/ydbd/ydbd
2025-12-23 19:01:26,831 - DEBUG - ydb.tests.library.harness.kikimr_runner.1657331  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/8ptz/0046b5/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18136', '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: 25233\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-23 19:01:26,832 - DEBUG - ya.test.1657331  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-23 19:01:26,833 - DEBUG - ya.test.1657331  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-23 19:01:26,834 - DEBUG - ya.test.1657331  - execute: Executing '['/home/runner/.ya/build/build_root/8ptz/0046b5/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18136', '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: 25233\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-23 19:01:26,851 - DEBUG - ya.test.1657331  - execute: Command pid: 1663157
2025-12-23 19:01:28,398 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) rc: 0
2025-12-23 19:01:28,399 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) elapsed time (sec): 1.5460262298583984
2025-12-23 19:01:28,399 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) idrss: 0
2025-12-23 19:01:28,399 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) inblock: 0
2025-12-23 19:01:28,400 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) isrss: 0
2025-12-23 19:01:28,400 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) ixrss: 0
2025-12-23 19:01:28,400 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) majflt: 0
2025-12-23 19:01:28,400 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) maxrss: 595636
2025-12-23 19:01:28,401 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) minflt: 52338
2025-12-23 19:01:28,401 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) msgrcv: 0
2025-12-23 19:01:28,401 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) msgsnd: 0
2025-12-23 19:01:28,401 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) nivcsw: 222
2025-12-23 19:01:28,402 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) nsignals: 0
2025-12-23 19:01:28,402 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) nswap: 0
2025-12-23 19:01:28,403 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) nvcsw: 600
2025-12-23 19:01:28,403 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) oublock: 8
2025-12-23 19:01:28,403 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) stime: 0.319071
2025-12-23 19:01:28,404 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) utime: 0.568237
2025-12-23 19:01:28,404 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) wtime: 1.564
2025-12-23 19:01:28,404 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-23 19:01:28,405 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663157) errors:

2025-12-23 19:01:28,406 - DEBUG - ya.test.1657331  - get_binary: Binary was found by /home/runner/.ya/build/build_root/8ptz/0046b5/ydb/apps/ydbd/ydbd
2025-12-23 19:01:28,415 - DEBUG - ydb.tests.library.harness.kikimr_runner.1657331  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/8ptz/0046b5/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18136', '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:28,417 - DEBUG - ya.test.1657331  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-23 19:01:28,417 - DEBUG - ya.test.1657331  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-23 19:01:28,418 - DEBUG - ya.test.1657331  - execute: Executing '['/home/runner/.ya/build/build_root/8ptz/0046b5/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18136', '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:28,435 - DEBUG - ya.test.1657331  - execute: Command pid: 1663410
2025-12-23 19:01:29,684 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) rc: 0
2025-12-23 19:01:29,685 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) elapsed time (sec): 1.2478673458099365
2025-12-23 19:01:29,685 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) idrss: 0
2025-12-23 19:01:29,686 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) inblock: 0
2025-12-23 19:01:29,686 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) isrss: 0
2025-12-23 19:01:29,686 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) ixrss: 0
2025-12-23 19:01:29,686 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) majflt: 0
2025-12-23 19:01:29,687 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) maxrss: 595636
2025-12-23 19:01:29,687 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) minflt: 52222
2025-12-23 19:01:29,687 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) msgrcv: 0
2025-12-23 19:01:29,687 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) msgsnd: 0
2025-12-23 19:01:29,687 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) nivcsw: 254
2025-12-23 19:01:29,688 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) nsignals: 0
2025-12-23 19:01:29,688 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) nswap: 0
2025-12-23 19:01:29,688 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) nvcsw: 340
2025-12-23 19:01:29,690 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) oublock: 8
2025-12-23 19:01:29,690 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) stime: 0.33543999999999996
2025-12-23 19:01:29,690 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) utime: 0.563256
2025-12-23 19:01:29,690 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) wtime: 1.257
2025-12-23 19:01:29,691 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-23 19:01:29,691 - DEBUG - ya.test.1657331  - _save_outputs: Command (pid 1663410) errors:

2025-12-23 19:01:29,691 - INFO - ydb.tests.library.harness.kikimr_runner.1657331  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-23 19:01:29,899 - INFO - ydb.tests.library.harness.kikimr_runner.1657331  - __run: Cluster started and initialized
2025-12-23 19:01:30,092 - DEBUG - ydb.connection.1657331  - 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:30,096 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1657331  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 19:01:30,097 - DEBUG - ydb.connection.1657331  - 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:30,162 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1657331  - context_resolve: Resolving endpoints for database /Root
2025-12-23 19:01:30,163 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ListEndpoints, 92867735-1c62-4b20-86f4-d852d2840307, localhost:18136): creating call state
2025-12-23 19:01:30,164 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ListEndpoints, 92867735-1c62-4b20-86f4-d852d2840307, localhost:18136): request = { database: "/Root" }
2025-12-23 19:01:30,177 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ListEndpoints, 92867735-1c62-4b20-86f4-d852d2840307, localhost:18136): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ou4bsyjxme.auto.internal" port: 18136 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:30,178 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1657331  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ou4bsyjxme.auto.internal:18136, location 1, ssl: False>]>
2025-12-23 19:01:30,186 - DEBUG - ydb.connection.1657331  - 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:30,223 - DEBUG - ydb.connection.1657331  - close: Closing channel for endpoint localhost:18136
2025-12-23 19:01:30,227 - DEBUG - ydb.query.pool.1657331  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-23 19:01:30,228 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(CreateSession, 3199c12a-eef8-4b76-b487-1cfe6a232b35, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:30,229 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(CreateSession, 3199c12a-eef8-4b76-b487-1cfe6a232b35, ghrun-ou4bsyjxme.auto.internal:18136): request = {  }
2025-12-23 19:01:30,259 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(CreateSession, 3199c12a-eef8-4b76-b487-1cfe6a232b35, ghrun-ou4bsyjxme.auto.internal:18136): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" node_id: 1 }
2025-12-23 19:01:30,260 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(AttachSession, 620c969a-617f-4890-aec9-13fda0be9342, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:30,261 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(AttachSession, 620c969a-617f-4890-aec9-13fda0be9342, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" }
2025-12-23 19:01:30,275 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(AttachSession, 620c969a-617f-4890-aec9-13fda0be9342, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:30,278 - DEBUG - ydb.query.pool.1657331  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:30,279 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 16027803-67f2-4e89-83a7-651858bdaa38, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:30,280 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 16027803-67f2-4e89-83a7-651858bdaa38, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:30,282 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 16027803-67f2-4e89-83a7-651858bdaa38, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:34,529 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:34,529 - INFO - __tests__.alter_compression.1657331  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-12-23 19:01:34,530 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:34,531 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, b2a10f8e-fc51-410c-bd50-26169bbb0066, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:34,532 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, b2a10f8e-fc51-410c-bd50-26169bbb0066, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:34,535 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, b2a10f8e-fc51-410c-bd50-26169bbb0066, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:35,975 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:35,976 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:35,977 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 4708782a-f6ec-4ba5-9a95-696dd34451b0, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:35,978 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 4708782a-f6ec-4ba5-9a95-696dd34451b0, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:35,997 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 4708782a-f6ec-4ba5-9a95-696dd34451b0, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:37,853 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:37,854 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:37,855 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, a093d645-148c-4f52-82e9-a40d7749782c, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:37,856 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, a093d645-148c-4f52-82e9-a40d7749782c, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:37,857 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, a093d645-148c-4f52-82e9-a40d7749782c, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:39,203 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:39,204 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:39,205 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 179ae270-eda5-4173-8bf1-9bcc013965ea, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:39,206 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 179ae270-eda5-4173-8bf1-9bcc013965ea, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:39,208 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 179ae270-eda5-4173-8bf1-9bcc013965ea, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:41,177 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:41,178 - INFO - __tests__.alter_compression.1657331  - 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:01:41,179 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:41,180 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 174279f7-7d81-440a-9aca-bd374e6a8bba, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:41,181 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 174279f7-7d81-440a-9aca-bd374e6a8bba, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:41,200 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 174279f7-7d81-440a-9aca-bd374e6a8bba, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:42,412 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:42,413 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:42,414 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 672feb51-e2a5-43b3-bc20-6da3d662abbf, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:42,415 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 672feb51-e2a5-43b3-bc20-6da3d662abbf, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:42,416 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 672feb51-e2a5-43b3-bc20-6da3d662abbf, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:42,776 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:42,777 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:42,778 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 3067960d-855b-45a2-9551-bf12cca82a91, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:42,779 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 3067960d-855b-45a2-9551-bf12cca82a91, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:42,780 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 3067960d-855b-45a2-9551-bf12cca82a91, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:43,202 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:43,203 - INFO - __tests__.alter_compression.1657331  - 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:01:43,204 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:43,204 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 612ab75e-d786-4132-ad1e-eaae8edf3c12, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:43,205 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 612ab75e-d786-4132-ad1e-eaae8edf3c12, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:43,207 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 612ab75e-d786-4132-ad1e-eaae8edf3c12, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:44,550 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:44,551 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:44,552 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, d14e3343-615f-41cc-a227-fc4fc5ba0ab4, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:44,552 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, d14e3343-615f-41cc-a227-fc4fc5ba0ab4, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:44,554 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, d14e3343-615f-41cc-a227-fc4fc5ba0ab4, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:45,051 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:45,052 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:45,053 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 13f5d143-334e-46be-bab9-cb70a4d439e8, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:45,054 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 13f5d143-334e-46be-bab9-cb70a4d439e8, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:45,056 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 13f5d143-334e-46be-bab9-cb70a4d439e8, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:45,867 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:45,868 - INFO - __tests__.alter_compression.1657331  - 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:01:45,868 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:45,869 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 5fb68a20-e9c5-4623-a169-1bf3af1b5807, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:45,870 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 5fb68a20-e9c5-4623-a169-1bf3af1b5807, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:45,876 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 5fb68a20-e9c5-4623-a169-1bf3af1b5807, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:47,528 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:47,529 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:47,529 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 640da859-89cf-48e9-9ca8-af830a773411, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:47,530 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 640da859-89cf-48e9-9ca8-af830a773411, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:47,532 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 640da859-89cf-48e9-9ca8-af830a773411, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:47,902 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:47,903 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:47,904 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, fa39bc4c-2062-4feb-9561-25de1860a5f0, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:47,905 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, fa39bc4c-2062-4feb-9561-25de1860a5f0, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:47,909 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, fa39bc4c-2062-4feb-9561-25de1860a5f0, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:48,466 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:48,467 - INFO - __tests__.alter_compression.1657331  - 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:01:48,467 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:48,468 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 8d12f3da-9fee-4163-9515-b0244a35116e, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:48,469 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 8d12f3da-9fee-4163-9515-b0244a35116e, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:48,471 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 8d12f3da-9fee-4163-9515-b0244a35116e, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:49,814 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:49,815 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:49,816 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 15915001-38d5-4354-be10-981e4df9cf95, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:49,817 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 15915001-38d5-4354-be10-981e4df9cf95, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:49,820 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 15915001-38d5-4354-be10-981e4df9cf95, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:50,233 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:50,234 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:50,235 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 34dddbbc-0a46-4dd1-9d90-2553d25cad39, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:50,236 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 34dddbbc-0a46-4dd1-9d90-2553d25cad39, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:50,237 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 34dddbbc-0a46-4dd1-9d90-2553d25cad39, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:51,012 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:51,012 - INFO - __tests__.alter_compression.1657331  - 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:01:51,013 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:51,014 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, c5d7cdab-ca04-43a1-9004-b59e8e5968b8, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:51,015 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, c5d7cdab-ca04-43a1-9004-b59e8e5968b8, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:51,016 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, c5d7cdab-ca04-43a1-9004-b59e8e5968b8, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:52,399 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:52,400 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:52,400 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, ef38e581-4821-4bf7-b1f5-d5d5c9ac1799, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:52,401 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, ef38e581-4821-4bf7-b1f5-d5d5c9ac1799, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:52,403 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, ef38e581-4821-4bf7-b1f5-d5d5c9ac1799, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:52,811 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:52,812 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:52,813 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, bec5cc84-982d-4f29-acba-48e65b281119, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:52,814 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, bec5cc84-982d-4f29-acba-48e65b281119, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:52,823 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, bec5cc84-982d-4f29-acba-48e65b281119, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:53,845 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:53,846 - INFO - __tests__.alter_compression.1657331  - 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:01:53,847 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:53,847 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, d54cc721-2bc2-40e9-9a6f-3b677a1a8574, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:53,848 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, d54cc721-2bc2-40e9-9a6f-3b677a1a8574, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:53,852 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, d54cc721-2bc2-40e9-9a6f-3b677a1a8574, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:55,281 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:55,282 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:55,282 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, db7d4740-572f-4fd3-8fe9-9ef242d98b1f, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:55,283 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, db7d4740-572f-4fd3-8fe9-9ef242d98b1f, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:55,285 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, db7d4740-572f-4fd3-8fe9-9ef242d98b1f, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:55,810 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:55,811 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:55,812 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 759ddc49-0f59-43e2-b471-e9c0c3afb7dd, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:55,813 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 759ddc49-0f59-43e2-b471-e9c0c3afb7dd, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:55,816 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 759ddc49-0f59-43e2-b471-e9c0c3afb7dd, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:57,020 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:57,021 - INFO - __tests__.alter_compression.1657331  - 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:01:57,021 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:57,022 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 938b3a73-46af-456d-b1e3-27d224468643, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:57,023 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 938b3a73-46af-456d-b1e3-27d224468643, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:57,027 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 938b3a73-46af-456d-b1e3-27d224468643, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:58,763 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:58,763 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:58,764 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 7444b573-b54f-4581-b8dc-03cdd6a0405d, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:58,765 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 7444b573-b54f-4581-b8dc-03cdd6a0405d, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:58,766 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 7444b573-b54f-4581-b8dc-03cdd6a0405d, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:01:59,356 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:59,357 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:01:59,358 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 3bdf510e-8e10-4c4a-b623-3c76ae0f533e, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:01:59,359 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 3bdf510e-8e10-4c4a-b623-3c76ae0f533e, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:01:59,386 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 3bdf510e-8e10-4c4a-b623-3c76ae0f533e, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:00,688 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:00,689 - INFO - __tests__.alter_compression.1657331  - 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:00,689 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:00,690 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 792bc67a-6880-4c7c-8fe5-d29471b82c2d, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:02:00,691 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 792bc67a-6880-4c7c-8fe5-d29471b82c2d, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:00,693 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 792bc67a-6880-4c7c-8fe5-d29471b82c2d, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:02,178 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:02,179 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:02,179 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 70797458-5648-42d2-9883-b69def526645, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:02:02,180 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 70797458-5648-42d2-9883-b69def526645, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:02,182 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 70797458-5648-42d2-9883-b69def526645, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:02,633 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:02,633 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:02,634 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 7c791e22-8744-4ac8-873b-5834ea130556, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:02:02,635 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 7c791e22-8744-4ac8-873b-5834ea130556, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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,643 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 7c791e22-8744-4ac8-873b-5834ea130556, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:04,166 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:04,167 - INFO - __tests__.alter_compression.1657331  - 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:04,167 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:04,168 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 4b7fe7fe-b96f-4a97-bcdd-526d269eb7e9, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:02:04,169 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 4b7fe7fe-b96f-4a97-bcdd-526d269eb7e9, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:04,173 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 4b7fe7fe-b96f-4a97-bcdd-526d269eb7e9, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:06,016 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:06,017 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:06,018 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 94bdce85-9bfc-41dc-a863-76f04eb129bb, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:02:06,019 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 94bdce85-9bfc-41dc-a863-76f04eb129bb, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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,022 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 94bdce85-9bfc-41dc-a863-76f04eb129bb, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:06,828 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:06,829 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:06,829 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 8cb52194-d8fc-4192-969b-08f93abfe37c, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:02:06,839 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 8cb52194-d8fc-4192-969b-08f93abfe37c, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:06,844 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 8cb52194-d8fc-4192-969b-08f93abfe37c, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:08,655 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:08,656 - INFO - __tests__.alter_compression.1657331  - 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:08,656 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:08,657 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 0eda1a5b-6e8e-4843-9ad7-90175a9f4441, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:02:08,658 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 0eda1a5b-6e8e-4843-9ad7-90175a9f4441, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:08,660 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 0eda1a5b-6e8e-4843-9ad7-90175a9f4441, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:10,502 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:10,503 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:10,504 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, a288adff-c2b0-4395-8325-2018424172d4, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:02:10,505 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, a288adff-c2b0-4395-8325-2018424172d4, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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,520 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, a288adff-c2b0-4395-8325-2018424172d4, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:11,003 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:11,004 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:11,005 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 44725bfb-944b-41c3-afa7-40df77e22742, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:02:11,006 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 44725bfb-944b-41c3-afa7-40df77e22742, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:11,014 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 44725bfb-944b-41c3-afa7-40df77e22742, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:11,510 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:11,511 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:11,512 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 1f65e6de-9a94-42a1-a133-1abb5f654bdc, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:02:11,513 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 1f65e6de-9a94-42a1-a133-1abb5f654bdc, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:11,515 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 1f65e6de-9a94-42a1-a133-1abb5f654bdc, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:20,621 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:30,763 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:30,764 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, d36ef30c-c93f-4fba-831b-baf37d0e8d9e, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:02:30,765 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, d36ef30c-c93f-4fba-831b-baf37d0e8d9e, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:30,767 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, d36ef30c-c93f-4fba-831b-baf37d0e8d9e, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:36,492 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:46,603 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:46,604 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 22d1c1a9-154e-4ab1-8523-4534eca27c98, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:02:46,605 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 22d1c1a9-154e-4ab1-8523-4534eca27c98, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:46,611 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 22d1c1a9-154e-4ab1-8523-4534eca27c98, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:47,787 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:57,793 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:02:57,794 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, d8709539-7cca-4af7-9780-78e74b505988, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:02:57,795 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, d8709539-7cca-4af7-9780-78e74b505988, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:57,796 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, d8709539-7cca-4af7-9780-78e74b505988, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:02:58,610 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:08,618 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:08,619 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 917015ec-f8de-468b-ba0b-d9e7e78c83d0, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:08,620 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 917015ec-f8de-468b-ba0b-d9e7e78c83d0, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:08,622 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 917015ec-f8de-468b-ba0b-d9e7e78c83d0, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:09,329 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:09,333 - INFO - root.1657331  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-12-23 19:03:09,334 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:09,335 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 937fbc56-4477-4c46-8a60-e0fbcc9713da, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:09,336 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 937fbc56-4477-4c46-8a60-e0fbcc9713da, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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,337 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 937fbc56-4477-4c46-8a60-e0fbcc9713da, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:10,133 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:12,527 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1657331  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 19:03:12,527 - DEBUG - ydb.connection.1657331  - 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:12,555 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1657331  - context_resolve: Resolving endpoints for database /Root
2025-12-23 19:03:12,556 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ListEndpoints, a686458f-23a9-41b1-9f4a-f1c8399f3bb4, localhost:18136): creating call state
2025-12-23 19:03:12,556 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ListEndpoints, a686458f-23a9-41b1-9f4a-f1c8399f3bb4, localhost:18136): request = { database: "/Root" }
2025-12-23 19:03:12,566 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ListEndpoints, a686458f-23a9-41b1-9f4a-f1c8399f3bb4, localhost:18136): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ou4bsyjxme.auto.internal" port: 18136 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:12,567 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1657331  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ou4bsyjxme.auto.internal:18136, location 1, ssl: False>]>
2025-12-23 19:03:12,568 - DEBUG - ydb.connection.1657331  - close: Closing channel for endpoint localhost:18136
2025-12-23 19:03:20,143 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:20,144 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, fec22547-6b4b-4da2-8862-f8ae5a31d0dc, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:20,145 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, fec22547-6b4b-4da2-8862-f8ae5a31d0dc, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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,147 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, fec22547-6b4b-4da2-8862-f8ae5a31d0dc, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:20,911 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:20,915 - INFO - root.1657331  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-12-23 19:03:20,916 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:20,917 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 099371c3-2bf5-44c2-900f-72c2524fc5f7, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:20,918 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 099371c3-2bf5-44c2-900f-72c2524fc5f7, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" 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:20,919 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 099371c3-2bf5-44c2-900f-72c2524fc5f7, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:21,217 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:21,221 - INFO - ya.test.1657331  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_compression-COMPRESSION = "zstd"])
2025-12-23 19:03:21,222 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:21,223 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 48966d87-7870-4414-92d9-ef96479b78b6, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:21,224 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 48966d87-7870-4414-92d9-ef96479b78b6, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\")\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:21,232 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 48966d87-7870-4414-92d9-ef96479b78b6, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:24,987 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:24,988 - INFO - __tests__.alter_compression.1657331  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_compression created
2025-12-23 19:03:24,988 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:24,989 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, d49ad3a2-2de1-4200-873c-065881653b7d, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:24,990 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, d49ad3a2-2de1-4200-873c-065881653b7d, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:24,995 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, d49ad3a2-2de1-4200-873c-065881653b7d, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:26,003 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:26,004 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:26,004 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, ad1adb2c-540f-4935-bc2b-30fcaa1486b5, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:26,005 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, ad1adb2c-540f-4935-bc2b-30fcaa1486b5, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_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:26,014 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, ad1adb2c-540f-4935-bc2b-30fcaa1486b5, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:27,276 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:27,276 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:27,277 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 499140ae-b322-45d0-97ca-8ffb2851364b, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:27,278 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 499140ae-b322-45d0-97ca-8ffb2851364b, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_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:27,280 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 499140ae-b322-45d0-97ca-8ffb2851364b, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:28,568 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:28,569 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:28,570 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 5fe7a65b-0f44-4692-ab78-a08b8a524263, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:28,571 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 5fe7a65b-0f44-4692-ab78-a08b8a524263, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_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:28,573 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 5fe7a65b-0f44-4692-ab78-a08b8a524263, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:30,775 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:30,776 - INFO - __tests__.alter_compression.1657331  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318880, 'BlobCount': 128}}
2025-12-23 19:03:30,776 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:30,777 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 4e6cbcce-9b65-4165-a392-73b7c87056e5, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:30,778 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 4e6cbcce-9b65-4165-a392-73b7c87056e5, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_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:30,779 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 4e6cbcce-9b65-4165-a392-73b7c87056e5, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:32,627 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:32,628 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:32,628 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 95eb1887-2cd3-47fc-98f1-09fc6a8b4250, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:32,629 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 95eb1887-2cd3-47fc-98f1-09fc6a8b4250, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_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:32,631 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 95eb1887-2cd3-47fc-98f1-09fc6a8b4250, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:33,018 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:33,019 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:33,020 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 52e2244c-62b4-4d82-bca9-819fccc9c940, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:33,021 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 52e2244c-62b4-4d82-bca9-819fccc9c940, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_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:33,023 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 52e2244c-62b4-4d82-bca9-819fccc9c940, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:33,503 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:33,504 - INFO - __tests__.alter_compression.1657331  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638384, 'BlobCount': 256}}
2025-12-23 19:03:33,505 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:33,505 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 9079eda7-efb9-499a-b587-f36fb6e4e1bf, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:33,507 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 9079eda7-efb9-499a-b587-f36fb6e4e1bf, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_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:33,508 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 9079eda7-efb9-499a-b587-f36fb6e4e1bf, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:34,999 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:35,000 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:35,000 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 4fedab68-60ec-4a9a-bd20-b4f127ef68d6, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:35,001 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 4fedab68-60ec-4a9a-bd20-b4f127ef68d6, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_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:35,004 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 4fedab68-60ec-4a9a-bd20-b4f127ef68d6, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:35,519 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:35,520 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:35,521 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 912e4172-fd2b-47c0-ae79-b4f0ba6779f1, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:35,522 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 912e4172-fd2b-47c0-ae79-b4f0ba6779f1, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_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:35,529 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 912e4172-fd2b-47c0-ae79-b4f0ba6779f1, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:36,065 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:36,066 - INFO - __tests__.alter_compression.1657331  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957552, 'BlobCount': 384}}
2025-12-23 19:03:36,066 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:36,067 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 3caf9054-4bbd-40ba-9e4b-b81cde291ac9, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:36,068 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 3caf9054-4bbd-40ba-9e4b-b81cde291ac9, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_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:36,070 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 3caf9054-4bbd-40ba-9e4b-b81cde291ac9, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:37,876 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:37,877 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:37,878 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 61508228-e9c8-40d7-948e-7d462211a343, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:37,879 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 61508228-e9c8-40d7-948e-7d462211a343, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_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:37,881 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 61508228-e9c8-40d7-948e-7d462211a343, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:38,222 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:38,223 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:38,224 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 7cf8fe68-ab5d-42a5-8d9f-10e54c907cd8, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:38,225 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 7cf8fe68-ab5d-42a5-8d9f-10e54c907cd8, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_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:38,227 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 7cf8fe68-ab5d-42a5-8d9f-10e54c907cd8, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:38,957 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:38,958 - INFO - __tests__.alter_compression.1657331  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1277056, 'BlobCount': 512}}
2025-12-23 19:03:38,959 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:38,959 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 7ae8d926-3ae2-40d3-9925-247722d14140, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:38,961 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 7ae8d926-3ae2-40d3-9925-247722d14140, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_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:38,963 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 7ae8d926-3ae2-40d3-9925-247722d14140, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:40,347 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:40,348 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:40,348 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 00eaa694-5f0f-408b-9da7-fef295d55ac9, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:40,349 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 00eaa694-5f0f-408b-9da7-fef295d55ac9, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_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,360 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 00eaa694-5f0f-408b-9da7-fef295d55ac9, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:40,712 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:40,713 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:40,714 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 4c4b4662-9637-4621-b617-daf7cec0059c, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:40,715 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 4c4b4662-9637-4621-b617-daf7cec0059c, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_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:40,717 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 4c4b4662-9637-4621-b617-daf7cec0059c, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:41,406 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:41,407 - INFO - __tests__.alter_compression.1657331  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1596256, 'BlobCount': 640}}
2025-12-23 19:03:41,407 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:41,408 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, b3cd3012-5c28-4d07-9956-d5d5081e1233, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:41,409 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, b3cd3012-5c28-4d07-9956-d5d5081e1233, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_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:41,411 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, b3cd3012-5c28-4d07-9956-d5d5081e1233, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:42,796 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:42,797 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:42,797 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, c4f7dd4a-4038-46e0-b0de-79f5404526fe, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:42,798 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, c4f7dd4a-4038-46e0-b0de-79f5404526fe, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_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:42,800 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, c4f7dd4a-4038-46e0-b0de-79f5404526fe, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:43,277 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:43,278 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:43,279 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 57bb6207-7b22-43fc-9f58-03ff59441801, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:43,280 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 57bb6207-7b22-43fc-9f58-03ff59441801, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_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:43,282 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 57bb6207-7b22-43fc-9f58-03ff59441801, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:43,924 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:43,925 - INFO - __tests__.alter_compression.1657331  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1915600, 'BlobCount': 768}}
2025-12-23 19:03:43,925 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:43,926 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 3fabb71d-2a4b-4062-ac0f-e06617b61d78, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:43,929 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 3fabb71d-2a4b-4062-ac0f-e06617b61d78, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_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,931 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 3fabb71d-2a4b-4062-ac0f-e06617b61d78, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:45,239 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:45,240 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:45,240 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 648a9383-f0c2-4179-a0e5-6f28bc1bb720, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:45,241 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 648a9383-f0c2-4179-a0e5-6f28bc1bb720, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_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,243 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 648a9383-f0c2-4179-a0e5-6f28bc1bb720, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:45,763 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:45,764 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:45,765 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 72f77a6f-8d64-481f-83a3-d9aab9b9ab64, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:45,766 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 72f77a6f-8d64-481f-83a3-d9aab9b9ab64, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_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,767 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 72f77a6f-8d64-481f-83a3-d9aab9b9ab64, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:46,820 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:46,820 - INFO - __tests__.alter_compression.1657331  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234896, 'BlobCount': 896}}
2025-12-23 19:03:46,821 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:46,822 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 796dc85b-3212-4191-840e-cb77b804230e, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:46,823 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 796dc85b-3212-4191-840e-cb77b804230e, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_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,824 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 796dc85b-3212-4191-840e-cb77b804230e, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:48,304 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:48,304 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:48,305 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, ad872fb9-1a8b-4d6b-a3c4-ae69b0ed5dd1, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:48,306 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, ad872fb9-1a8b-4d6b-a3c4-ae69b0ed5dd1, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_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,309 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, ad872fb9-1a8b-4d6b-a3c4-ae69b0ed5dd1, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:48,792 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:48,793 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:48,794 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 19d62d75-4f2d-4e5c-bc2a-fbad7f9857d7, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:48,795 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 19d62d75-4f2d-4e5c-bc2a-fbad7f9857d7, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_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,797 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 19d62d75-4f2d-4e5c-bc2a-fbad7f9857d7, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:49,865 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:49,866 - INFO - __tests__.alter_compression.1657331  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2554464, 'BlobCount': 1024}}
2025-12-23 19:03:49,866 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:49,867 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 8ac6f35b-3d8b-426e-86d7-c7dfbef24a24, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:49,868 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 8ac6f35b-3d8b-426e-86d7-c7dfbef24a24, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_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,887 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 8ac6f35b-3d8b-426e-86d7-c7dfbef24a24, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:51,164 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:51,165 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:51,165 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, b7072bbf-9574-43cf-a8f2-d1eb8f19759b, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:51,167 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, b7072bbf-9574-43cf-a8f2-d1eb8f19759b, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_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:51,181 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, b7072bbf-9574-43cf-a8f2-d1eb8f19759b, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:51,826 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:51,827 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:51,828 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, e402547e-8725-4692-b849-20f2e1bbd2f3, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:51,829 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, e402547e-8725-4692-b849-20f2e1bbd2f3, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_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,830 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, e402547e-8725-4692-b849-20f2e1bbd2f3, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:53,096 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:53,097 - INFO - __tests__.alter_compression.1657331  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2873568, 'BlobCount': 1152}}
2025-12-23 19:03:53,098 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:53,098 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 4988c6b5-a823-4357-a0e3-267b89e7c3af, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:53,099 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 4988c6b5-a823-4357-a0e3-267b89e7c3af, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_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:53,102 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 4988c6b5-a823-4357-a0e3-267b89e7c3af, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:54,747 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:54,748 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:54,749 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, a6c04758-9ba0-4fb2-9e46-b94de8923379, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:54,750 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, a6c04758-9ba0-4fb2-9e46-b94de8923379, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_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:54,751 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, a6c04758-9ba0-4fb2-9e46-b94de8923379, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:55,306 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:55,307 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:55,308 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 45c13377-8cd9-4bbb-89c4-fbf810485f4b, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:55,309 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 45c13377-8cd9-4bbb-89c4-fbf810485f4b, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_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:55,314 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 45c13377-8cd9-4bbb-89c4-fbf810485f4b, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:56,444 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:56,445 - INFO - __tests__.alter_compression.1657331  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3193216, 'BlobCount': 1280}}
2025-12-23 19:03:56,446 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:56,447 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 13bacf24-0ed8-426b-828e-d0e588c6be44, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:56,448 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 13bacf24-0ed8-426b-828e-d0e588c6be44, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-23 19:03:56,450 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 13bacf24-0ed8-426b-828e-d0e588c6be44, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:58,268 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:58,268 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:58,269 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 9145ada6-8436-49db-a5eb-5cb971b71bd1, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:58,271 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 9145ada6-8436-49db-a5eb-5cb971b71bd1, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_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,273 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 9145ada6-8436-49db-a5eb-5cb971b71bd1, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:58,772 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:58,773 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:58,774 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 1c42f7ab-2ca8-4b4e-ad30-23dfa3b88dce, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:58,775 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 1c42f7ab-2ca8-4b4e-ad30-23dfa3b88dce, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_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,780 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 1c42f7ab-2ca8-4b4e-ad30-23dfa3b88dce, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:03:59,330 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:59,331 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:03:59,332 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 4b904015-0ea4-43ed-858a-d21d5fd069df, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:03:59,334 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 4b904015-0ea4-43ed-858a-d21d5fd069df, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_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:59,335 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 4b904015-0ea4-43ed-858a-d21d5fd069df, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:05,126 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:04:15,166 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:04:15,167 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 5dd3e23e-7ba5-4cb3-b391-2bd1967d04b5, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:04:15,168 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 5dd3e23e-7ba5-4cb3-b391-2bd1967d04b5, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_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:15,169 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 5dd3e23e-7ba5-4cb3-b391-2bd1967d04b5, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:21,692 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:04:21,763 - INFO - root.1657331  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_compression`, volumes `value` (8000000, 1596608)
2025-12-23 19:04:21,764 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:04:21,765 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 5983e061-496a-4d04-9134-699d730fc80e, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:04:21,765 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 5983e061-496a-4d04-9134-699d730fc80e, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_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:21,767 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 5983e061-496a-4d04-9134-699d730fc80e, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:22,299 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:04:22,300 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:04:22,300 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, fa1e4281-4ed1-4826-aa6b-a19fe5650ff3, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:04:22,301 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, fa1e4281-4ed1-4826-aa6b-a19fe5650ff3, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_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:22,307 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, fa1e4281-4ed1-4826-aa6b-a19fe5650ff3, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:23,533 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1657331  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-23 19:04:23,534 - DEBUG - ydb.connection.1657331  - 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:04:23,563 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1657331  - context_resolve: Resolving endpoints for database /Root
2025-12-23 19:04:23,564 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ListEndpoints, a2b17cd6-a0d9-4ee0-bb4a-47538a069660, localhost:18136): creating call state
2025-12-23 19:04:23,564 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ListEndpoints, a2b17cd6-a0d9-4ee0-bb4a-47538a069660, localhost:18136): request = { database: "/Root" }
2025-12-23 19:04:23,574 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ListEndpoints, a2b17cd6-a0d9-4ee0-bb4a-47538a069660, localhost:18136): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ou4bsyjxme.auto.internal" port: 18136 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:04:23,575 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1657331  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ou4bsyjxme.auto.internal:18136, location 1, ssl: False>]>
2025-12-23 19:04:23,576 - DEBUG - ydb.connection.1657331  - close: Closing channel for endpoint localhost:18136
2025-12-23 19:04:28,432 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:04:38,510 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:04:38,511 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, b6a338bc-089f-4431-9787-69f963ad80ef, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:04:38,512 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, b6a338bc-089f-4431-9787-69f963ad80ef, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_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:38,514 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, b6a338bc-089f-4431-9787-69f963ad80ef, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:40,444 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:04:50,451 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:04:50,452 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, 7ef5a4da-f419-49f8-a8f4-bee40a7353fd, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:04:50,453 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, 7ef5a4da-f419-49f8-a8f4-bee40a7353fd, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_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:50,456 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, 7ef5a4da-f419-49f8-a8f4-bee40a7353fd, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:04:52,704 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:05:02,711 - DEBUG - ydb.query.pool.1657331  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:05:02,712 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(ExecuteQuery, ae89e929-258f-4eb2-b60d-7bfaa6f0fab3, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:05:02,713 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(ExecuteQuery, ae89e929-258f-4eb2-b60d-7bfaa6f0fab3, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_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:02,714 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(ExecuteQuery, ae89e929-258f-4eb2-b60d-7bfaa6f0fab3, ghrun-ou4bsyjxme.auto.internal:18136): response = { <_MultiThreadedRendezvous object> }
2025-12-23 19:05:04,114 - DEBUG - ydb.query.pool.1657331  - release: Session returned to queue: ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=
2025-12-23 19:05:04,120 - INFO - root.1657331  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_compression`, volumes `value` (8000000, 1465272)
2025-12-23 19:05:04,120 - INFO - root.1657331  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_compression` 8009216 / 1465272: 5.4660267854705475
2025-12-23 19:05:04,123 - INFO - ya.test.1657331  - pytest_runtest_teardown: Test teardown
2025-12-23 19:05:04,125 - DEBUG - ydb.connection.1657331  - _prepare_call: RpcState(DeleteSession, f5f604b7-ff46-4ff4-aacc-3862d88f710a, ghrun-ou4bsyjxme.auto.internal:18136): creating call state
2025-12-23 19:05:04,125 - DEBUG - ydb.connection.1657331  - _log_request: RpcState(DeleteSession, f5f604b7-ff46-4ff4-aacc-3862d88f710a, ghrun-ou4bsyjxme.auto.internal:18136): request = { session_id: "ydb://session/3?node_id=1&id=NjI5ZjZhOTYtODA0MDJiZGEtN2VlZGU3NmEtNDMyY2VlODE=" }
2025-12-23 19:05:04,132 - DEBUG - ydb.connection.1657331  - _log_response: RpcState(DeleteSession, f5f604b7-ff46-4ff4-aacc-3862d88f710a, ghrun-ou4bsyjxme.auto.internal:18136): response = { status: SUCCESS }
2025-12-23 19:05:04,134 - DEBUG - ydb.query.pool.1657331  - stop: All session were deleted.
2025-12-23 19:05:04,134 - DEBUG - ydb.topic.1657331  - close: Close topic client
2025-12-23 19:05:04,135 - DEBUG - ydb.topic.1657331  - close: Topic client was closed
2025-12-23 19:05:04,135 - DEBUG - ydb.connection.1657331  - close: Closing channel for endpoint localhost:18136
2025-12-23 19:05:04,139 - DEBUG - ydb.connection.1657331  - _rpc_error_handler: RpcState(AttachSession, 620c969a-617f-4890-aec9-13fda0be9342, ghrun-ou4bsyjxme.auto.internal:18136): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-12-23 19:05:04,145 - DEBUG - ydb.connection.1657331  - close: Closing channel for endpoint ghrun-ou4bsyjxme.auto.internal:18136
2025-12-23 19:05:04,146 - INFO - ydb.pool.Discovery.1657331  - run: Successfully terminated discovery process
2025-12-23 19:05:16,179 - INFO - ydb.tests.library.harness.kikimr_runner.1657331  - stop: Stopped node localhost:18136/1
2025-12-23 19:05:16,181 - DEBUG - library.python.filelock.1657331  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7366
2025-12-23 19:05:16,182 - DEBUG - library.python.filelock.1657331  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/32744
2025-12-23 19:05:16,182 - DEBUG - library.python.filelock.1657331  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25766
2025-12-23 19:05:16,183 - DEBUG - library.python.filelock.1657331  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18136
2025-12-23 19:05:16,184 - DEBUG - library.python.filelock.1657331  - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/25233
