2025-10-15 15:11:58,851 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:11:58,852 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_2_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 2]
2025-10-15 15:11:58,852 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:11:58,852 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-15 15:11:58,855 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-15 15:11:58,856 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-15 15:11:58,857 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001bc6/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:11:58,864 - DEBUG - ya.test - execute: Command pid: 569381
2025-10-15 15:12:00,249 - DEBUG - ya.test - _save_outputs: Command (pid 569381) rc: 0
2025-10-15 15:12:00,250 - DEBUG - ya.test - _save_outputs: Command (pid 569381) elapsed time (sec): 1.3833506107330322
2025-10-15 15:12:00,250 - DEBUG - ya.test - _save_outputs: Command (pid 569381) idrss: 0
2025-10-15 15:12:00,250 - DEBUG - ya.test - _save_outputs: Command (pid 569381) inblock: 0
2025-10-15 15:12:00,251 - DEBUG - ya.test - _save_outputs: Command (pid 569381) isrss: 0
2025-10-15 15:12:00,251 - DEBUG - ya.test - _save_outputs: Command (pid 569381) ixrss: 0
2025-10-15 15:12:00,251 - DEBUG - ya.test - _save_outputs: Command (pid 569381) majflt: 0
2025-10-15 15:12:00,252 - DEBUG - ya.test - _save_outputs: Command (pid 569381) maxrss: 475300
2025-10-15 15:12:00,252 - DEBUG - ya.test - _save_outputs: Command (pid 569381) minflt: 45139
2025-10-15 15:12:00,252 - DEBUG - ya.test - _save_outputs: Command (pid 569381) msgrcv: 0
2025-10-15 15:12:00,253 - DEBUG - ya.test - _save_outputs: Command (pid 569381) msgsnd: 0
2025-10-15 15:12:00,261 - DEBUG - ya.test - _save_outputs: Command (pid 569381) nivcsw: 235
2025-10-15 15:12:00,262 - DEBUG - ya.test - _save_outputs: Command (pid 569381) nsignals: 0
2025-10-15 15:12:00,262 - DEBUG - ya.test - _save_outputs: Command (pid 569381) nswap: 0
2025-10-15 15:12:00,262 - DEBUG - ya.test - _save_outputs: Command (pid 569381) nvcsw: 14
2025-10-15 15:12:00,263 - DEBUG - ya.test - _save_outputs: Command (pid 569381) oublock: 8
2025-10-15 15:12:00,263 - DEBUG - ya.test - _save_outputs: Command (pid 569381) stime: 0.23023
2025-10-15 15:12:00,263 - DEBUG - ya.test - _save_outputs: Command (pid 569381) utime: 0.537369
2025-10-15 15:12:00,264 - DEBUG - ya.test - _save_outputs: Command (pid 569381) wtime: 1.391
2025-10-15 15:12:00,264 - DEBUG - ya.test - _save_outputs: Command (pid 569381) output:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-lvxbqnxafi
    Host information: 
        Linux ghrun-lvxbqnxafi 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/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/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-10-15 15:12:00,267 - DEBUG - ya.test - _save_outputs: Command (pid 569381) errors:

2025-10-15 15:12:00,268 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-lvxbqnxafi
    Host information: 
        Linux ghrun-lvxbqnxafi 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/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/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-10-15 15:12:00,594 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001bc6/ydb/apps/ydbd/ydbd
2025-10-15 15:12:00,595 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1098
2025-10-15 15:12:00,597 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/w2kl/001bc6/r3tmp/kikimr_cluster_vxdkrz1e
2025-10-15 15:12:00,642 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001bc6/ydb/apps/ydbd/ydbd
2025-10-15 15:12:00,643 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29038
2025-10-15 15:12:00,643 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14984
2025-10-15 15:12:00,644 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14023
2025-10-15 15:12:00,644 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29039
2025-10-15 15:12:00,646 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001bc6/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs"
2025-10-15 15:12:00,646 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001bc6/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/w2kl/001bc6/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_0lkh5ity.log --grpc-port=29038 --mon-port=14984 --ic-port=1098
2025-10-15 15:12:00,647 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:29038/1, disk_size 68719476736
2025-10-15 15:12:00,647 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001bc6/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs"
2025-10-15 15:12:00,648 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001bc6/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/w2kl/001bc6/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_0lkh5ity.log --grpc-port=29038 --mon-port=14984 --ic-port=1098
2025-10-15 15:12:00,649 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/w2kl/001bc6/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/w2kl/001bc6/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/w2kl/001bc6/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_0lkh5ity.log', '--grpc-port=29038', '--mon-port=14984', '--ic-port=1098')' in '/home/runner/.ya/build/build_root/w2kl/001bc6/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1' (no wait)
2025-10-15 15:12:00,661 - DEBUG - ya.test - execute: Command pid: 570115
2025-10-15 15:12:00,662 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:29038/1
2025-10-15 15:12:00,664 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-15 15:12:00,674 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-15 15:12:00,674 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001bc6/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:12:00,686 - DEBUG - ya.test - execute: Command pid: 570117
2025-10-15 15:12:01,927 - DEBUG - ya.test - _save_outputs: Command (pid 570117) rc: 0
2025-10-15 15:12:01,928 - DEBUG - ya.test - _save_outputs: Command (pid 570117) elapsed time (sec): 1.2403321266174316
2025-10-15 15:12:01,929 - DEBUG - ya.test - _save_outputs: Command (pid 570117) idrss: 0
2025-10-15 15:12:01,929 - DEBUG - ya.test - _save_outputs: Command (pid 570117) inblock: 0
2025-10-15 15:12:01,929 - DEBUG - ya.test - _save_outputs: Command (pid 570117) isrss: 0
2025-10-15 15:12:01,930 - DEBUG - ya.test - _save_outputs: Command (pid 570117) ixrss: 0
2025-10-15 15:12:01,930 - DEBUG - ya.test - _save_outputs: Command (pid 570117) majflt: 0
2025-10-15 15:12:01,930 - DEBUG - ya.test - _save_outputs: Command (pid 570117) maxrss: 484056
2025-10-15 15:12:01,930 - DEBUG - ya.test - _save_outputs: Command (pid 570117) minflt: 45150
2025-10-15 15:12:01,931 - DEBUG - ya.test - _save_outputs: Command (pid 570117) msgrcv: 0
2025-10-15 15:12:01,931 - DEBUG - ya.test - _save_outputs: Command (pid 570117) msgsnd: 0
2025-10-15 15:12:01,931 - DEBUG - ya.test - _save_outputs: Command (pid 570117) nivcsw: 180
2025-10-15 15:12:01,932 - DEBUG - ya.test - _save_outputs: Command (pid 570117) nsignals: 0
2025-10-15 15:12:01,932 - DEBUG - ya.test - _save_outputs: Command (pid 570117) nswap: 0
2025-10-15 15:12:01,932 - DEBUG - ya.test - _save_outputs: Command (pid 570117) nvcsw: 14
2025-10-15 15:12:01,933 - DEBUG - ya.test - _save_outputs: Command (pid 570117) oublock: 8
2025-10-15 15:12:01,933 - DEBUG - ya.test - _save_outputs: Command (pid 570117) stime: 0.227238
2025-10-15 15:12:01,933 - DEBUG - ya.test - _save_outputs: Command (pid 570117) utime: 0.528897
2025-10-15 15:12:01,934 - DEBUG - ya.test - _save_outputs: Command (pid 570117) wtime: 1.252
2025-10-15 15:12:01,934 - DEBUG - ya.test - _save_outputs: Command (pid 570117) output:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-lvxbqnxafi
    Host information: 
        Linux ghrun-lvxbqnxafi 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/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/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-10-15 15:12:01,934 - DEBUG - ya.test - _save_outputs: Command (pid 570117) errors:

2025-10-15 15:12:01,935 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b
2025-10-15 15:12:07,551 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001bc6/ydb/apps/ydbd/ydbd
2025-10-15 15:12:07,552 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2kl/001bc6/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:29038', '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: 1098\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-15 15:12:07,553 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-15 15:12:07,554 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-15 15:12:07,555 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001bc6/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:29038', '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: 1098\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-15 15:12:07,572 - DEBUG - ya.test - execute: Command pid: 571905
2025-10-15 15:12:09,158 - DEBUG - ya.test - _save_outputs: Command (pid 571905) rc: 0
2025-10-15 15:12:09,158 - DEBUG - ya.test - _save_outputs: Command (pid 571905) elapsed time (sec): 1.58487868309021
2025-10-15 15:12:09,159 - DEBUG - ya.test - _save_outputs: Command (pid 571905) idrss: 0
2025-10-15 15:12:09,159 - DEBUG - ya.test - _save_outputs: Command (pid 571905) inblock: 0
2025-10-15 15:12:09,159 - DEBUG - ya.test - _save_outputs: Command (pid 571905) isrss: 0
2025-10-15 15:12:09,160 - DEBUG - ya.test - _save_outputs: Command (pid 571905) ixrss: 0
2025-10-15 15:12:09,160 - DEBUG - ya.test - _save_outputs: Command (pid 571905) majflt: 0
2025-10-15 15:12:09,160 - DEBUG - ya.test - _save_outputs: Command (pid 571905) maxrss: 584668
2025-10-15 15:12:09,161 - DEBUG - ya.test - _save_outputs: Command (pid 571905) minflt: 51380
2025-10-15 15:12:09,161 - DEBUG - ya.test - _save_outputs: Command (pid 571905) msgrcv: 0
2025-10-15 15:12:09,162 - DEBUG - ya.test - _save_outputs: Command (pid 571905) msgsnd: 0
2025-10-15 15:12:09,162 - DEBUG - ya.test - _save_outputs: Command (pid 571905) nivcsw: 254
2025-10-15 15:12:09,162 - DEBUG - ya.test - _save_outputs: Command (pid 571905) nsignals: 0
2025-10-15 15:12:09,162 - DEBUG - ya.test - _save_outputs: Command (pid 571905) nswap: 0
2025-10-15 15:12:09,163 - DEBUG - ya.test - _save_outputs: Command (pid 571905) nvcsw: 449
2025-10-15 15:12:09,163 - DEBUG - ya.test - _save_outputs: Command (pid 571905) oublock: 8
2025-10-15 15:12:09,163 - DEBUG - ya.test - _save_outputs: Command (pid 571905) stime: 0.313934
2025-10-15 15:12:09,164 - DEBUG - ya.test - _save_outputs: Command (pid 571905) utime: 0.5560109999999999
2025-10-15 15:12:09,164 - DEBUG - ya.test - _save_outputs: Command (pid 571905) wtime: 1.602
2025-10-15 15:12:09,164 - DEBUG - ya.test - _save_outputs: Command (pid 571905) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-15 15:12:09,164 - DEBUG - ya.test - _save_outputs: Command (pid 571905) errors:

2025-10-15 15:12:09,171 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001bc6/ydb/apps/ydbd/ydbd
2025-10-15 15:12:09,171 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2kl/001bc6/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:29038', '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-10-15 15:12:09,172 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-15 15:12:09,179 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-15 15:12:09,179 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001bc6/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:29038', '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-10-15 15:12:09,201 - DEBUG - ya.test - execute: Command pid: 572368
2025-10-15 15:12:10,606 - DEBUG - ya.test - _save_outputs: Command (pid 572368) rc: 0
2025-10-15 15:12:10,607 - DEBUG - ya.test - _save_outputs: Command (pid 572368) elapsed time (sec): 1.4041941165924072
2025-10-15 15:12:10,607 - DEBUG - ya.test - _save_outputs: Command (pid 572368) idrss: 0
2025-10-15 15:12:10,608 - DEBUG - ya.test - _save_outputs: Command (pid 572368) inblock: 0
2025-10-15 15:12:10,608 - DEBUG - ya.test - _save_outputs: Command (pid 572368) isrss: 0
2025-10-15 15:12:10,608 - DEBUG - ya.test - _save_outputs: Command (pid 572368) ixrss: 0
2025-10-15 15:12:10,608 - DEBUG - ya.test - _save_outputs: Command (pid 572368) majflt: 0
2025-10-15 15:12:10,609 - DEBUG - ya.test - _save_outputs: Command (pid 572368) maxrss: 585216
2025-10-15 15:12:10,609 - DEBUG - ya.test - _save_outputs: Command (pid 572368) minflt: 51465
2025-10-15 15:12:10,609 - DEBUG - ya.test - _save_outputs: Command (pid 572368) msgrcv: 0
2025-10-15 15:12:10,610 - DEBUG - ya.test - _save_outputs: Command (pid 572368) msgsnd: 0
2025-10-15 15:12:10,610 - DEBUG - ya.test - _save_outputs: Command (pid 572368) nivcsw: 245
2025-10-15 15:12:10,610 - DEBUG - ya.test - _save_outputs: Command (pid 572368) nsignals: 0
2025-10-15 15:12:10,611 - DEBUG - ya.test - _save_outputs: Command (pid 572368) nswap: 0
2025-10-15 15:12:10,611 - DEBUG - ya.test - _save_outputs: Command (pid 572368) nvcsw: 602
2025-10-15 15:12:10,611 - DEBUG - ya.test - _save_outputs: Command (pid 572368) oublock: 8
2025-10-15 15:12:10,612 - DEBUG - ya.test - _save_outputs: Command (pid 572368) stime: 0.267787
2025-10-15 15:12:10,612 - DEBUG - ya.test - _save_outputs: Command (pid 572368) utime: 0.605324
2025-10-15 15:12:10,612 - DEBUG - ya.test - _save_outputs: Command (pid 572368) wtime: 1.426
2025-10-15 15:12:10,613 - DEBUG - ya.test - _save_outputs: Command (pid 572368) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-15 15:12:10,613 - DEBUG - ya.test - _save_outputs: Command (pid 572368) errors:

2025-10-15 15:12:10,614 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-15 15:12:11,257 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-15 15:12:11,316 - DEBUG - ydb.connection - 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-10-15 15:12:11,339 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:12:11,339 - DEBUG - ydb.connection - 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-10-15 15:12:11,383 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:12:11,384 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 6a4812e8-ff2a-41db-af4a-784e31581661, localhost:29038): creating call state
2025-10-15 15:12:11,385 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 6a4812e8-ff2a-41db-af4a-784e31581661, localhost:29038): request = { database: "/Root" }
2025-10-15 15:12:11,403 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 6a4812e8-ff2a-41db-af4a-784e31581661, localhost:29038): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 29038 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-10-15 15:12:11,404 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:29038, location 1, ssl: False>]>
2025-10-15 15:12:11,405 - DEBUG - ydb.connection - 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-10-15 15:12:11,457 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:29038
2025-10-15 15:12:11,458 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-10-15 15:12:11,459 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 90dcd644-8f78-480c-b429-7d184c22e5fa, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:11,460 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 90dcd644-8f78-480c-b429-7d184c22e5fa, ghrun-lvxbqnxafi.auto.internal:29038): request = {  }
2025-10-15 15:12:11,474 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 90dcd644-8f78-480c-b429-7d184c22e5fa, ghrun-lvxbqnxafi.auto.internal:29038): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" node_id: 1 }
2025-10-15 15:12:11,475 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 190f4944-ba52-43f3-a3ff-9eab8c58b783, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:11,476 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 190f4944-ba52-43f3-a3ff-9eab8c58b783, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" }
2025-10-15 15:12:11,481 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 190f4944-ba52-43f3-a3ff-9eab8c58b783, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:11,500 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:11,501 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d424788b-47e4-44d3-8f77-ee2513b74c1a, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:11,502 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d424788b-47e4-44d3-8f77-ee2513b74c1a, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:11,504 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d424788b-47e4-44d3-8f77-ee2513b74c1a, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:16,299 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:16,300 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-10-15 15:12:16,300 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:16,301 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e59f633b-778a-427b-88f7-bc45cbd880f2, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:16,302 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e59f633b-778a-427b-88f7-bc45cbd880f2, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:16,307 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e59f633b-778a-427b-88f7-bc45cbd880f2, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:17,436 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:17,437 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:17,437 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 949a9845-85e3-4fc8-bc20-b3f30f6c2908, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:17,438 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 949a9845-85e3-4fc8-bc20-b3f30f6c2908, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:17,440 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 949a9845-85e3-4fc8-bc20-b3f30f6c2908, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:18,636 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:18,637 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:18,638 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3d6fa3ef-3e8b-43e1-8a75-e3980d8b74b4, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:18,639 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3d6fa3ef-3e8b-43e1-8a75-e3980d8b74b4, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:18,640 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3d6fa3ef-3e8b-43e1-8a75-e3980d8b74b4, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:19,526 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:19,527 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:19,528 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c4ac682b-6dc0-4e27-ad20-f233ae247ba9, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:19,528 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c4ac682b-6dc0-4e27-ad20-f233ae247ba9, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:19,530 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c4ac682b-6dc0-4e27-ad20-f233ae247ba9, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:21,431 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:21,432 - INFO - __tests__.alter_compression - 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-10-15 15:12:21,433 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:21,433 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8de0e58b-897f-42c2-816d-b12d751e630d, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:21,435 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8de0e58b-897f-42c2-816d-b12d751e630d, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:21,437 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8de0e58b-897f-42c2-816d-b12d751e630d, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:22,507 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:22,508 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:22,509 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1d1ac20b-1d9c-4bfe-aa2f-7e25b75737b9, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:22,510 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1d1ac20b-1d9c-4bfe-aa2f-7e25b75737b9, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:22,523 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1d1ac20b-1d9c-4bfe-aa2f-7e25b75737b9, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:22,770 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:22,771 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:22,772 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 345204cc-3d43-472e-89c5-a10621377d03, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:22,773 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 345204cc-3d43-472e-89c5-a10621377d03, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:22,775 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 345204cc-3d43-472e-89c5-a10621377d03, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:23,118 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:23,119 - INFO - __tests__.alter_compression - 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-10-15 15:12:23,120 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:23,121 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ba7b70d0-9362-4031-8d3f-a506e2774baf, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:23,122 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ba7b70d0-9362-4031-8d3f-a506e2774baf, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:23,133 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ba7b70d0-9362-4031-8d3f-a506e2774baf, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:24,270 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:24,270 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:24,271 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 95155a4e-316c-4b86-b9fd-9a83649718d9, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:24,272 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 95155a4e-316c-4b86-b9fd-9a83649718d9, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:24,284 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 95155a4e-316c-4b86-b9fd-9a83649718d9, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:24,811 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:24,812 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:24,813 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fdf50f47-c698-4227-972a-670ad4358b1e, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:24,814 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fdf50f47-c698-4227-972a-670ad4358b1e, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:24,829 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fdf50f47-c698-4227-972a-670ad4358b1e, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:25,463 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:25,464 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 303050, 'Portions': 188}}, blobs: {'__DEFAULT': {'Portions': 186, 'BlobSize': 4857280, 'BlobCount': 384}}
2025-10-15 15:12:25,464 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:25,465 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 10af7b47-1860-4a6e-a438-edf5eb4e2b55, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:25,466 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 10af7b47-1860-4a6e-a438-edf5eb4e2b55, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:25,468 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 10af7b47-1860-4a6e-a438-edf5eb4e2b55, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:26,938 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:26,939 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:26,940 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, db4b83e0-23b1-40a8-b12c-f8aad1c8d1dc, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:26,941 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, db4b83e0-23b1-40a8-b12c-f8aad1c8d1dc, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:26,942 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, db4b83e0-23b1-40a8-b12c-f8aad1c8d1dc, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:27,381 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:27,382 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:27,383 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b91071dd-d34d-4e5c-ac47-bd20b1301ed4, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:27,383 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b91071dd-d34d-4e5c-ac47-bd20b1301ed4, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:27,386 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b91071dd-d34d-4e5c-ac47-bd20b1301ed4, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:27,941 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:27,942 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 248}}, blobs: {'__DEFAULT': {'Portions': 248, 'BlobSize': 6475872, 'BlobCount': 510}}
2025-10-15 15:12:27,943 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:27,943 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fb14036d-cb15-42cf-8d58-b1953c5fc5bc, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:27,944 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fb14036d-cb15-42cf-8d58-b1953c5fc5bc, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:27,958 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fb14036d-cb15-42cf-8d58-b1953c5fc5bc, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:29,054 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:29,055 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:29,056 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f021067a-52cb-451e-8969-f541eb96e9dc, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:29,057 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f021067a-52cb-451e-8969-f541eb96e9dc, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:29,058 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f021067a-52cb-451e-8969-f541eb96e9dc, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:29,304 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:29,305 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:29,306 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd2d0105-d2ad-4a84-9136-5ca2407cd21e, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:29,307 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd2d0105-d2ad-4a84-9136-5ca2407cd21e, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:29,319 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd2d0105-d2ad-4a84-9136-5ca2407cd21e, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:29,959 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:29,960 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 309}}, blobs: {'__DEFAULT': {'Portions': 309, 'BlobSize': 8094280, 'BlobCount': 634}}
2025-10-15 15:12:29,960 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:29,961 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 625e7e86-fd4c-4dc8-9f58-bfed12ec3c40, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:29,962 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 625e7e86-fd4c-4dc8-9f58-bfed12ec3c40, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:29,966 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 625e7e86-fd4c-4dc8-9f58-bfed12ec3c40, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:31,238 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:31,239 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:31,239 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0e832006-2b84-4583-ba51-c45c193d3f65, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:31,240 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0e832006-2b84-4583-ba51-c45c193d3f65, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:31,246 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0e832006-2b84-4583-ba51-c45c193d3f65, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:31,610 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:31,611 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:31,611 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7240b2dc-331d-44df-9131-b5ad3209165c, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:31,622 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7240b2dc-331d-44df-9131-b5ad3209165c, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:31,624 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7240b2dc-331d-44df-9131-b5ad3209165c, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:32,141 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:32,142 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 615549, 'Portions': 367}}, blobs: {'__DEFAULT': {'Portions': 357, 'BlobSize': 9711944, 'BlobCount': 738}}
2025-10-15 15:12:32,142 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:32,143 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b93d04cc-6af8-4ed0-9ba4-c0a6f658011a, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:32,144 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b93d04cc-6af8-4ed0-9ba4-c0a6f658011a, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:32,147 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b93d04cc-6af8-4ed0-9ba4-c0a6f658011a, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:33,528 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:33,529 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:33,530 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e9413fa3-582c-458e-b6bb-d59445c1bb07, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:33,531 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e9413fa3-582c-458e-b6bb-d59445c1bb07, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:33,532 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e9413fa3-582c-458e-b6bb-d59445c1bb07, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:33,789 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:33,793 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:33,794 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f70e6b20-87c4-4b4b-b9f4-97b7e1964ad7, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:33,795 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f70e6b20-87c4-4b4b-b9f4-97b7e1964ad7, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:33,797 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f70e6b20-87c4-4b4b-b9f4-97b7e1964ad7, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:34,597 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:34,598 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 406}}, blobs: {'__DEFAULT': {'Portions': 406, 'BlobSize': 11329376, 'BlobCount': 842}}
2025-10-15 15:12:34,598 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:34,599 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a6d44ef1-4897-4469-a3cd-39927f898e46, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:34,600 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a6d44ef1-4897-4469-a3cd-39927f898e46, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:34,604 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a6d44ef1-4897-4469-a3cd-39927f898e46, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:35,713 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:35,714 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:35,715 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 647668a0-dafb-44b8-9924-fdd417b21b13, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:35,716 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 647668a0-dafb-44b8-9924-fdd417b21b13, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:35,718 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 647668a0-dafb-44b8-9924-fdd417b21b13, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:36,131 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:36,132 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:36,132 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1069c8ac-797c-428f-bfd2-a2ac9e94eae5, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:36,134 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1069c8ac-797c-428f-bfd2-a2ac9e94eae5, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:36,141 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1069c8ac-797c-428f-bfd2-a2ac9e94eae5, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:36,836 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:36,837 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 810871, 'Portions': 465}}, blobs: {'__DEFAULT': {'Portions': 458, 'BlobSize': 12946784, 'BlobCount': 950}}
2025-10-15 15:12:36,837 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:36,838 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bfbd538b-f1a9-422e-94b4-7c3c64f61291, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:36,840 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bfbd538b-f1a9-422e-94b4-7c3c64f61291, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:36,854 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bfbd538b-f1a9-422e-94b4-7c3c64f61291, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:38,127 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:38,128 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:38,129 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1ed6a967-de72-499c-8f2e-54f3b20353c8, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:38,130 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1ed6a967-de72-499c-8f2e-54f3b20353c8, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:38,132 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1ed6a967-de72-499c-8f2e-54f3b20353c8, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:38,474 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:38,475 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:38,475 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 815b410d-57d2-48d5-ac8a-466f46475a9a, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:38,476 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 815b410d-57d2-48d5-ac8a-466f46475a9a, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:38,477 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 815b410d-57d2-48d5-ac8a-466f46475a9a, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:39,146 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:39,147 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 912491, 'Portions': 509}}, blobs: {'__DEFAULT': {'Portions': 494, 'BlobSize': 14562496, 'BlobCount': 1030}}
2025-10-15 15:12:39,148 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:39,148 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8a2804c5-00de-4029-9a4e-2b1c1a8108ea, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:39,150 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8a2804c5-00de-4029-9a4e-2b1c1a8108ea, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:39,152 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8a2804c5-00de-4029-9a4e-2b1c1a8108ea, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:40,381 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:40,381 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:40,382 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cd7c2f98-9cc6-4258-be6c-8a3ca9429049, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:40,383 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cd7c2f98-9cc6-4258-be6c-8a3ca9429049, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:40,385 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cd7c2f98-9cc6-4258-be6c-8a3ca9429049, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:40,802 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:40,803 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:40,804 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 52347cfe-6947-4ec5-a5f9-0084fe46f0cf, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:40,805 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 52347cfe-6947-4ec5-a5f9-0084fe46f0cf, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:40,822 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 52347cfe-6947-4ec5-a5f9-0084fe46f0cf, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:41,566 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:41,567 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 550}}, blobs: {'__DEFAULT': {'Portions': 550, 'BlobSize': 16180056, 'BlobCount': 1144}}
2025-10-15 15:12:41,568 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:41,568 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c573fabe-f3cd-4a7a-a8df-5034fb6fbf99, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:41,569 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c573fabe-f3cd-4a7a-a8df-5034fb6fbf99, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:41,570 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c573fabe-f3cd-4a7a-a8df-5034fb6fbf99, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:42,707 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:42,708 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:42,709 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6b13a408-6674-4597-8a7a-9ea7cecc5fc6, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:42,710 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6b13a408-6674-4597-8a7a-9ea7cecc5fc6, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:42,716 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6b13a408-6674-4597-8a7a-9ea7cecc5fc6, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:43,123 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:43,124 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:43,125 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8b1fec7e-bf2e-4316-abf3-87b47bcba9bc, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:43,126 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8b1fec7e-bf2e-4316-abf3-87b47bcba9bc, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:43,127 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8b1fec7e-bf2e-4316-abf3-87b47bcba9bc, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:43,459 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:43,460 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:43,461 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 51f9e173-b712-45f4-b249-31536fec1e02, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:43,462 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 51f9e173-b712-45f4-b249-31536fec1e02, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:43,464 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 51f9e173-b712-45f4-b249-31536fec1e02, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:48,969 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:59,005 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:12:59,006 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7051ee9c-46fa-4702-9b40-bcfd78a246ae, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:12:59,007 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7051ee9c-46fa-4702-9b40-bcfd78a246ae, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:59,008 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7051ee9c-46fa-4702-9b40-bcfd78a246ae, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:05,007 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:05,081 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8079200)
2025-10-15 15:13:05,081 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:05,082 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3cbc3fb8-d91e-4ba7-b726-919cb7b142ab, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:13:05,083 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3cbc3fb8-d91e-4ba7-b726-919cb7b142ab, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:05,086 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3cbc3fb8-d91e-4ba7-b726-919cb7b142ab, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:09,385 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:19,418 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:19,419 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 50040252-a641-4f97-9645-33ee37667e7e, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:13:19,420 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 50040252-a641-4f97-9645-33ee37667e7e, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:19,422 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 50040252-a641-4f97-9645-33ee37667e7e, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:21,547 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:22,744 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:13:22,745 - DEBUG - ydb.connection - 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-10-15 15:13:22,770 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:13:22,771 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 98dd7bf6-581e-482a-8432-155958d03223, localhost:29038): creating call state
2025-10-15 15:13:22,771 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 98dd7bf6-581e-482a-8432-155958d03223, localhost:29038): request = { database: "/Root" }
2025-10-15 15:13:22,781 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 98dd7bf6-581e-482a-8432-155958d03223, localhost:29038): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 29038 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-10-15 15:13:22,782 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:29038, location 1, ssl: False>]>
2025-10-15 15:13:22,783 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:29038
2025-10-15 15:13:31,565 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:31,566 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b009474c-c98f-4d15-8467-d23d0091668a, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:13:31,567 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b009474c-c98f-4d15-8467-d23d0091668a, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:31,568 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b009474c-c98f-4d15-8467-d23d0091668a, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:33,060 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:43,071 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:43,073 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 790853e3-21eb-4ce3-ad6d-e4ae152fa4f9, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:13:43,073 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 790853e3-21eb-4ce3-ad6d-e4ae152fa4f9, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:43,075 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 790853e3-21eb-4ce3-ad6d-e4ae152fa4f9, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:45,273 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:45,290 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8027360)
2025-10-15 15:13:45,291 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:45,292 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0c16d9b5-467d-427d-98a7-cd34e802e62c, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:13:45,293 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0c16d9b5-467d-427d-98a7-cd34e802e62c, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:45,294 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0c16d9b5-467d-427d-98a7-cd34e802e62c, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:45,807 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:45,811 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_2_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 2])
2025-10-15 15:13:45,812 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:45,822 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fbc4cbbf-b65e-48c0-b49a-9c52892b7ddd, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:13:45,823 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fbc4cbbf-b65e-48c0-b49a-9c52892b7ddd, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_2_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 2)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:45,825 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fbc4cbbf-b65e-48c0-b49a-9c52892b7ddd, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:50,719 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:50,720 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_2_compression created
2025-10-15 15:13:50,720 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:50,721 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aa5a0e53-5136-4a63-8840-62dd4f3a5f5c, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:13:50,722 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aa5a0e53-5136-4a63-8840-62dd4f3a5f5c, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_2_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:50,724 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aa5a0e53-5136-4a63-8840-62dd4f3a5f5c, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:51,861 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:51,861 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:51,862 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 70b8228f-01dd-4a43-adf8-2c4ff8076ec5, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:13:51,863 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 70b8228f-01dd-4a43-adf8-2c4ff8076ec5, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:51,864 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 70b8228f-01dd-4a43-adf8-2c4ff8076ec5, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:54,026 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:54,027 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:54,028 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cdab2555-af1b-4418-bbd7-774e9a4db48f, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:13:54,029 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cdab2555-af1b-4418-bbd7-774e9a4db48f, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:54,037 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cdab2555-af1b-4418-bbd7-774e9a4db48f, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:55,174 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:55,175 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:55,176 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9b5630d5-5f9c-4a3e-808a-325591a271a3, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:13:55,176 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9b5630d5-5f9c-4a3e-808a-325591a271a3, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:55,185 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9b5630d5-5f9c-4a3e-808a-325591a271a3, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:57,607 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:57,607 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318880, 'BlobCount': 128}}
2025-10-15 15:13:57,608 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:57,609 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bd9719c6-eb37-4f5c-8294-4d5c9f9fb6fd, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:13:57,610 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bd9719c6-eb37-4f5c-8294-4d5c9f9fb6fd, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:57,622 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bd9719c6-eb37-4f5c-8294-4d5c9f9fb6fd, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:58,989 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:58,990 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:58,990 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7539c969-3d2c-4586-afe2-bf8674a9d5cc, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:13:58,991 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7539c969-3d2c-4586-afe2-bf8674a9d5cc, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:58,992 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7539c969-3d2c-4586-afe2-bf8674a9d5cc, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:59,321 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:59,322 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:59,323 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b6d26a87-f04a-4034-a194-754c6d34e6dd, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:13:59,324 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b6d26a87-f04a-4034-a194-754c6d34e6dd, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:59,326 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b6d26a87-f04a-4034-a194-754c6d34e6dd, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:59,889 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:59,890 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638656, 'BlobCount': 256}}
2025-10-15 15:13:59,891 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:13:59,892 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c0ddb117-5bf2-4262-9e5f-866379da2a53, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:13:59,897 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c0ddb117-5bf2-4262-9e5f-866379da2a53, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:59,899 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c0ddb117-5bf2-4262-9e5f-866379da2a53, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:01,448 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:01,449 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:01,449 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f38bf127-ebb8-411d-9799-b189d7e18857, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:01,451 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f38bf127-ebb8-411d-9799-b189d7e18857, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:01,454 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f38bf127-ebb8-411d-9799-b189d7e18857, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:01,894 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:01,895 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:01,896 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 17092dbc-0d2d-4a44-a636-587fff73fb00, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:01,897 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 17092dbc-0d2d-4a44-a636-587fff73fb00, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:01,899 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 17092dbc-0d2d-4a44-a636-587fff73fb00, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:02,416 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:02,417 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 184}}, blobs: {'__DEFAULT': {'Portions': 184, 'BlobSize': 959216, 'BlobCount': 384}}
2025-10-15 15:14:02,418 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:02,419 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e12a5b9-21c4-4d5c-bd34-5e414f03ebc3, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:02,420 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e12a5b9-21c4-4d5c-bd34-5e414f03ebc3, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:02,423 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e12a5b9-21c4-4d5c-bd34-5e414f03ebc3, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:03,922 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:03,923 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:03,924 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a5a0c9d3-c0ef-449c-b370-220069c8e2cc, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:03,925 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a5a0c9d3-c0ef-449c-b370-220069c8e2cc, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:03,928 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a5a0c9d3-c0ef-449c-b370-220069c8e2cc, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:04,475 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:04,476 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:04,477 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fbdd97e3-9632-4f55-a930-ae2ce9bdc466, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:04,478 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fbdd97e3-9632-4f55-a930-ae2ce9bdc466, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:04,489 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fbdd97e3-9632-4f55-a930-ae2ce9bdc466, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:05,240 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:05,241 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 238}}, blobs: {'__DEFAULT': {'Portions': 238, 'BlobSize': 1278016, 'BlobCount': 502}}
2025-10-15 15:14:05,242 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:05,242 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 31183404-862e-4566-9219-831302f17551, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:05,244 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 31183404-862e-4566-9219-831302f17551, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:05,246 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 31183404-862e-4566-9219-831302f17551, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:07,223 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:07,224 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:07,225 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 96f85563-d5cd-46ad-b5df-386aa3347fdf, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:07,226 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 96f85563-d5cd-46ad-b5df-386aa3347fdf, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:07,228 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 96f85563-d5cd-46ad-b5df-386aa3347fdf, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:07,802 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:07,803 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:07,804 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8cf4557b-9a49-4ddb-8078-78de0f5102ea, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:07,805 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8cf4557b-9a49-4ddb-8078-78de0f5102ea, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:07,811 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8cf4557b-9a49-4ddb-8078-78de0f5102ea, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:08,425 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:08,426 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 506340, 'Portions': 297}}, blobs: {'__DEFAULT': {'Portions': 293, 'BlobSize': 1595864, 'BlobCount': 618}}
2025-10-15 15:14:08,427 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:08,427 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f513d48d-5007-4aeb-b3a7-2235d452cd7f, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:08,428 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f513d48d-5007-4aeb-b3a7-2235d452cd7f, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:08,438 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f513d48d-5007-4aeb-b3a7-2235d452cd7f, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:09,781 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:09,782 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:09,783 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 489ea0ba-01ce-495b-b245-18e3e998e0ea, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:09,783 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 489ea0ba-01ce-495b-b245-18e3e998e0ea, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:09,789 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 489ea0ba-01ce-495b-b245-18e3e998e0ea, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:10,190 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:10,191 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:10,192 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c5896add-fab1-4621-a68b-afb077ea8595, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:10,193 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c5896add-fab1-4621-a68b-afb077ea8595, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:10,195 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c5896add-fab1-4621-a68b-afb077ea8595, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:10,927 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:10,928 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 337}}, blobs: {'__DEFAULT': {'Portions': 337, 'BlobSize': 1911064, 'BlobCount': 716}}
2025-10-15 15:14:10,928 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:10,929 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a2063b10-9c91-4eaa-946a-20ca9ec7eb53, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:10,930 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a2063b10-9c91-4eaa-946a-20ca9ec7eb53, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:10,941 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a2063b10-9c91-4eaa-946a-20ca9ec7eb53, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:12,406 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:12,407 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:12,407 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b6b2c64e-1fdd-4191-9a71-5c8ea1181d42, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:12,408 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b6b2c64e-1fdd-4191-9a71-5c8ea1181d42, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:12,410 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b6b2c64e-1fdd-4191-9a71-5c8ea1181d42, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:12,800 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:12,801 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:12,802 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bef6c198-86ae-45b3-b939-530cf9946645, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:12,803 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bef6c198-86ae-45b3-b939-530cf9946645, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:12,804 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bef6c198-86ae-45b3-b939-530cf9946645, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:13,436 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:13,437 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 396}}, blobs: {'__DEFAULT': {'Portions': 396, 'BlobSize': 2229560, 'BlobCount': 836}}
2025-10-15 15:14:13,438 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:13,438 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1ff176bb-b63c-43d2-b05b-0d8278ed6931, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:13,439 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1ff176bb-b63c-43d2-b05b-0d8278ed6931, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:13,442 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1ff176bb-b63c-43d2-b05b-0d8278ed6931, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:14,974 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:14,975 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:14,975 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a6283476-6e81-4f9a-b844-16b3b097bab3, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:14,976 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a6283476-6e81-4f9a-b844-16b3b097bab3, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:14,978 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a6283476-6e81-4f9a-b844-16b3b097bab3, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:15,421 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:15,422 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:15,423 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b781d648-f664-41bc-9619-c19941b31d48, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:15,425 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b781d648-f664-41bc-9619-c19941b31d48, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:15,427 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b781d648-f664-41bc-9619-c19941b31d48, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:16,318 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:16,319 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2546216, 'BlobCount': 944}}
2025-10-15 15:14:16,319 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:16,320 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e31dc628-be4c-49d0-8f07-7bee5b99b05f, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:16,326 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e31dc628-be4c-49d0-8f07-7bee5b99b05f, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:16,328 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e31dc628-be4c-49d0-8f07-7bee5b99b05f, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:17,958 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:17,958 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:17,959 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 402290c5-8c4e-4c5e-9ab3-84e16c68325d, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:17,960 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 402290c5-8c4e-4c5e-9ab3-84e16c68325d, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:17,965 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 402290c5-8c4e-4c5e-9ab3-84e16c68325d, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:18,576 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:18,576 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:18,577 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1eb300d4-9a35-49d2-b97d-41a33ddd1212, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:18,578 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1eb300d4-9a35-49d2-b97d-41a33ddd1212, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:18,581 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1eb300d4-9a35-49d2-b97d-41a33ddd1212, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:19,454 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:19,454 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2865528, 'BlobCount': 1072}}
2025-10-15 15:14:19,455 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:19,456 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6bebcac6-c09c-4ce1-8b5e-1ceaa8e28002, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:19,457 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6bebcac6-c09c-4ce1-8b5e-1ceaa8e28002, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:19,459 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6bebcac6-c09c-4ce1-8b5e-1ceaa8e28002, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:20,879 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:20,880 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:20,881 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 51fb31f4-8db1-4076-9708-55bde4c2f0ea, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:20,881 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 51fb31f4-8db1-4076-9708-55bde4c2f0ea, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:20,883 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 51fb31f4-8db1-4076-9708-55bde4c2f0ea, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:21,308 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:21,309 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:21,311 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 667bc188-006e-4a0e-bbc0-598156171dcf, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:21,312 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 667bc188-006e-4a0e-bbc0-598156171dcf, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:21,313 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 667bc188-006e-4a0e-bbc0-598156171dcf, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:22,163 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:22,164 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 1014076, 'Portions': 546}}, blobs: {'__DEFAULT': {'Portions': 537, 'BlobSize': 3174600, 'BlobCount': 1132}}
2025-10-15 15:14:22,164 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:22,165 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a2286dbb-a5a0-4a8a-a983-2a40446ec9d4, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:22,166 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a2286dbb-a5a0-4a8a-a983-2a40446ec9d4, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_2_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:22,167 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a2286dbb-a5a0-4a8a-a983-2a40446ec9d4, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:23,542 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:23,543 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:23,544 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, adb5684f-9a08-4e40-96e4-765f53fbc01e, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:23,545 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, adb5684f-9a08-4e40-96e4-765f53fbc01e, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:23,546 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, adb5684f-9a08-4e40-96e4-765f53fbc01e, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:23,925 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:23,926 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:23,927 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e6bb51ce-0962-43f3-818c-80e69b046ce7, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:23,928 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e6bb51ce-0962-43f3-818c-80e69b046ce7, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:23,930 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e6bb51ce-0962-43f3-818c-80e69b046ce7, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:24,341 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:24,342 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:24,343 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 652557aa-f14d-474d-a0ae-9d30d38beb82, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:24,344 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 652557aa-f14d-474d-a0ae-9d30d38beb82, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:24,346 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 652557aa-f14d-474d-a0ae-9d30d38beb82, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:29,006 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:39,061 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:39,062 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 99d6db97-42ab-4f55-b2c6-982795a0730b, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:39,063 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 99d6db97-42ab-4f55-b2c6-982795a0730b, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:39,064 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 99d6db97-42ab-4f55-b2c6-982795a0730b, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:42,845 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:42,887 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_2_compression`, volumes `value` (8000000, 1574024)
2025-10-15 15:14:42,888 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:42,888 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a6a02d8e-3225-4803-847f-1bd5286ae671, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:42,890 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a6a02d8e-3225-4803-847f-1bd5286ae671, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:42,891 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a6a02d8e-3225-4803-847f-1bd5286ae671, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:43,432 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:43,432 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:43,433 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fb17fdd6-f9e5-4c8c-8de1-c7fe5dd89fca, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:43,434 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fb17fdd6-f9e5-4c8c-8de1-c7fe5dd89fca, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:43,436 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fb17fdd6-f9e5-4c8c-8de1-c7fe5dd89fca, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:47,154 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:57,193 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:57,194 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 00fd63a7-ff0f-49e1-9f91-bf7451df380c, ghrun-lvxbqnxafi.auto.internal:29038): creating call state
2025-10-15 15:14:57,195 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 00fd63a7-ff0f-49e1-9f91-bf7451df380c, ghrun-lvxbqnxafi.auto.internal:29038): request = { session_id: "ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:57,196 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 00fd63a7-ff0f-49e1-9f91-bf7451df380c, ghrun-lvxbqnxafi.auto.internal:29038): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:57,991 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:14:57,992 - DEBUG - ydb.connection - 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-10-15 15:14:58,026 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:14:58,027 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 78c4965e-70f0-45eb-bf70-2b633e872b8f, localhost:29038): creating call state
2025-10-15 15:14:58,028 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 78c4965e-70f0-45eb-bf70-2b633e872b8f, localhost:29038): request = { database: "/Root" }
2025-10-15 15:14:58,047 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 78c4965e-70f0-45eb-bf70-2b633e872b8f, localhost:29038): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 29038 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-10-15 15:14:58,048 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:29038, location 1, ssl: False>]>
2025-10-15 15:14:58,048 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:29038
2025-10-15 15:14:58,566 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzcyNzc1NDEtNGZhM2EzYTgtMjRjMjE4YzMtYjI3M2Y0OTQ=
2025-10-15 15:14:58,582 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_2_compression`, volumes `value` (8000000, 1505744)
2025-10-15 15:14:58,582 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_2_compression` 8079200 / 1505744: 5.365586713279282
2025-10-15 15:14:58,585 - INFO - ya.test - pytest_runtest_teardown: Test teardown
