2025-12-09 12:49:42,524 - INFO - ya.test.1986964  - pytest_runtest_setup: ####################################################################################################
2025-12-09 12:49:42,524 - INFO - ya.test.1986964  - pytest_runtest_setup: test_all_supported_compression[lz4_compression-COMPRESSION = "lz4"]
2025-12-09 12:49:42,525 - INFO - ya.test.1986964  - pytest_runtest_setup: ####################################################################################################
2025-12-09 12:49:42,525 - INFO - ya.test.1986964  - pytest_runtest_setup: Test setup
2025-12-09 12:49:42,528 - DEBUG - ya.test.1986964  - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-09 12:49:42,529 - DEBUG - ya.test.1986964  - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-09 12:49:42,530 - DEBUG - ya.test.1986964  - execute: Executing '['/home/runner/.ya/build/build_root/cm7y/0043ac/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-09 12:49:42,542 - DEBUG - ya.test.1986964  - execute: Command pid: 1988753
2025-12-09 12:49:43,676 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) rc: 0
2025-12-09 12:49:43,676 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) elapsed time (sec): 1.1324005126953125
2025-12-09 12:49:43,677 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) idrss: 0
2025-12-09 12:49:43,677 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) inblock: 0
2025-12-09 12:49:43,677 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) isrss: 0
2025-12-09 12:49:43,678 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) ixrss: 0
2025-12-09 12:49:43,680 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) majflt: 0
2025-12-09 12:49:43,681 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) maxrss: 504032
2025-12-09 12:49:43,681 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) minflt: 45710
2025-12-09 12:49:43,681 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) msgrcv: 0
2025-12-09 12:49:43,682 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) msgsnd: 0
2025-12-09 12:49:43,682 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) nivcsw: 202
2025-12-09 12:49:43,682 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) nsignals: 0
2025-12-09 12:49:43,682 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) nswap: 0
2025-12-09 12:49:43,683 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) nvcsw: 14
2025-12-09 12:49:43,683 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) oublock: 8
2025-12-09 12:49:43,683 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) stime: 0.180365
2025-12-09 12:49:43,684 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) utime: 0.516162
2025-12-09 12:49:43,684 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) wtime: 1.145
2025-12-09 12:49:43,684 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) output:
Git info:
    Commit: ddb9b596fa394c4a7a03070d9031b3067b79c8d4
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge 79a09f7950d025ac7651c8845c6ab578953fef41 into cd0aab03f9646e0cb890d748a31ed35e4e9d9963
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-7wapoxkjru    Host information: 
        Linux ghrun-7wapoxkjru 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-09 12:49:43,685 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1988753) errors:

2025-12-09 12:49:43,685 - INFO - __tests__.base.1986964  - _setup_ydb: Git info:
    Commit: ddb9b596fa394c4a7a03070d9031b3067b79c8d4
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge 79a09f7950d025ac7651c8845c6ab578953fef41 into cd0aab03f9646e0cb890d748a31ed35e4e9d9963
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-7wapoxkjru    Host information: 
        Linux ghrun-7wapoxkjru 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-09 12:49:43,856 - DEBUG - ya.test.1986964  - get_binary: Binary was found by /home/runner/.ya/build/build_root/cm7y/0043ac/ydb/apps/ydbd/ydbd
2025-12-09 12:49:43,859 - DEBUG - library.python.filelock.1986964  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24904
2025-12-09 12:49:43,861 - DEBUG - ydb.tests.library.harness.kikimr_runner.1986964  - start: Working directory: /home/runner/.ya/build/build_root/cm7y/0043ac/r3tmp/kikimr_cluster_k3n083iw
2025-12-09 12:49:43,928 - DEBUG - ya.test.1986964  - get_binary: Binary was found by /home/runner/.ya/build/build_root/cm7y/0043ac/ydb/apps/ydbd/ydbd
2025-12-09 12:49:43,929 - DEBUG - library.python.filelock.1986964  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63675
2025-12-09 12:49:43,929 - DEBUG - library.python.filelock.1986964  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4447
2025-12-09 12:49:43,930 - DEBUG - library.python.filelock.1986964  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19032
2025-12-09 12:49:43,931 - DEBUG - library.python.filelock.1986964  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10369
2025-12-09 12:49:43,932 - INFO - ydb.tests.library.harness.kikimr_runner.1986964  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/cm7y/0043ac/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/kikimr_configs"
2025-12-09 12:49:43,932 - INFO - ydb.tests.library.harness.kikimr_runner.1986964  - __make_run_command: Final command: /home/runner/.ya/build/build_root/cm7y/0043ac/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/cm7y/0043ac/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1/logfile__f9t3len.log --grpc-port=63675 --mon-port=4447 --ic-port=24904
2025-12-09 12:49:43,933 - DEBUG - ydb.tests.library.harness.kikimr_runner.1986964  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:63675/1, disk_size 68719476736
2025-12-09 12:49:43,934 - INFO - ydb.tests.library.harness.kikimr_runner.1986964  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/cm7y/0043ac/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/kikimr_configs"
2025-12-09 12:49:43,934 - INFO - ydb.tests.library.harness.kikimr_runner.1986964  - __make_run_command: Final command: /home/runner/.ya/build/build_root/cm7y/0043ac/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/cm7y/0043ac/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1/logfile__f9t3len.log --grpc-port=63675 --mon-port=4447 --ic-port=24904
2025-12-09 12:49:43,935 - DEBUG - ya.test.1986964  - execute: Executing '('/home/runner/.ya/build/build_root/cm7y/0043ac/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/cm7y/0043ac/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/cm7y/0043ac/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1/logfile__f9t3len.log', '--grpc-port=63675', '--mon-port=4447', '--ic-port=24904')' in '/home/runner/.ya/build/build_root/cm7y/0043ac/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.lz4_compression-COMPRESSION.=.lz4/cluster/node_1' (no wait)
2025-12-09 12:49:43,950 - DEBUG - ya.test.1986964  - execute: Command pid: 1989130
2025-12-09 12:49:43,951 - INFO - ydb.tests.library.harness.kikimr_runner.1986964  - start: Started node localhost:63675/1
2025-12-09 12:49:43,953 - DEBUG - ya.test.1986964  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-09 12:49:43,958 - DEBUG - ya.test.1986964  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-09 12:49:43,959 - DEBUG - ya.test.1986964  - execute: Executing '['/home/runner/.ya/build/build_root/cm7y/0043ac/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-09 12:49:43,973 - DEBUG - ya.test.1986964  - execute: Command pid: 1989140
2025-12-09 12:49:45,014 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) rc: 0
2025-12-09 12:49:45,014 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) elapsed time (sec): 1.0392169952392578
2025-12-09 12:49:45,015 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) idrss: 0
2025-12-09 12:49:45,015 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) inblock: 0
2025-12-09 12:49:45,015 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) isrss: 0
2025-12-09 12:49:45,016 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) ixrss: 0
2025-12-09 12:49:45,016 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) majflt: 0
2025-12-09 12:49:45,016 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) maxrss: 514040
2025-12-09 12:49:45,017 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) minflt: 45740
2025-12-09 12:49:45,017 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) msgrcv: 0
2025-12-09 12:49:45,017 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) msgsnd: 0
2025-12-09 12:49:45,018 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) nivcsw: 219
2025-12-09 12:49:45,018 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) nsignals: 0
2025-12-09 12:49:45,018 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) nswap: 0
2025-12-09 12:49:45,019 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) nvcsw: 14
2025-12-09 12:49:45,019 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) oublock: 8
2025-12-09 12:49:45,019 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) stime: 0.159552
2025-12-09 12:49:45,019 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) utime: 0.5342979999999999
2025-12-09 12:49:45,020 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) wtime: 1.054
2025-12-09 12:49:45,021 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) output:
Git info:
    Commit: ddb9b596fa394c4a7a03070d9031b3067b79c8d4
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge 79a09f7950d025ac7651c8845c6ab578953fef41 into cd0aab03f9646e0cb890d748a31ed35e4e9d9963
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-7wapoxkjru    Host information: 
        Linux ghrun-7wapoxkjru 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-09 12:49:45,021 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1989140) errors:

2025-12-09 12:49:45,022 - INFO - ydb.tests.library.harness.kikimr_runner.1986964  - start: Node 1 version:
Git info:
    Commit: ddb9b596fa394c4a7a03070d9031b3067b79c8d4
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge 79a09f7950d025ac7651c8845c6ab578953fef41 into cd0aab03f9646e0cb890d748a31ed35e4e9d9963
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-7wapoxkjru    Host information: 
        Linux ghrun-7wapoxkjru 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-09 12:49:55,473 - DEBUG - ya.test.1986964  - get_binary: Binary was found by /home/runner/.ya/build/build_root/cm7y/0043ac/ydb/apps/ydbd/ydbd
2025-12-09 12:49:55,475 - DEBUG - ydb.tests.library.harness.kikimr_runner.1986964  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/cm7y/0043ac/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:63675', '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: 24904\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-09 12:49:55,477 - DEBUG - ya.test.1986964  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-09 12:49:55,478 - DEBUG - ya.test.1986964  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-09 12:49:55,478 - DEBUG - ya.test.1986964  - execute: Executing '['/home/runner/.ya/build/build_root/cm7y/0043ac/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:63675', '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: 24904\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-09 12:49:55,489 - DEBUG - ya.test.1986964  - execute: Command pid: 1992428
2025-12-09 12:49:56,758 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) rc: 0
2025-12-09 12:49:56,759 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) elapsed time (sec): 1.260420560836792
2025-12-09 12:49:56,759 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) idrss: 0
2025-12-09 12:49:56,782 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) inblock: 0
2025-12-09 12:49:56,782 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) isrss: 0
2025-12-09 12:49:56,782 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) ixrss: 0
2025-12-09 12:49:56,783 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) majflt: 0
2025-12-09 12:49:56,783 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) maxrss: 642528
2025-12-09 12:49:56,783 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) minflt: 52250
2025-12-09 12:49:56,783 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) msgrcv: 0
2025-12-09 12:49:56,784 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) msgsnd: 0
2025-12-09 12:49:56,784 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) nivcsw: 248
2025-12-09 12:49:56,784 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) nsignals: 0
2025-12-09 12:49:56,785 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) nswap: 0
2025-12-09 12:49:56,785 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) nvcsw: 435
2025-12-09 12:49:56,785 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) oublock: 8
2025-12-09 12:49:56,785 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) stime: 0.283141
2025-12-09 12:49:56,786 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) utime: 0.5695629999999999
2025-12-09 12:49:56,786 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) wtime: 1.271
2025-12-09 12:49:56,786 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-09 12:49:56,794 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992428) errors:

2025-12-09 12:49:56,795 - DEBUG - ya.test.1986964  - get_binary: Binary was found by /home/runner/.ya/build/build_root/cm7y/0043ac/ydb/apps/ydbd/ydbd
2025-12-09 12:49:56,796 - DEBUG - ydb.tests.library.harness.kikimr_runner.1986964  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/cm7y/0043ac/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:63675', '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-09 12:49:56,797 - DEBUG - ya.test.1986964  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-09 12:49:56,798 - DEBUG - ya.test.1986964  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-09 12:49:56,798 - DEBUG - ya.test.1986964  - execute: Executing '['/home/runner/.ya/build/build_root/cm7y/0043ac/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:63675', '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-09 12:49:56,814 - DEBUG - ya.test.1986964  - execute: Command pid: 1992814
2025-12-09 12:49:58,446 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) rc: 0
2025-12-09 12:49:58,447 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) elapsed time (sec): 1.6270074844360352
2025-12-09 12:49:58,447 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) idrss: 0
2025-12-09 12:49:58,447 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) inblock: 0
2025-12-09 12:49:58,447 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) isrss: 0
2025-12-09 12:49:58,448 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) ixrss: 0
2025-12-09 12:49:58,448 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) majflt: 0
2025-12-09 12:49:58,448 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) maxrss: 642528
2025-12-09 12:49:58,448 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) minflt: 52154
2025-12-09 12:49:58,449 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) msgrcv: 0
2025-12-09 12:49:58,449 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) msgsnd: 0
2025-12-09 12:49:58,449 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) nivcsw: 213
2025-12-09 12:49:58,454 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) nsignals: 0
2025-12-09 12:49:58,454 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) nswap: 0
2025-12-09 12:49:58,454 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) nvcsw: 425
2025-12-09 12:49:58,455 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) oublock: 8
2025-12-09 12:49:58,455 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) stime: 0.25233099999999997
2025-12-09 12:49:58,455 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) utime: 0.5676249999999999
2025-12-09 12:49:58,455 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) wtime: 1.642
2025-12-09 12:49:58,456 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-09 12:49:58,456 - DEBUG - ya.test.1986964  - _save_outputs: Command (pid 1992814) errors:

2025-12-09 12:49:58,456 - INFO - ydb.tests.library.harness.kikimr_runner.1986964  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-09 12:49:58,589 - INFO - ydb.tests.library.harness.kikimr_runner.1986964  - __run: Cluster started and initialized
2025-12-09 12:49:58,639 - DEBUG - ydb.connection.1986964  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-09 12:49:58,646 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1986964  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 12:49:58,650 - DEBUG - ydb.connection.1986964  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-09 12:49:58,669 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1986964  - context_resolve: Resolving endpoints for database /Root
2025-12-09 12:49:58,670 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ListEndpoints, df1a70b4-8274-49c1-9133-f3c927fab1f1, localhost:63675): creating call state
2025-12-09 12:49:58,671 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ListEndpoints, df1a70b4-8274-49c1-9133-f3c927fab1f1, localhost:63675): request = { database: "/Root" }
2025-12-09 12:49:58,684 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ListEndpoints, df1a70b4-8274-49c1-9133-f3c927fab1f1, localhost:63675): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-7wapoxkjru.auto.internal" port: 63675 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-09 12:49:58,685 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1986964  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-7wapoxkjru.auto.internal:63675, location 1, ssl: False>]>
2025-12-09 12:49:58,686 - DEBUG - ydb.connection.1986964  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-09 12:49:58,722 - DEBUG - ydb.connection.1986964  - close: Closing channel for endpoint localhost:63675
2025-12-09 12:49:58,726 - DEBUG - ydb.query.pool.1986964  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-09 12:49:58,727 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(CreateSession, b6939d12-86f9-4baf-b9aa-265c09f80809, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:49:58,728 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(CreateSession, b6939d12-86f9-4baf-b9aa-265c09f80809, ghrun-7wapoxkjru.auto.internal:63675): request = {  }
2025-12-09 12:49:58,736 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(CreateSession, b6939d12-86f9-4baf-b9aa-265c09f80809, ghrun-7wapoxkjru.auto.internal:63675): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" node_id: 1 }
2025-12-09 12:49:58,737 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(AttachSession, f037a614-470c-4957-80ee-c1bb90973639, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:49:58,737 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(AttachSession, f037a614-470c-4957-80ee-c1bb90973639, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" }
2025-12-09 12:49:58,740 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(AttachSession, f037a614-470c-4957-80ee-c1bb90973639, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:49:58,743 - DEBUG - ydb.query.pool.1986964  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:49:58,744 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 4f2c9590-7dc8-47a0-acc1-3314c77b0bcf, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:49:58,745 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 4f2c9590-7dc8-47a0-acc1-3314c77b0bcf, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:49:58,747 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 4f2c9590-7dc8-47a0-acc1-3314c77b0bcf, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:02,962 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:02,963 - INFO - __tests__.alter_compression.1986964  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-12-09 12:50:02,964 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:02,964 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 0c23b3b9-9fc1-4c66-a675-235f4890859e, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:03,010 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 0c23b3b9-9fc1-4c66-a675-235f4890859e, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:03,012 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 0c23b3b9-9fc1-4c66-a675-235f4890859e, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:04,190 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:04,190 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:04,191 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 4ae4c58a-660f-400c-a886-7fe1c16759c7, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:04,192 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 4ae4c58a-660f-400c-a886-7fe1c16759c7, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:04,195 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 4ae4c58a-660f-400c-a886-7fe1c16759c7, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:05,338 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:05,343 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:05,344 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, b70325ec-7c2d-4c69-b169-c60c982e61b6, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:05,345 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, b70325ec-7c2d-4c69-b169-c60c982e61b6, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:05,346 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, b70325ec-7c2d-4c69-b169-c60c982e61b6, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:06,151 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:06,152 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:06,153 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 544040af-df80-4feb-9daa-f7deab912ff6, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:06,154 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 544040af-df80-4feb-9daa-f7deab912ff6, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:06,156 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 544040af-df80-4feb-9daa-f7deab912ff6, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:07,660 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:07,661 - INFO - __tests__.alter_compression.1986964  - 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-09 12:50:07,661 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:07,662 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 45e00924-3bbb-4500-b52d-c4e142988d00, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:07,663 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 45e00924-3bbb-4500-b52d-c4e142988d00, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:07,664 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 45e00924-3bbb-4500-b52d-c4e142988d00, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:09,046 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:09,047 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:09,048 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, b71e548c-5c01-4bad-9c5f-873eac4dd832, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:09,048 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, b71e548c-5c01-4bad-9c5f-873eac4dd832, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:09,050 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, b71e548c-5c01-4bad-9c5f-873eac4dd832, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:09,303 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:09,303 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:09,304 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 2c6c0baf-f5f9-439c-911c-1e41c62f7fd1, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:09,305 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 2c6c0baf-f5f9-439c-911c-1e41c62f7fd1, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:09,309 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 2c6c0baf-f5f9-439c-911c-1e41c62f7fd1, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:09,654 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:09,655 - INFO - __tests__.alter_compression.1986964  - 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-09 12:50:09,656 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:09,656 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 9347881f-cd46-4ed8-ba97-8cb8f07a0d9c, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:09,657 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 9347881f-cd46-4ed8-ba97-8cb8f07a0d9c, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:09,659 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 9347881f-cd46-4ed8-ba97-8cb8f07a0d9c, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:11,257 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:11,258 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:11,258 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, bbf09ba4-557a-4277-9c85-f651c771ad6c, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:11,259 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, bbf09ba4-557a-4277-9c85-f651c771ad6c, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:11,270 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, bbf09ba4-557a-4277-9c85-f651c771ad6c, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:11,646 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:11,647 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:11,647 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 40959411-4a2f-4fff-9249-55be3ac19f49, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:11,648 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 40959411-4a2f-4fff-9249-55be3ac19f49, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:11,654 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 40959411-4a2f-4fff-9249-55be3ac19f49, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:12,461 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:12,462 - INFO - __tests__.alter_compression.1986964  - 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-09 12:50:12,462 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:12,463 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 6e136c74-c143-49ec-aa18-24c96b134d61, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:12,464 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 6e136c74-c143-49ec-aa18-24c96b134d61, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:12,466 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 6e136c74-c143-49ec-aa18-24c96b134d61, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:14,188 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:14,189 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:14,190 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, a8f51c62-7402-4c85-81b4-8256dfab4afb, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:14,190 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, a8f51c62-7402-4c85-81b4-8256dfab4afb, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:14,192 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, a8f51c62-7402-4c85-81b4-8256dfab4afb, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:14,679 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:14,680 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:14,681 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, c88345e8-e8b1-4ebb-9364-fbdded2e5f32, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:14,682 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, c88345e8-e8b1-4ebb-9364-fbdded2e5f32, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:14,690 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, c88345e8-e8b1-4ebb-9364-fbdded2e5f32, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:15,795 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:15,796 - INFO - __tests__.alter_compression.1986964  - 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-09 12:50:15,797 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:15,798 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, bb345fe4-fcb7-4a87-bdde-9550b59ca859, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:15,799 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, bb345fe4-fcb7-4a87-bdde-9550b59ca859, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:15,822 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, bb345fe4-fcb7-4a87-bdde-9550b59ca859, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:17,735 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:17,736 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:17,737 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 7b7b5fd4-edd1-4483-9e7c-58d3bb87d6f4, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:17,747 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 7b7b5fd4-edd1-4483-9e7c-58d3bb87d6f4, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:17,749 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 7b7b5fd4-edd1-4483-9e7c-58d3bb87d6f4, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:18,274 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:18,275 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:18,276 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 676cbc4a-feff-4a24-b9a7-c9be9aa424f5, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:18,277 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 676cbc4a-feff-4a24-b9a7-c9be9aa424f5, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:18,280 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 676cbc4a-feff-4a24-b9a7-c9be9aa424f5, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:18,738 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:18,739 - INFO - __tests__.alter_compression.1986964  - 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-09 12:50:18,740 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:18,740 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, e83ccacf-880b-42ed-967f-9c7e63bdeaae, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:18,741 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, e83ccacf-880b-42ed-967f-9c7e63bdeaae, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:18,743 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, e83ccacf-880b-42ed-967f-9c7e63bdeaae, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:20,227 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:20,231 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:20,232 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, e01972b7-1041-43a3-a25e-fad2495dde83, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:20,233 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, e01972b7-1041-43a3-a25e-fad2495dde83, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:20,235 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, e01972b7-1041-43a3-a25e-fad2495dde83, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:20,611 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:20,612 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:20,613 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 8bf7113c-8c43-4240-9f3b-f219f6508b31, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:20,614 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 8bf7113c-8c43-4240-9f3b-f219f6508b31, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:20,623 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 8bf7113c-8c43-4240-9f3b-f219f6508b31, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:21,733 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:21,734 - INFO - __tests__.alter_compression.1986964  - 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-09 12:50:21,735 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:21,735 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 9160158e-a769-4983-a574-fc9478c31438, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:21,737 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 9160158e-a769-4983-a574-fc9478c31438, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:21,763 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 9160158e-a769-4983-a574-fc9478c31438, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:23,371 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:23,372 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:23,373 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 93e595ae-6c3c-4285-81c6-ce5611dd2e70, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:23,374 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 93e595ae-6c3c-4285-81c6-ce5611dd2e70, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:23,375 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 93e595ae-6c3c-4285-81c6-ce5611dd2e70, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:23,636 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:23,638 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:23,638 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, a4725dbd-35a4-4eec-a86a-02f7c334d4a2, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:23,639 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, a4725dbd-35a4-4eec-a86a-02f7c334d4a2, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:23,641 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, a4725dbd-35a4-4eec-a86a-02f7c334d4a2, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:24,218 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:24,219 - INFO - __tests__.alter_compression.1986964  - 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-09 12:50:24,219 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:24,220 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 66c751e2-8a71-4dc9-aaba-2fd8cc0c271a, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:24,221 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 66c751e2-8a71-4dc9-aaba-2fd8cc0c271a, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:24,223 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 66c751e2-8a71-4dc9-aaba-2fd8cc0c271a, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:25,914 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:25,914 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:25,915 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, f605a5d0-c933-429b-ad38-51320d79acb9, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:25,916 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, f605a5d0-c933-429b-ad38-51320d79acb9, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:25,918 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, f605a5d0-c933-429b-ad38-51320d79acb9, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:26,562 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:26,563 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:26,564 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, d33fedc3-fb0e-4331-b634-6c91374c6f04, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:26,575 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, d33fedc3-fb0e-4331-b634-6c91374c6f04, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:26,576 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, d33fedc3-fb0e-4331-b634-6c91374c6f04, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:28,326 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:28,326 - INFO - __tests__.alter_compression.1986964  - 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-09 12:50:28,327 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:28,328 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 3ae1fa10-a1de-4bc5-aa35-cf165168bc08, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:28,342 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 3ae1fa10-a1de-4bc5-aa35-cf165168bc08, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:28,355 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 3ae1fa10-a1de-4bc5-aa35-cf165168bc08, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:29,525 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:29,526 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:29,527 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, b78490f1-5a27-4f38-afaa-329bf367d79b, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:29,528 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, b78490f1-5a27-4f38-afaa-329bf367d79b, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:29,529 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, b78490f1-5a27-4f38-afaa-329bf367d79b, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:29,898 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:29,899 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:29,914 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, eff92f92-a778-4d02-a482-b373a33b489d, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:29,925 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, eff92f92-a778-4d02-a482-b373a33b489d, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:29,933 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, eff92f92-a778-4d02-a482-b373a33b489d, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:31,015 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:31,021 - INFO - __tests__.alter_compression.1986964  - 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-09 12:50:31,022 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:31,036 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, b1e299b8-fa98-4804-8f17-918358d0dddf, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:31,037 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, b1e299b8-fa98-4804-8f17-918358d0dddf, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:31,038 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, b1e299b8-fa98-4804-8f17-918358d0dddf, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:32,713 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:32,714 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:32,715 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 3955aca9-878c-483d-9098-4b49c859e266, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:32,716 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 3955aca9-878c-483d-9098-4b49c859e266, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:32,730 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 3955aca9-878c-483d-9098-4b49c859e266, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:33,426 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:33,435 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:33,436 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 26b68b3a-8ae1-4675-a9fc-876aa73251b6, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:33,437 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 26b68b3a-8ae1-4675-a9fc-876aa73251b6, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:33,438 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 26b68b3a-8ae1-4675-a9fc-876aa73251b6, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:34,489 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:34,514 - INFO - __tests__.alter_compression.1986964  - 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-09 12:50:34,514 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:34,515 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 859eafb3-b72a-4fe2-a25e-5fc09fa7ee7a, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:34,516 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 859eafb3-b72a-4fe2-a25e-5fc09fa7ee7a, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:34,521 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 859eafb3-b72a-4fe2-a25e-5fc09fa7ee7a, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:36,227 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:36,228 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:36,228 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 5410632a-ed28-42a9-8908-6e4f1f60edab, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:36,229 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 5410632a-ed28-42a9-8908-6e4f1f60edab, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:36,246 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 5410632a-ed28-42a9-8908-6e4f1f60edab, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:36,787 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:36,788 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:36,788 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 054b4d1b-53fc-40b3-8c06-c032c4f98e6f, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:36,789 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 054b4d1b-53fc-40b3-8c06-c032c4f98e6f, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:36,790 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 054b4d1b-53fc-40b3-8c06-c032c4f98e6f, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:37,305 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:37,306 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:37,307 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, d6b7b5fe-22bf-416b-bee3-6e90edc39b33, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:37,307 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, d6b7b5fe-22bf-416b-bee3-6e90edc39b33, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:37,309 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, d6b7b5fe-22bf-416b-bee3-6e90edc39b33, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:50:45,340 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:55,393 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:50:55,394 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 9412e142-093e-4b8b-8239-ddf19a45491e, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:50:55,395 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 9412e142-093e-4b8b-8239-ddf19a45491e, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:50:55,396 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 9412e142-093e-4b8b-8239-ddf19a45491e, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:00,748 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:00,786 - INFO - root.1986964  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-12-09 12:51:00,786 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:00,787 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 9c5eeba7-36c9-4d39-8463-343bdc3ecb2a, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:00,788 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 9c5eeba7-36c9-4d39-8463-343bdc3ecb2a, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:51:00,790 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 9c5eeba7-36c9-4d39-8463-343bdc3ecb2a, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:04,716 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:14,757 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:14,758 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, a9d18ad3-89ed-436b-90c7-6644d7da009b, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:14,759 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, a9d18ad3-89ed-436b-90c7-6644d7da009b, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:51:14,786 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, a9d18ad3-89ed-436b-90c7-6644d7da009b, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:15,582 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:15,586 - INFO - root.1986964  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-12-09 12:51:15,587 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:15,587 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 27290922-dc33-49ec-a09f-4d003cb9698b, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:15,588 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 27290922-dc33-49ec-a09f-4d003cb9698b, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" 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-09 12:51:15,592 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 27290922-dc33-49ec-a09f-4d003cb9698b, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:15,830 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:15,833 - INFO - ya.test.1986964  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[lz4_compression-COMPRESSION = "lz4"])
2025-12-09 12:51:15,834 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:15,835 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 0cb3262a-6912-4660-879c-766057abab17, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:15,835 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 0cb3262a-6912-4660-879c-766057abab17, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/lz4_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"lz4\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:15,841 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 0cb3262a-6912-4660-879c-766057abab17, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:18,625 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:18,626 - INFO - __tests__.alter_compression.1986964  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/lz4_compression created
2025-12-09 12:51:18,627 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:18,627 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 3d5cef50-f1a4-44e9-ad7c-10261b8b04d4, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:18,628 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 3d5cef50-f1a4-44e9-ad7c-10261b8b04d4, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/lz4_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:18,630 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 3d5cef50-f1a4-44e9-ad7c-10261b8b04d4, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:19,452 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:19,453 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:19,454 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, ff323356-0a7f-495e-990d-667821d815f1, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:19,455 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, ff323356-0a7f-495e-990d-667821d815f1, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:19,457 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, ff323356-0a7f-495e-990d-667821d815f1, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:20,402 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:20,402 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:20,403 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 95a6a7e9-8231-48e7-b007-78e470b88561, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:20,404 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 95a6a7e9-8231-48e7-b007-78e470b88561, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:20,406 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 95a6a7e9-8231-48e7-b007-78e470b88561, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:21,283 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:21,284 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:21,285 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 2deb41b6-fe28-4f97-9a32-136689c5af3b, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:21,286 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 2deb41b6-fe28-4f97-9a32-136689c5af3b, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:21,287 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 2deb41b6-fe28-4f97-9a32-136689c5af3b, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:22,687 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:22,688 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 872992, 'BlobCount': 128}}
2025-12-09 12:51:22,689 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:22,689 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 5eef6d35-be67-44c7-ada2-c3c0b16cb5d0, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:22,690 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 5eef6d35-be67-44c7-ada2-c3c0b16cb5d0, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:22,692 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 5eef6d35-be67-44c7-ada2-c3c0b16cb5d0, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:23,612 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:23,613 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:23,613 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, bbcb20a0-db6e-4c3a-ad87-863e1e09412d, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:23,614 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, bbcb20a0-db6e-4c3a-ad87-863e1e09412d, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:23,615 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, bbcb20a0-db6e-4c3a-ad87-863e1e09412d, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:23,886 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:23,886 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:23,887 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, c0b2a4bf-88d1-4666-87e7-6986ba113663, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:23,888 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, c0b2a4bf-88d1-4666-87e7-6986ba113663, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:23,889 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, c0b2a4bf-88d1-4666-87e7-6986ba113663, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:24,195 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:24,196 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 1747088, 'BlobCount': 256}}
2025-12-09 12:51:24,197 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:24,197 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 6d1872e5-c644-4736-88eb-59bedba702d8, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:24,198 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 6d1872e5-c644-4736-88eb-59bedba702d8, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:24,200 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 6d1872e5-c644-4736-88eb-59bedba702d8, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:25,276 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:25,277 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:25,278 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 4c334870-15b7-4d43-b21d-424a8fce0770, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:25,279 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 4c334870-15b7-4d43-b21d-424a8fce0770, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:25,280 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 4c334870-15b7-4d43-b21d-424a8fce0770, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:25,498 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:25,498 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:25,499 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 1124e230-08f7-4b15-9a03-b58a90741218, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:25,500 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 1124e230-08f7-4b15-9a03-b58a90741218, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:25,502 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 1124e230-08f7-4b15-9a03-b58a90741218, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:25,845 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:25,845 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 2620992, 'BlobCount': 384}}
2025-12-09 12:51:25,847 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:25,847 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 1c041194-1ed6-44f6-9efc-37f4a3bbbf08, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:25,848 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 1c041194-1ed6-44f6-9efc-37f4a3bbbf08, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:25,849 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 1c041194-1ed6-44f6-9efc-37f4a3bbbf08, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:26,986 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:26,986 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:26,987 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, c921ff0a-078e-483c-8cff-166153538ef0, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:26,988 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, c921ff0a-078e-483c-8cff-166153538ef0, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:26,989 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, c921ff0a-078e-483c-8cff-166153538ef0, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:27,265 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:27,265 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:27,266 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 3371174b-2013-4a63-a6d9-8951ebedbc75, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:27,267 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 3371174b-2013-4a63-a6d9-8951ebedbc75, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:27,269 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 3371174b-2013-4a63-a6d9-8951ebedbc75, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:27,809 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:27,810 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 3495088, 'BlobCount': 512}}
2025-12-09 12:51:27,810 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:27,811 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, dc89b8d9-42b0-48fc-8ded-7e7d50312901, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:27,812 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, dc89b8d9-42b0-48fc-8ded-7e7d50312901, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:27,814 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, dc89b8d9-42b0-48fc-8ded-7e7d50312901, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:29,244 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:29,244 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:29,245 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 34cf0032-e78b-4acf-9896-3313fce602b2, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:29,246 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 34cf0032-e78b-4acf-9896-3313fce602b2, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:29,247 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 34cf0032-e78b-4acf-9896-3313fce602b2, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:29,600 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:29,716 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:29,717 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, ac6570d1-9fc7-4818-a01d-dd84000e4582, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:29,718 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, ac6570d1-9fc7-4818-a01d-dd84000e4582, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:29,720 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, ac6570d1-9fc7-4818-a01d-dd84000e4582, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:30,294 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:30,295 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 4368976, 'BlobCount': 640}}
2025-12-09 12:51:30,296 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:30,296 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, e0417d99-bc67-4459-be4c-2d439b19791d, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:30,298 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, e0417d99-bc67-4459-be4c-2d439b19791d, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:30,299 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, e0417d99-bc67-4459-be4c-2d439b19791d, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:31,519 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:31,520 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:31,521 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 17d9efed-e1d8-4b57-bb87-796c5d6ada76, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:31,522 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 17d9efed-e1d8-4b57-bb87-796c5d6ada76, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:31,523 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 17d9efed-e1d8-4b57-bb87-796c5d6ada76, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:31,802 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:31,803 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:31,804 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, dd9a6637-1248-469b-b88e-db282ff38b5a, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:31,805 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, dd9a6637-1248-469b-b88e-db282ff38b5a, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:31,811 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, dd9a6637-1248-469b-b88e-db282ff38b5a, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:32,418 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:32,419 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 5242960, 'BlobCount': 768}}
2025-12-09 12:51:32,419 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:32,420 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, f40000bb-c8f2-4009-bb55-3ca0b14b635c, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:32,421 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, f40000bb-c8f2-4009-bb55-3ca0b14b635c, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:32,423 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, f40000bb-c8f2-4009-bb55-3ca0b14b635c, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:33,623 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:33,624 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:33,625 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 0568981c-e729-4e82-b5e4-9c360168723b, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:33,626 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 0568981c-e729-4e82-b5e4-9c360168723b, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:33,627 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 0568981c-e729-4e82-b5e4-9c360168723b, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:33,879 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:33,880 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:33,881 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, f88dee60-d4b7-4acd-9fcd-73cfef7b8438, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:33,882 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, f88dee60-d4b7-4acd-9fcd-73cfef7b8438, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:33,883 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, f88dee60-d4b7-4acd-9fcd-73cfef7b8438, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:34,430 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:34,431 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 6117040, 'BlobCount': 896}}
2025-12-09 12:51:34,431 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:34,432 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, e9c74c91-8b68-4b0c-8c54-42388e0a2100, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:34,438 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, e9c74c91-8b68-4b0c-8c54-42388e0a2100, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:34,440 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, e9c74c91-8b68-4b0c-8c54-42388e0a2100, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:35,875 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:35,876 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:35,876 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, ce8ccf33-78ca-4ceb-905d-19462da8ce2e, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:35,877 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, ce8ccf33-78ca-4ceb-905d-19462da8ce2e, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:35,879 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, ce8ccf33-78ca-4ceb-905d-19462da8ce2e, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:36,355 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:36,355 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:36,356 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 52d61519-a29f-4108-a992-c3f4a5e8625a, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:36,357 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 52d61519-a29f-4108-a992-c3f4a5e8625a, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:36,358 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 52d61519-a29f-4108-a992-c3f4a5e8625a, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:37,306 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:37,307 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 6991184, 'BlobCount': 1024}}
2025-12-09 12:51:37,307 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:37,308 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 8e674cae-3995-4a59-9537-8c475d7dd970, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:37,309 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 8e674cae-3995-4a59-9537-8c475d7dd970, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:37,314 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 8e674cae-3995-4a59-9537-8c475d7dd970, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:38,723 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:38,723 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:38,724 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 81d31d11-6cbb-415a-9217-93890fec431f, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:38,725 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 81d31d11-6cbb-415a-9217-93890fec431f, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:38,734 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 81d31d11-6cbb-415a-9217-93890fec431f, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:39,379 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:39,380 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:39,381 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 931c065a-830f-4f0e-b52c-f96c8a882ed0, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:39,382 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 931c065a-830f-4f0e-b52c-f96c8a882ed0, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:39,383 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 931c065a-830f-4f0e-b52c-f96c8a882ed0, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:40,239 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:40,240 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 7865088, 'BlobCount': 1152}}
2025-12-09 12:51:40,241 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:40,241 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 0b69229d-7150-475c-8828-ab91d2603eb3, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:40,242 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 0b69229d-7150-475c-8828-ab91d2603eb3, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/lz4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:40,247 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 0b69229d-7150-475c-8828-ab91d2603eb3, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:41,602 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:41,603 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:41,603 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, ad8f2182-47f5-49c0-a385-eeb7c8cd739d, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:41,604 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, ad8f2182-47f5-49c0-a385-eeb7c8cd739d, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:41,606 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, ad8f2182-47f5-49c0-a385-eeb7c8cd739d, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:41,868 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:41,869 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:41,869 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, fb5fa549-325f-4902-8c74-f13ffe75b210, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:41,870 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, fb5fa549-325f-4902-8c74-f13ffe75b210, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:41,871 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, fb5fa549-325f-4902-8c74-f13ffe75b210, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:42,719 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:42,720 - INFO - __tests__.alter_compression.1986964  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/lz4_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 8739264, 'BlobCount': 1280}}
2025-12-09 12:51:42,720 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:42,721 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, e59312cb-ebb8-4d62-bf86-4c6193c0e8bd, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:42,722 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, e59312cb-ebb8-4d62-bf86-4c6193c0e8bd, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/lz4_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:42,724 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, e59312cb-ebb8-4d62-bf86-4c6193c0e8bd, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:43,196 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1986964  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-09 12:51:43,197 - DEBUG - ydb.connection.1986964  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-12-09 12:51:43,229 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1986964  - context_resolve: Resolving endpoints for database /Root
2025-12-09 12:51:43,230 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ListEndpoints, cb42cf74-e227-4c53-8d46-e7fd78994c05, localhost:63675): creating call state
2025-12-09 12:51:43,230 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ListEndpoints, cb42cf74-e227-4c53-8d46-e7fd78994c05, localhost:63675): request = { database: "/Root" }
2025-12-09 12:51:43,272 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ListEndpoints, cb42cf74-e227-4c53-8d46-e7fd78994c05, localhost:63675): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-7wapoxkjru.auto.internal" port: 63675 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-09 12:51:43,273 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.1986964  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-7wapoxkjru.auto.internal:63675, location 1, ssl: False>]>
2025-12-09 12:51:43,274 - DEBUG - ydb.connection.1986964  - close: Closing channel for endpoint localhost:63675
2025-12-09 12:51:44,327 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:44,328 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:44,328 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, e8a8cb55-80c0-4b67-8f41-150d5a2bda90, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:44,329 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, e8a8cb55-80c0-4b67-8f41-150d5a2bda90, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:44,331 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, e8a8cb55-80c0-4b67-8f41-150d5a2bda90, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:44,951 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:44,951 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:44,952 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 359202a4-4f0a-41e1-bc78-9316a3c11118, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:44,953 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 359202a4-4f0a-41e1-bc78-9316a3c11118, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:44,954 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 359202a4-4f0a-41e1-bc78-9316a3c11118, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:45,434 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:45,435 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:51:45,436 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 23b5706e-9443-492f-92a1-4fc4bd673332, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:51:45,437 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 23b5706e-9443-492f-92a1-4fc4bd673332, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:51:45,438 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 23b5706e-9443-492f-92a1-4fc4bd673332, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:51:51,366 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:01,411 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:01,413 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 43f5e08a-cbf6-43ca-8925-573ae4b24deb, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:01,414 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 43f5e08a-cbf6-43ca-8925-573ae4b24deb, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:52:01,416 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 43f5e08a-cbf6-43ca-8925-573ae4b24deb, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:52:06,613 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:06,733 - INFO - root.1986964  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4369632)
2025-12-09 12:52:06,734 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:06,735 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 0ac8516c-f2a4-45c0-a118-9cc7d23afca4, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:06,736 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 0ac8516c-f2a4-45c0-a118-9cc7d23afca4, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:52:06,739 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 0ac8516c-f2a4-45c0-a118-9cc7d23afca4, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:52:07,616 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:07,616 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:07,617 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 984bee5f-96cf-4a1e-8ee8-e64e176bf6b7, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:07,618 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 984bee5f-96cf-4a1e-8ee8-e64e176bf6b7, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:52:07,621 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 984bee5f-96cf-4a1e-8ee8-e64e176bf6b7, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:52:12,066 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:22,316 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:22,317 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 515cf781-910a-45d1-be1a-8fcbea6f885f, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:22,318 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 515cf781-910a-45d1-be1a-8fcbea6f885f, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:52:22,320 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 515cf781-910a-45d1-be1a-8fcbea6f885f, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:52:24,456 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:34,511 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:34,512 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, 8c6a5c19-141d-436b-91ea-dea9f58fdf12, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:34,513 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, 8c6a5c19-141d-436b-91ea-dea9f58fdf12, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:52:34,514 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, 8c6a5c19-141d-436b-91ea-dea9f58fdf12, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:52:35,164 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:45,170 - DEBUG - ydb.query.pool.1986964  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:45,170 - DEBUG - ydb.connection.1986964  - _prepare_call: RpcState(ExecuteQuery, b3ac436f-2dba-4ed3-bc25-bb7f26630c85, ghrun-7wapoxkjru.auto.internal:63675): creating call state
2025-12-09 12:52:45,171 - DEBUG - ydb.connection.1986964  - _log_request: RpcState(ExecuteQuery, b3ac436f-2dba-4ed3-bc25-bb7f26630c85, ghrun-7wapoxkjru.auto.internal:63675): request = { session_id: "ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/lz4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-09 12:52:45,173 - DEBUG - ydb.connection.1986964  - _log_response: RpcState(ExecuteQuery, b3ac436f-2dba-4ed3-bc25-bb7f26630c85, ghrun-7wapoxkjru.auto.internal:63675): response = { <_MultiThreadedRendezvous object> }
2025-12-09 12:52:45,910 - DEBUG - ydb.query.pool.1986964  - release: Session returned to queue: ydb://session/3?node_id=1&id=YjEwMWNkNDMtZDQxZjRhYjYtZjkzZTQzNDgtNjhiNzc0ZGQ=
2025-12-09 12:52:45,915 - INFO - root.1986964  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/lz4_compression`, volumes `value` (8000000, 4258592)
2025-12-09 12:52:45,915 - INFO - root.1986964  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/lz4_compression` 8092160 / 4258592: 1.900196121159294
2025-12-09 12:52:45,930 - INFO - ya.test.1986964  - pytest_runtest_teardown: Test teardown
