2025-10-15 15:11:56,533 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:11:56,533 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_compression-COMPRESSION = "zstd"]
2025-10-15 15:11:56,534 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:11:56,534 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-15 15:11:56,536 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-15 15:11:56,537 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-15 15:11:56,538 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001bda/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:11:56,543 - DEBUG - ya.test - execute: Command pid: 568654
2025-10-15 15:11:57,573 - DEBUG - ya.test - _save_outputs: Command (pid 568654) rc: 0
2025-10-15 15:11:57,574 - DEBUG - ya.test - _save_outputs: Command (pid 568654) elapsed time (sec): 1.0287926197052002
2025-10-15 15:11:57,574 - DEBUG - ya.test - _save_outputs: Command (pid 568654) idrss: 0
2025-10-15 15:11:57,574 - DEBUG - ya.test - _save_outputs: Command (pid 568654) inblock: 0
2025-10-15 15:11:57,575 - DEBUG - ya.test - _save_outputs: Command (pid 568654) isrss: 0
2025-10-15 15:11:57,575 - DEBUG - ya.test - _save_outputs: Command (pid 568654) ixrss: 0
2025-10-15 15:11:57,575 - DEBUG - ya.test - _save_outputs: Command (pid 568654) majflt: 0
2025-10-15 15:11:57,575 - DEBUG - ya.test - _save_outputs: Command (pid 568654) maxrss: 474556
2025-10-15 15:11:57,576 - DEBUG - ya.test - _save_outputs: Command (pid 568654) minflt: 45049
2025-10-15 15:11:57,576 - DEBUG - ya.test - _save_outputs: Command (pid 568654) msgrcv: 0
2025-10-15 15:11:57,576 - DEBUG - ya.test - _save_outputs: Command (pid 568654) msgsnd: 0
2025-10-15 15:11:57,577 - DEBUG - ya.test - _save_outputs: Command (pid 568654) nivcsw: 200
2025-10-15 15:11:57,578 - DEBUG - ya.test - _save_outputs: Command (pid 568654) nsignals: 0
2025-10-15 15:11:57,578 - DEBUG - ya.test - _save_outputs: Command (pid 568654) nswap: 0
2025-10-15 15:11:57,578 - DEBUG - ya.test - _save_outputs: Command (pid 568654) nvcsw: 13
2025-10-15 15:11:57,579 - DEBUG - ya.test - _save_outputs: Command (pid 568654) oublock: 8
2025-10-15 15:11:57,579 - DEBUG - ya.test - _save_outputs: Command (pid 568654) stime: 0.265719
2025-10-15 15:11:57,579 - DEBUG - ya.test - _save_outputs: Command (pid 568654) utime: 0.49088499999999996
2025-10-15 15:11:57,580 - DEBUG - ya.test - _save_outputs: Command (pid 568654) wtime: 1.035
2025-10-15 15:11:57,580 - DEBUG - ya.test - _save_outputs: Command (pid 568654) 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:11:57,580 - DEBUG - ya.test - _save_outputs: Command (pid 568654) errors:

2025-10-15 15:11:57,587 - 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:11:57,756 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001bda/ydb/apps/ydbd/ydbd
2025-10-15 15:11:57,758 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15790
2025-10-15 15:11:57,760 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/w2kl/001bda/r3tmp/kikimr_cluster_shpq40qu
2025-10-15 15:11:57,817 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001bda/ydb/apps/ydbd/ydbd
2025-10-15 15:11:57,818 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15201
2025-10-15 15:11:57,818 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15320
2025-10-15 15:11:57,819 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28771
2025-10-15 15:11:57,820 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27996
2025-10-15 15:11:57,820 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27997
2025-10-15 15:11:57,821 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001bda/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/kikimr_configs"
2025-10-15 15:11:57,822 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001bda/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/001bda/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/node_1/logfile_dcsg74sa.log --grpc-port=15201 --mon-port=15320 --ic-port=15790
2025-10-15 15:11:57,822 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:15201/1, disk_size 68719476736
2025-10-15 15:11:57,823 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001bda/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/kikimr_configs"
2025-10-15 15:11:57,823 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001bda/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/001bda/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/node_1/logfile_dcsg74sa.log --grpc-port=15201 --mon-port=15320 --ic-port=15790
2025-10-15 15:11:57,824 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/w2kl/001bda/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/w2kl/001bda/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/w2kl/001bda/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/node_1/logfile_dcsg74sa.log', '--grpc-port=15201', '--mon-port=15320', '--ic-port=15790')' in '/home/runner/.ya/build/build_root/w2kl/001bda/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/node_1' (no wait)
2025-10-15 15:11:57,837 - DEBUG - ya.test - execute: Command pid: 569026
2025-10-15 15:11:57,838 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:15201/1
2025-10-15 15:11:57,840 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-15 15:11:57,840 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-15 15:11:57,841 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001bda/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:11:57,868 - DEBUG - ya.test - execute: Command pid: 569028
2025-10-15 15:11:58,931 - DEBUG - ya.test - _save_outputs: Command (pid 569028) rc: 0
2025-10-15 15:11:58,932 - DEBUG - ya.test - _save_outputs: Command (pid 569028) elapsed time (sec): 1.0626027584075928
2025-10-15 15:11:58,932 - DEBUG - ya.test - _save_outputs: Command (pid 569028) idrss: 0
2025-10-15 15:11:58,933 - DEBUG - ya.test - _save_outputs: Command (pid 569028) inblock: 0
2025-10-15 15:11:58,933 - DEBUG - ya.test - _save_outputs: Command (pid 569028) isrss: 0
2025-10-15 15:11:58,933 - DEBUG - ya.test - _save_outputs: Command (pid 569028) ixrss: 0
2025-10-15 15:11:58,934 - DEBUG - ya.test - _save_outputs: Command (pid 569028) majflt: 0
2025-10-15 15:11:58,934 - DEBUG - ya.test - _save_outputs: Command (pid 569028) maxrss: 483312
2025-10-15 15:11:58,934 - DEBUG - ya.test - _save_outputs: Command (pid 569028) minflt: 45155
2025-10-15 15:11:58,935 - DEBUG - ya.test - _save_outputs: Command (pid 569028) msgrcv: 0
2025-10-15 15:11:58,935 - DEBUG - ya.test - _save_outputs: Command (pid 569028) msgsnd: 0
2025-10-15 15:11:58,935 - DEBUG - ya.test - _save_outputs: Command (pid 569028) nivcsw: 228
2025-10-15 15:11:58,935 - DEBUG - ya.test - _save_outputs: Command (pid 569028) nsignals: 0
2025-10-15 15:11:58,936 - DEBUG - ya.test - _save_outputs: Command (pid 569028) nswap: 0
2025-10-15 15:11:58,936 - DEBUG - ya.test - _save_outputs: Command (pid 569028) nvcsw: 14
2025-10-15 15:11:58,936 - DEBUG - ya.test - _save_outputs: Command (pid 569028) oublock: 8
2025-10-15 15:11:58,943 - DEBUG - ya.test - _save_outputs: Command (pid 569028) stime: 0.213147
2025-10-15 15:11:58,943 - DEBUG - ya.test - _save_outputs: Command (pid 569028) utime: 0.505146
2025-10-15 15:11:58,943 - DEBUG - ya.test - _save_outputs: Command (pid 569028) wtime: 1.09
2025-10-15 15:11:58,944 - DEBUG - ya.test - _save_outputs: Command (pid 569028) 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:11:58,944 - DEBUG - ya.test - _save_outputs: Command (pid 569028) errors:

2025-10-15 15:11:58,945 - 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:04,936 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001bda/ydb/apps/ydbd/ydbd
2025-10-15 15:12:04,937 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2kl/001bda/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:15201', '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: 15790\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-15 15:12:04,938 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-15 15:12:04,939 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-15 15:12:04,939 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001bda/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:15201', '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: 15790\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-15 15:12:04,965 - DEBUG - ya.test - execute: Command pid: 570840
2025-10-15 15:12:06,342 - DEBUG - ya.test - _save_outputs: Command (pid 570840) rc: 0
2025-10-15 15:12:06,342 - DEBUG - ya.test - _save_outputs: Command (pid 570840) elapsed time (sec): 1.3757684230804443
2025-10-15 15:12:06,343 - DEBUG - ya.test - _save_outputs: Command (pid 570840) idrss: 0
2025-10-15 15:12:06,343 - DEBUG - ya.test - _save_outputs: Command (pid 570840) inblock: 0
2025-10-15 15:12:06,343 - DEBUG - ya.test - _save_outputs: Command (pid 570840) isrss: 0
2025-10-15 15:12:06,343 - DEBUG - ya.test - _save_outputs: Command (pid 570840) ixrss: 0
2025-10-15 15:12:06,344 - DEBUG - ya.test - _save_outputs: Command (pid 570840) majflt: 0
2025-10-15 15:12:06,344 - DEBUG - ya.test - _save_outputs: Command (pid 570840) maxrss: 588876
2025-10-15 15:12:06,344 - DEBUG - ya.test - _save_outputs: Command (pid 570840) minflt: 51417
2025-10-15 15:12:06,345 - DEBUG - ya.test - _save_outputs: Command (pid 570840) msgrcv: 0
2025-10-15 15:12:06,350 - DEBUG - ya.test - _save_outputs: Command (pid 570840) msgsnd: 0
2025-10-15 15:12:06,350 - DEBUG - ya.test - _save_outputs: Command (pid 570840) nivcsw: 247
2025-10-15 15:12:06,350 - DEBUG - ya.test - _save_outputs: Command (pid 570840) nsignals: 0
2025-10-15 15:12:06,350 - DEBUG - ya.test - _save_outputs: Command (pid 570840) nswap: 0
2025-10-15 15:12:06,351 - DEBUG - ya.test - _save_outputs: Command (pid 570840) nvcsw: 500
2025-10-15 15:12:06,351 - DEBUG - ya.test - _save_outputs: Command (pid 570840) oublock: 8
2025-10-15 15:12:06,351 - DEBUG - ya.test - _save_outputs: Command (pid 570840) stime: 0.29911699999999997
2025-10-15 15:12:06,352 - DEBUG - ya.test - _save_outputs: Command (pid 570840) utime: 0.636359
2025-10-15 15:12:06,352 - DEBUG - ya.test - _save_outputs: Command (pid 570840) wtime: 1.398
2025-10-15 15:12:06,352 - DEBUG - ya.test - _save_outputs: Command (pid 570840) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-15 15:12:06,352 - DEBUG - ya.test - _save_outputs: Command (pid 570840) errors:

2025-10-15 15:12:06,363 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001bda/ydb/apps/ydbd/ydbd
2025-10-15 15:12:06,363 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2kl/001bda/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:15201', '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:06,364 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-15 15:12:06,376 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-15 15:12:06,377 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001bda/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:15201', '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:06,389 - DEBUG - ya.test - execute: Command pid: 571456
2025-10-15 15:12:07,677 - DEBUG - ya.test - _save_outputs: Command (pid 571456) rc: 0
2025-10-15 15:12:07,678 - DEBUG - ya.test - _save_outputs: Command (pid 571456) elapsed time (sec): 1.2871825695037842
2025-10-15 15:12:07,678 - DEBUG - ya.test - _save_outputs: Command (pid 571456) idrss: 0
2025-10-15 15:12:07,679 - DEBUG - ya.test - _save_outputs: Command (pid 571456) inblock: 0
2025-10-15 15:12:07,679 - DEBUG - ya.test - _save_outputs: Command (pid 571456) isrss: 0
2025-10-15 15:12:07,679 - DEBUG - ya.test - _save_outputs: Command (pid 571456) ixrss: 0
2025-10-15 15:12:07,679 - DEBUG - ya.test - _save_outputs: Command (pid 571456) majflt: 0
2025-10-15 15:12:07,680 - DEBUG - ya.test - _save_outputs: Command (pid 571456) maxrss: 589480
2025-10-15 15:12:07,680 - DEBUG - ya.test - _save_outputs: Command (pid 571456) minflt: 51457
2025-10-15 15:12:07,680 - DEBUG - ya.test - _save_outputs: Command (pid 571456) msgrcv: 0
2025-10-15 15:12:07,681 - DEBUG - ya.test - _save_outputs: Command (pid 571456) msgsnd: 0
2025-10-15 15:12:07,681 - DEBUG - ya.test - _save_outputs: Command (pid 571456) nivcsw: 230
2025-10-15 15:12:07,681 - DEBUG - ya.test - _save_outputs: Command (pid 571456) nsignals: 0
2025-10-15 15:12:07,681 - DEBUG - ya.test - _save_outputs: Command (pid 571456) nswap: 0
2025-10-15 15:12:07,682 - DEBUG - ya.test - _save_outputs: Command (pid 571456) nvcsw: 517
2025-10-15 15:12:07,682 - DEBUG - ya.test - _save_outputs: Command (pid 571456) oublock: 8
2025-10-15 15:12:07,682 - DEBUG - ya.test - _save_outputs: Command (pid 571456) stime: 0.28565599999999997
2025-10-15 15:12:07,682 - DEBUG - ya.test - _save_outputs: Command (pid 571456) utime: 0.5498
2025-10-15 15:12:07,683 - DEBUG - ya.test - _save_outputs: Command (pid 571456) wtime: 1.3
2025-10-15 15:12:07,683 - DEBUG - ya.test - _save_outputs: Command (pid 571456) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-15 15:12:07,683 - DEBUG - ya.test - _save_outputs: Command (pid 571456) errors:

2025-10-15 15:12:07,684 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-15 15:12:07,870 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-15 15:12:07,942 - 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:07,962 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:12:07,962 - 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:08,029 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:12:08,030 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 16412d53-353e-40eb-9fcc-f2e31b1a4133, localhost:15201): creating call state
2025-10-15 15:12:08,031 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 16412d53-353e-40eb-9fcc-f2e31b1a4133, localhost:15201): request = { database: "/Root" }
2025-10-15 15:12:08,039 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 16412d53-353e-40eb-9fcc-f2e31b1a4133, localhost:15201): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 15201 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:08,040 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:15201, location 1, ssl: False>]>
2025-10-15 15:12:08,041 - 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:08,081 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:15201
2025-10-15 15:12:08,082 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-10-15 15:12:08,083 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 25a6804d-3412-4019-ac20-f8f88920029a, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:08,084 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 25a6804d-3412-4019-ac20-f8f88920029a, ghrun-lvxbqnxafi.auto.internal:15201): request = {  }
2025-10-15 15:12:08,091 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 25a6804d-3412-4019-ac20-f8f88920029a, ghrun-lvxbqnxafi.auto.internal:15201): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" node_id: 1 }
2025-10-15 15:12:08,092 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 3d48b2c7-a504-4737-a25f-42215910f7eb, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:08,093 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 3d48b2c7-a504-4737-a25f-42215910f7eb, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" }
2025-10-15 15:12:08,105 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 3d48b2c7-a504-4737-a25f-42215910f7eb, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:08,138 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:08,139 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 146fcb41-1d22-442e-9d44-f2d0200a0b95, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:08,140 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 146fcb41-1d22-442e-9d44-f2d0200a0b95, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:08,141 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 146fcb41-1d22-442e-9d44-f2d0200a0b95, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:12,488 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:12,489 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-10-15 15:12:12,490 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:12,491 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3b5aa65b-8588-46fd-90a1-70612366c80c, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:12,491 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3b5aa65b-8588-46fd-90a1-70612366c80c, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:12,494 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3b5aa65b-8588-46fd-90a1-70612366c80c, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:14,187 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:14,188 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:14,189 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 573ebea1-cdef-4932-b12f-7ff4c869807c, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:14,190 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 573ebea1-cdef-4932-b12f-7ff4c869807c, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:14,191 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 573ebea1-cdef-4932-b12f-7ff4c869807c, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:16,187 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:16,187 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:16,188 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 698cae85-5ff7-4b4b-b097-e328f42dbaa5, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:16,189 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 698cae85-5ff7-4b4b-b097-e328f42dbaa5, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:16,190 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 698cae85-5ff7-4b4b-b097-e328f42dbaa5, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:17,982 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:17,983 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:17,983 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 362cb255-77de-4758-9c2b-48be0d313618, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:17,984 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 362cb255-77de-4758-9c2b-48be0d313618, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:17,987 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 362cb255-77de-4758-9c2b-48be0d313618, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:20,463 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:20,464 - 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:20,464 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:20,465 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 95daa014-727a-4002-99ac-e02e972eaa8c, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:20,466 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 95daa014-727a-4002-99ac-e02e972eaa8c, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:20,467 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 95daa014-727a-4002-99ac-e02e972eaa8c, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:21,479 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:21,480 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:21,480 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e0409ade-b461-4433-b7ce-09e6acbff3b1, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:21,481 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e0409ade-b461-4433-b7ce-09e6acbff3b1, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:21,483 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e0409ade-b461-4433-b7ce-09e6acbff3b1, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:21,789 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:21,790 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:21,791 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 449d3f2f-af6e-4113-8e30-aadc72046b36, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:21,792 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 449d3f2f-af6e-4113-8e30-aadc72046b36, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:21,793 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 449d3f2f-af6e-4113-8e30-aadc72046b36, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:22,209 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:22,209 - 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:22,210 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:22,211 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 09477692-a923-4e5c-a8f7-2b5a218be8fd, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:22,212 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 09477692-a923-4e5c-a8f7-2b5a218be8fd, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:22,225 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 09477692-a923-4e5c-a8f7-2b5a218be8fd, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:23,839 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:23,840 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:23,840 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3cd1fd43-e572-43b0-9920-9d31d97a3422, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:23,841 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3cd1fd43-e572-43b0-9920-9d31d97a3422, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:23,843 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3cd1fd43-e572-43b0-9920-9d31d97a3422, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:24,114 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:24,115 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:24,116 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a11450b2-8fb5-463a-86a0-bf174a08a4fc, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:24,117 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a11450b2-8fb5-463a-86a0-bf174a08a4fc, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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,126 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a11450b2-8fb5-463a-86a0-bf174a08a4fc, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:24,734 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:24,735 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 180}}, blobs: {'__DEFAULT': {'Portions': 180, 'BlobSize': 4859264, 'BlobCount': 384}}
2025-10-15 15:12:24,736 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:24,736 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7cd0ff0d-3fcc-481d-95cd-649d7a0641d6, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:24,737 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7cd0ff0d-3fcc-481d-95cd-649d7a0641d6, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:24,749 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7cd0ff0d-3fcc-481d-95cd-649d7a0641d6, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:25,861 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:25,862 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:25,863 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6ef689a7-ff4f-419a-b9c2-1674c3343870, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:25,863 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6ef689a7-ff4f-419a-b9c2-1674c3343870, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:25,865 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6ef689a7-ff4f-419a-b9c2-1674c3343870, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:26,180 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:26,181 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:26,182 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 780448ad-bbda-427e-b94d-bfffe7348231, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:26,182 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 780448ad-bbda-427e-b94d-bfffe7348231, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:26,184 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 780448ad-bbda-427e-b94d-bfffe7348231, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:26,647 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:26,648 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 240}}, blobs: {'__DEFAULT': {'Portions': 240, 'BlobSize': 6478008, 'BlobCount': 508}}
2025-10-15 15:12:26,649 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:26,650 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4234e086-eda0-42b7-a70b-0fe3cebd88af, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:26,651 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4234e086-eda0-42b7-a70b-0fe3cebd88af, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:26,653 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4234e086-eda0-42b7-a70b-0fe3cebd88af, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:28,002 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:28,003 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:28,004 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cac2c852-e9c8-476b-9143-498caa7a23fc, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:28,005 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cac2c852-e9c8-476b-9143-498caa7a23fc, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:28,006 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cac2c852-e9c8-476b-9143-498caa7a23fc, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:28,483 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:28,483 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:28,484 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ec5d8c4b-4ed9-40a4-a693-d9da583066de, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:28,485 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ec5d8c4b-4ed9-40a4-a693-d9da583066de, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:28,486 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ec5d8c4b-4ed9-40a4-a693-d9da583066de, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:29,181 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:29,181 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 304}}, blobs: {'__DEFAULT': {'Portions': 304, 'BlobSize': 8096440, 'BlobCount': 636}}
2025-10-15 15:12:29,182 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:29,182 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 148b93d9-ad5f-4361-a9a3-3570c201fb53, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:29,183 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 148b93d9-ad5f-4361-a9a3-3570c201fb53, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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,186 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 148b93d9-ad5f-4361-a9a3-3570c201fb53, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:30,413 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:30,413 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:30,414 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9d0dbf23-c6aa-450d-a06d-3791c2bd71bc, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:30,415 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9d0dbf23-c6aa-450d-a06d-3791c2bd71bc, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:30,415 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9d0dbf23-c6aa-450d-a06d-3791c2bd71bc, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:30,778 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:30,779 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:30,780 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 26cdcddd-4bd3-4f29-830a-d4d622b01c68, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:30,781 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 26cdcddd-4bd3-4f29-830a-d4d622b01c68, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:30,783 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 26cdcddd-4bd3-4f29-830a-d4d622b01c68, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:31,416 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:31,417 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 607790, 'Portions': 357}}, blobs: {'__DEFAULT': {'Portions': 352, 'BlobSize': 9714104, 'BlobCount': 740}}
2025-10-15 15:12:31,418 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:31,418 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dbd2d067-043c-4fbe-abf2-871529e89401, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:31,419 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dbd2d067-043c-4fbe-abf2-871529e89401, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:31,426 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dbd2d067-043c-4fbe-abf2-871529e89401, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:32,856 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:32,857 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:32,858 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4a898089-da6b-4760-8968-abf70defa13c, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:32,859 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4a898089-da6b-4760-8968-abf70defa13c, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:32,860 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4a898089-da6b-4760-8968-abf70defa13c, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:33,199 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:33,200 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:33,200 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ac740092-eafc-4094-806f-fd1e79afbb17, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:33,201 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ac740092-eafc-4094-806f-fd1e79afbb17, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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,203 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ac740092-eafc-4094-806f-fd1e79afbb17, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:33,706 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:33,707 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 411}}, blobs: {'__DEFAULT': {'Portions': 411, 'BlobSize': 11332192, 'BlobCount': 860}}
2025-10-15 15:12:33,708 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:33,708 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a77ce709-d47b-405d-8bf0-540a5e919c27, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:33,709 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a77ce709-d47b-405d-8bf0-540a5e919c27, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:33,713 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a77ce709-d47b-405d-8bf0-540a5e919c27, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:34,766 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:34,766 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:34,767 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f02cbd2a-ad63-4e72-9fb5-63673829eb57, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:34,768 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f02cbd2a-ad63-4e72-9fb5-63673829eb57, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:34,770 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f02cbd2a-ad63-4e72-9fb5-63673829eb57, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:35,047 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:35,048 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:35,048 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0fa0a286-c2db-41c7-bcd9-e81debe23c30, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:35,049 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0fa0a286-c2db-41c7-bcd9-e81debe23c30, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:35,051 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0fa0a286-c2db-41c7-bcd9-e81debe23c30, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:35,579 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:35,580 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 475}}, blobs: {'__DEFAULT': {'Portions': 475, 'BlobSize': 12950624, 'BlobCount': 988}}
2025-10-15 15:12:35,580 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:35,581 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 05e89bb7-f8bc-4d21-b7ca-f9a3621f9984, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:35,582 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 05e89bb7-f8bc-4d21-b7ca-f9a3621f9984, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:35,585 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 05e89bb7-f8bc-4d21-b7ca-f9a3621f9984, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:36,751 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:36,752 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:36,752 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6fe93b71-3414-42c1-aca8-db3380db938b, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:36,753 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6fe93b71-3414-42c1-aca8-db3380db938b, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:36,755 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6fe93b71-3414-42c1-aca8-db3380db938b, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:37,185 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:37,186 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:37,186 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cdcdec0b-8bf0-4036-b44e-1017dbb26d00, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:37,187 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cdcdec0b-8bf0-4036-b44e-1017dbb26d00, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:37,192 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cdcdec0b-8bf0-4036-b44e-1017dbb26d00, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:37,926 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:37,927 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 912407, 'Portions': 533}}, blobs: {'__DEFAULT': {'Portions': 525, 'BlobSize': 14567680, 'BlobCount': 1092}}
2025-10-15 15:12:37,927 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:37,928 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c57825fd-e60d-4b2d-8f86-81f83a99eb32, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:37,929 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c57825fd-e60d-4b2d-8f86-81f83a99eb32, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:37,930 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c57825fd-e60d-4b2d-8f86-81f83a99eb32, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:39,129 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:39,130 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:39,130 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, baebc87d-2676-4982-ab63-bf8f0ee94a32, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:39,131 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, baebc87d-2676-4982-ab63-bf8f0ee94a32, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:39,132 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, baebc87d-2676-4982-ab63-bf8f0ee94a32, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:39,456 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:39,457 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:39,457 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3e4a3f2e-4a44-4845-9aa2-7de7caaf7f03, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:39,458 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3e4a3f2e-4a44-4845-9aa2-7de7caaf7f03, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:39,460 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3e4a3f2e-4a44-4845-9aa2-7de7caaf7f03, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:40,118 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:40,119 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 589}}, blobs: {'__DEFAULT': {'Portions': 589, 'BlobSize': 16186112, 'BlobCount': 1220}}
2025-10-15 15:12:40,119 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:40,120 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 58935fb0-9008-4fe2-b2b7-4fe52799e085, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:40,121 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 58935fb0-9008-4fe2-b2b7-4fe52799e085, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:40,122 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 58935fb0-9008-4fe2-b2b7-4fe52799e085, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:41,562 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:41,563 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:41,564 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5cd07f2d-194e-4ab9-8e62-c6d575e2fa33, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:41,565 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5cd07f2d-194e-4ab9-8e62-c6d575e2fa33, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:41,566 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5cd07f2d-194e-4ab9-8e62-c6d575e2fa33, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:41,966 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:41,967 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:41,968 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d2f4a7ac-bf86-45c7-ac91-37e8a0f6d87c, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:41,968 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d2f4a7ac-bf86-45c7-ac91-37e8a0f6d87c, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:41,972 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d2f4a7ac-bf86-45c7-ac91-37e8a0f6d87c, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:42,280 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:42,281 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:42,281 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 471971be-b96e-44c8-840e-5de160f1808d, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:42,282 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 471971be-b96e-44c8-840e-5de160f1808d, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:42,283 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 471971be-b96e-44c8-840e-5de160f1808d, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:48,017 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:58,068 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:12:58,069 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 090d0ab8-019e-478c-8d69-a7bb2aa38aad, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:12:58,070 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 090d0ab8-019e-478c-8d69-a7bb2aa38aad, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:58,072 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 090d0ab8-019e-478c-8d69-a7bb2aa38aad, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:02,636 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:02,731 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8084816)
2025-10-15 15:13:02,732 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:02,733 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 94781f4e-4674-491d-8eab-7b5a67442bb5, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:02,733 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 94781f4e-4674-491d-8eab-7b5a67442bb5, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:02,735 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 94781f4e-4674-491d-8eab-7b5a67442bb5, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:08,174 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:18,224 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:18,225 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3b715f34-560f-4f8f-888c-1f03b75c6aba, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:18,226 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3b715f34-560f-4f8f-888c-1f03b75c6aba, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:18,228 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3b715f34-560f-4f8f-888c-1f03b75c6aba, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:20,035 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:20,061 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8032976)
2025-10-15 15:13:20,062 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:20,062 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, df34abd9-46d5-4931-8908-8217e6e4aebf, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:20,063 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, df34abd9-46d5-4931-8908-8217e6e4aebf, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" 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:20,065 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, df34abd9-46d5-4931-8908-8217e6e4aebf, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:20,413 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:20,429 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_compression-COMPRESSION = "zstd"])
2025-10-15 15:13:20,430 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:20,430 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8fc33ff2-9072-42d0-b218-d4b501949d7d, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:20,431 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8fc33ff2-9072-42d0-b218-d4b501949d7d, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:20,434 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8fc33ff2-9072-42d0-b218-d4b501949d7d, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:24,284 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:24,284 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_compression created
2025-10-15 15:13:24,285 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:24,285 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eca6e79b-3b24-4e1e-872d-ca73ecd6c1b6, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:24,286 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eca6e79b-3b24-4e1e-872d-ca73ecd6c1b6, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:24,287 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eca6e79b-3b24-4e1e-872d-ca73ecd6c1b6, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:25,183 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:25,184 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:25,185 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 587733ce-98e6-4839-b9e4-f1b1532f0c07, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:25,186 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 587733ce-98e6-4839-b9e4-f1b1532f0c07, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:25,187 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 587733ce-98e6-4839-b9e4-f1b1532f0c07, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:26,596 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:26,597 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:26,598 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9a79a26b-00f4-42f9-85c4-8917d09b6ffb, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:26,599 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9a79a26b-00f4-42f9-85c4-8917d09b6ffb, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:26,601 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9a79a26b-00f4-42f9-85c4-8917d09b6ffb, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:27,541 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:27,542 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:27,543 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c43d13e6-30b2-45cb-ac00-f8dd94b3582c, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:27,545 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c43d13e6-30b2-45cb-ac00-f8dd94b3582c, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:27,546 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c43d13e6-30b2-45cb-ac00-f8dd94b3582c, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:30,108 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:30,108 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318880, 'BlobCount': 128}}
2025-10-15 15:13:30,109 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:30,110 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6f35664b-37fb-4803-8b73-5c0b021ccd19, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:30,111 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6f35664b-37fb-4803-8b73-5c0b021ccd19, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:30,112 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6f35664b-37fb-4803-8b73-5c0b021ccd19, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:31,456 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:31,457 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:31,458 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5251128c-3f6c-47d9-a189-be712dec0b30, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:31,458 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5251128c-3f6c-47d9-a189-be712dec0b30, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:31,460 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5251128c-3f6c-47d9-a189-be712dec0b30, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:31,826 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:31,827 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:31,828 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9a90b249-b51c-4eac-8f21-bc534dd73ff5, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:31,829 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9a90b249-b51c-4eac-8f21-bc534dd73ff5, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:31,837 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9a90b249-b51c-4eac-8f21-bc534dd73ff5, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:32,531 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:32,531 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638384, 'BlobCount': 256}}
2025-10-15 15:13:32,532 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:32,533 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 36e9b186-2a11-4f67-b347-c7c13996a5aa, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:32,534 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 36e9b186-2a11-4f67-b347-c7c13996a5aa, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:32,535 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 36e9b186-2a11-4f67-b347-c7c13996a5aa, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:34,297 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:34,298 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:34,299 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a43cf688-e2f6-4e96-8efc-86aa33107768, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:34,299 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a43cf688-e2f6-4e96-8efc-86aa33107768, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:34,302 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a43cf688-e2f6-4e96-8efc-86aa33107768, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:34,535 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:34,536 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:34,537 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 335e9a5b-b119-4e7d-a3f1-684fee86084e, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:34,538 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 335e9a5b-b119-4e7d-a3f1-684fee86084e, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:34,540 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 335e9a5b-b119-4e7d-a3f1-684fee86084e, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:35,061 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:35,061 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 187}}, blobs: {'__DEFAULT': {'Portions': 187, 'BlobSize': 958344, 'BlobCount': 384}}
2025-10-15 15:13:35,062 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:35,062 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 427b8d79-dc18-4e4a-902d-5a5559b9d13b, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:35,063 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 427b8d79-dc18-4e4a-902d-5a5559b9d13b, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:35,066 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 427b8d79-dc18-4e4a-902d-5a5559b9d13b, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:36,604 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:36,604 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:36,605 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8a4ad0ea-aeb1-401b-b73d-0b1f0287be55, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:36,606 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8a4ad0ea-aeb1-401b-b73d-0b1f0287be55, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:36,607 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8a4ad0ea-aeb1-401b-b73d-0b1f0287be55, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:36,913 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:36,914 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:36,915 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d01fff53-a612-4d84-9866-98ce1fddd2a2, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:36,916 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d01fff53-a612-4d84-9866-98ce1fddd2a2, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:36,918 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d01fff53-a612-4d84-9866-98ce1fddd2a2, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:37,462 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:37,463 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 245}}, blobs: {'__DEFAULT': {'Portions': 245, 'BlobSize': 1277296, 'BlobCount': 506}}
2025-10-15 15:13:37,464 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:37,465 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 56b84f3c-3397-4058-9df0-7a2e6e63ee7d, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:37,466 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 56b84f3c-3397-4058-9df0-7a2e6e63ee7d, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:37,470 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 56b84f3c-3397-4058-9df0-7a2e6e63ee7d, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:38,666 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:38,667 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:38,668 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, adc1a138-ca50-4eac-b6ab-429f7c52d1ec, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:38,669 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, adc1a138-ca50-4eac-b6ab-429f7c52d1ec, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:38,671 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, adc1a138-ca50-4eac-b6ab-429f7c52d1ec, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:39,075 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:39,076 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:39,077 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4a3d765d-92fa-4d58-9279-5d647cc615cf, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:39,079 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4a3d765d-92fa-4d58-9279-5d647cc615cf, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:39,081 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4a3d765d-92fa-4d58-9279-5d647cc615cf, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:39,489 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:39,490 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 303}}, blobs: {'__DEFAULT': {'Portions': 303, 'BlobSize': 1595440, 'BlobCount': 626}}
2025-10-15 15:13:39,491 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:39,492 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aef11dda-65da-4392-8c69-0d762e2a5ea9, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:39,493 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aef11dda-65da-4392-8c69-0d762e2a5ea9, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:39,495 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aef11dda-65da-4392-8c69-0d762e2a5ea9, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:40,920 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:40,920 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:40,921 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7201aa33-4d28-4ea7-a25d-17f700f75390, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:40,922 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7201aa33-4d28-4ea7-a25d-17f700f75390, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:40,923 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7201aa33-4d28-4ea7-a25d-17f700f75390, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:41,301 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:41,302 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:41,303 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8cb1abae-23c9-4c70-9bd8-85c37a474127, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:41,304 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8cb1abae-23c9-4c70-9bd8-85c37a474127, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:41,305 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8cb1abae-23c9-4c70-9bd8-85c37a474127, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:41,940 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:41,941 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 359}}, blobs: {'__DEFAULT': {'Portions': 359, 'BlobSize': 1913056, 'BlobCount': 742}}
2025-10-15 15:13:41,942 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:41,942 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 83ea1631-6439-4a26-a7a6-e82d7446f84c, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:41,944 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 83ea1631-6439-4a26-a7a6-e82d7446f84c, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:41,948 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 83ea1631-6439-4a26-a7a6-e82d7446f84c, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:43,104 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:43,105 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:43,106 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a0b7a876-46e9-42d3-b6a1-cc5343c8a87b, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:43,107 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a0b7a876-46e9-42d3-b6a1-cc5343c8a87b, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:43,108 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a0b7a876-46e9-42d3-b6a1-cc5343c8a87b, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:43,389 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:43,390 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:43,397 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 94e021b4-966c-4806-9c6e-906b7f8f9de9, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:43,398 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 94e021b4-966c-4806-9c6e-906b7f8f9de9, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:43,405 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 94e021b4-966c-4806-9c6e-906b7f8f9de9, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:44,298 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:44,299 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 423}}, blobs: {'__DEFAULT': {'Portions': 423, 'BlobSize': 2232352, 'BlobCount': 870}}
2025-10-15 15:13:44,299 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:44,300 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ee6ff2fc-1e4e-490f-976d-673403b12bff, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:44,301 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ee6ff2fc-1e4e-490f-976d-673403b12bff, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:44,313 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ee6ff2fc-1e4e-490f-976d-673403b12bff, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:45,945 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:45,946 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:45,947 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 56f61e29-6192-4313-9f0c-81c8b3f101e0, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:45,948 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 56f61e29-6192-4313-9f0c-81c8b3f101e0, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:45,949 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 56f61e29-6192-4313-9f0c-81c8b3f101e0, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:46,413 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:46,414 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:46,415 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e9ad9bcd-3d47-4130-a74d-0055a08ac2ce, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:46,416 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e9ad9bcd-3d47-4130-a74d-0055a08ac2ce, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:46,418 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e9ad9bcd-3d47-4130-a74d-0055a08ac2ce, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:47,141 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:47,142 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 481}}, blobs: {'__DEFAULT': {'Portions': 481, 'BlobSize': 2550392, 'BlobCount': 988}}
2025-10-15 15:13:47,142 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:47,143 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f129878e-2838-4a27-aa81-2c773723466e, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:47,144 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f129878e-2838-4a27-aa81-2c773723466e, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:47,151 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f129878e-2838-4a27-aa81-2c773723466e, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:47,485 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:13:47,486 - 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:47,511 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:13:47,512 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 6b105d11-d0c2-4a33-8bee-69b6e12c2d2c, localhost:15201): creating call state
2025-10-15 15:13:47,512 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 6b105d11-d0c2-4a33-8bee-69b6e12c2d2c, localhost:15201): request = { database: "/Root" }
2025-10-15 15:13:47,527 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 6b105d11-d0c2-4a33-8bee-69b6e12c2d2c, localhost:15201): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 15201 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:47,527 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:15201, location 1, ssl: False>]>
2025-10-15 15:13:47,528 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:15201
2025-10-15 15:13:48,615 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:48,616 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:48,617 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e81a6cb3-2eb1-42b2-82e0-51759f3285a8, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:48,618 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e81a6cb3-2eb1-42b2-82e0-51759f3285a8, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:48,621 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e81a6cb3-2eb1-42b2-82e0-51759f3285a8, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:49,269 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:49,270 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:49,271 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bee9dcb0-9808-48f7-9ea1-e6e80ce87f2c, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:49,272 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bee9dcb0-9808-48f7-9ea1-e6e80ce87f2c, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:49,274 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bee9dcb0-9808-48f7-9ea1-e6e80ce87f2c, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:50,321 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:50,322 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 912594, 'Portions': 518}}, blobs: {'__DEFAULT': {'Portions': 510, 'BlobSize': 2860240, 'BlobCount': 1056}}
2025-10-15 15:13:50,322 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:50,323 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e574b314-87fb-4dfa-86cd-185baebb8e49, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:50,324 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e574b314-87fb-4dfa-86cd-185baebb8e49, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:50,326 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e574b314-87fb-4dfa-86cd-185baebb8e49, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:51,862 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:51,863 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:51,864 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d32fe931-edf1-41fe-89ca-09eb7e662178, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:51,865 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d32fe931-edf1-41fe-89ca-09eb7e662178, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:51,867 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d32fe931-edf1-41fe-89ca-09eb7e662178, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:52,224 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:52,224 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:52,225 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e8478bd4-71ac-4d4c-afb0-2d649ce37cb6, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:52,226 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e8478bd4-71ac-4d4c-afb0-2d649ce37cb6, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:52,228 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e8478bd4-71ac-4d4c-afb0-2d649ce37cb6, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:53,234 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:53,235 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 558}}, blobs: {'__DEFAULT': {'Portions': 558, 'BlobSize': 3175504, 'BlobCount': 1156}}
2025-10-15 15:13:53,235 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:53,236 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4ef2bc1e-c63e-4cdf-809d-477ebde87b68, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:53,242 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4ef2bc1e-c63e-4cdf-809d-477ebde87b68, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:53,243 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4ef2bc1e-c63e-4cdf-809d-477ebde87b68, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:54,507 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:54,507 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:54,508 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4c639413-5aa7-4166-af76-8e681c1362cc, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:54,509 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4c639413-5aa7-4166-af76-8e681c1362cc, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:54,510 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4c639413-5aa7-4166-af76-8e681c1362cc, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:54,939 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:54,940 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:54,940 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 171993a5-f9fd-4901-a21f-22771202948e, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:54,941 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 171993a5-f9fd-4901-a21f-22771202948e, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:54,945 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 171993a5-f9fd-4901-a21f-22771202948e, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:55,320 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:55,320 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:13:55,321 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6fd5efa3-997c-48c0-bf5f-bd7ff805ed1a, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:13:55,321 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6fd5efa3-997c-48c0-bf5f-bd7ff805ed1a, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:55,323 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6fd5efa3-997c-48c0-bf5f-bd7ff805ed1a, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:00,184 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:14:10,217 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:14:10,218 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bbceec61-4957-4cbc-8b64-4ccbd6647adf, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:14:10,219 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bbceec61-4957-4cbc-8b64-4ccbd6647adf, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:10,222 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bbceec61-4957-4cbc-8b64-4ccbd6647adf, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:15,079 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:14:15,158 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_compression`, volumes `value` (8000000, 1577920)
2025-10-15 15:14:15,159 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:14:15,160 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4b6984d6-8197-422f-b8c8-d01b7d5d8713, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:14:15,167 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4b6984d6-8197-422f-b8c8-d01b7d5d8713, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:15,174 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4b6984d6-8197-422f-b8c8-d01b7d5d8713, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:15,710 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:14:15,711 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:14:15,712 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e8a34f10-ca71-48cc-87ab-7c542bbe5970, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:14:15,713 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e8a34f10-ca71-48cc-87ab-7c542bbe5970, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:15,725 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e8a34f10-ca71-48cc-87ab-7c542bbe5970, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:19,996 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:14:30,027 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:14:30,028 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 94bcadbc-803f-4451-b038-15159c362447, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:14:30,029 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 94bcadbc-803f-4451-b038-15159c362447, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:30,030 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 94bcadbc-803f-4451-b038-15159c362447, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:32,234 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:14:42,253 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:14:42,254 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ac0f0cab-04a3-484d-b8e4-7870a0db0b08, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:14:42,255 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ac0f0cab-04a3-484d-b8e4-7870a0db0b08, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:42,256 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ac0f0cab-04a3-484d-b8e4-7870a0db0b08, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:43,410 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:14:53,425 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:14:53,426 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5122472f-4394-4dfd-b614-88dce6127d30, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:14:53,427 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5122472f-4394-4dfd-b614-88dce6127d30, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:53,428 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5122472f-4394-4dfd-b614-88dce6127d30, ghrun-lvxbqnxafi.auto.internal:15201): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:54,699 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=
2025-10-15 15:14:54,710 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_compression`, volumes `value` (8000000, 1489624)
2025-10-15 15:14:54,711 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_compression` 8084816 / 1489624: 5.427420610838708
2025-10-15 15:14:54,713 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-15 15:14:54,715 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, aa81f7b9-d714-4a4d-b188-70794e32d716, ghrun-lvxbqnxafi.auto.internal:15201): creating call state
2025-10-15 15:14:54,715 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, aa81f7b9-d714-4a4d-b188-70794e32d716, ghrun-lvxbqnxafi.auto.internal:15201): request = { session_id: "ydb://session/3?node_id=1&id=MTAyYjhjMjQtYzQ0MDA4NjctNjllNDk5ZjUtYmFmMjNiNDk=" }
2025-10-15 15:14:54,719 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, aa81f7b9-d714-4a4d-b188-70794e32d716, ghrun-lvxbqnxafi.auto.internal:15201): response = { status: SUCCESS }
2025-10-15 15:14:54,725 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-10-15 15:14:54,726 - DEBUG - ydb.topic - close: Close topic client
2025-10-15 15:14:54,726 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-15 15:14:54,726 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:15201
2025-10-15 15:14:54,728 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-lvxbqnxafi.auto.internal:15201
2025-10-15 15:14:54,729 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-15 15:15:06,737 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:15201/1
2025-10-15 15:15:06,740 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27997
2025-10-15 15:15:06,740 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28771
2025-10-15 15:15:06,741 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15320
2025-10-15 15:15:06,741 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15201
2025-10-15 15:15:06,741 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15790
