2025-10-15 15:11:53,516 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:11:53,517 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_15_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 15]
2025-10-15 15:11:53,517 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:11:53,518 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-15 15:11:53,520 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-15 15:11:53,522 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-15 15:11:53,522 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001bf7/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:11:53,529 - DEBUG - ya.test - execute: Command pid: 568254
2025-10-15 15:11:54,781 - DEBUG - ya.test - _save_outputs: Command (pid 568254) rc: 0
2025-10-15 15:11:54,782 - DEBUG - ya.test - _save_outputs: Command (pid 568254) elapsed time (sec): 1.2504522800445557
2025-10-15 15:11:54,782 - DEBUG - ya.test - _save_outputs: Command (pid 568254) idrss: 0
2025-10-15 15:11:54,782 - DEBUG - ya.test - _save_outputs: Command (pid 568254) inblock: 0
2025-10-15 15:11:54,783 - DEBUG - ya.test - _save_outputs: Command (pid 568254) isrss: 0
2025-10-15 15:11:54,783 - DEBUG - ya.test - _save_outputs: Command (pid 568254) ixrss: 0
2025-10-15 15:11:54,784 - DEBUG - ya.test - _save_outputs: Command (pid 568254) majflt: 0
2025-10-15 15:11:54,784 - DEBUG - ya.test - _save_outputs: Command (pid 568254) maxrss: 481444
2025-10-15 15:11:54,784 - DEBUG - ya.test - _save_outputs: Command (pid 568254) minflt: 45136
2025-10-15 15:11:54,785 - DEBUG - ya.test - _save_outputs: Command (pid 568254) msgrcv: 0
2025-10-15 15:11:54,785 - DEBUG - ya.test - _save_outputs: Command (pid 568254) msgsnd: 0
2025-10-15 15:11:54,786 - DEBUG - ya.test - _save_outputs: Command (pid 568254) nivcsw: 223
2025-10-15 15:11:54,786 - DEBUG - ya.test - _save_outputs: Command (pid 568254) nsignals: 0
2025-10-15 15:11:54,787 - DEBUG - ya.test - _save_outputs: Command (pid 568254) nswap: 0
2025-10-15 15:11:54,787 - DEBUG - ya.test - _save_outputs: Command (pid 568254) nvcsw: 14
2025-10-15 15:11:54,787 - DEBUG - ya.test - _save_outputs: Command (pid 568254) oublock: 8
2025-10-15 15:11:54,788 - DEBUG - ya.test - _save_outputs: Command (pid 568254) stime: 0.22677699999999998
2025-10-15 15:11:54,788 - DEBUG - ya.test - _save_outputs: Command (pid 568254) utime: 0.550159
2025-10-15 15:11:54,788 - DEBUG - ya.test - _save_outputs: Command (pid 568254) wtime: 1.258
2025-10-15 15:11:54,789 - DEBUG - ya.test - _save_outputs: Command (pid 568254) 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:54,790 - DEBUG - ya.test - _save_outputs: Command (pid 568254) errors:

2025-10-15 15:11:54,790 - 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:54,913 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001bf7/ydb/apps/ydbd/ydbd
2025-10-15 15:11:54,915 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61161
2025-10-15 15:11:54,917 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/w2kl/001bf7/r3tmp/kikimr_cluster_on_iur4z
2025-10-15 15:11:54,953 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001bf7/ydb/apps/ydbd/ydbd
2025-10-15 15:11:54,953 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10960
2025-10-15 15:11:54,954 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9594
2025-10-15 15:11:54,955 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8144
2025-10-15 15:11:54,958 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9528
2025-10-15 15:11:54,959 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001bf7/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/kikimr_configs"
2025-10-15 15:11:54,960 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001bf7/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/001bf7/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1/logfile_ry9_7gom.log --grpc-port=10960 --mon-port=9594 --ic-port=61161
2025-10-15 15:11:54,960 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:10960/1, disk_size 68719476736
2025-10-15 15:11:54,961 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001bf7/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/kikimr_configs"
2025-10-15 15:11:54,961 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001bf7/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/001bf7/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1/logfile_ry9_7gom.log --grpc-port=10960 --mon-port=9594 --ic-port=61161
2025-10-15 15:11:54,963 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/w2kl/001bf7/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/w2kl/001bf7/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/w2kl/001bf7/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1/logfile_ry9_7gom.log', '--grpc-port=10960', '--mon-port=9594', '--ic-port=61161')' in '/home/runner/.ya/build/build_root/w2kl/001bf7/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1' (no wait)
2025-10-15 15:11:54,982 - DEBUG - ya.test - execute: Command pid: 568509
2025-10-15 15:11:54,983 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:10960/1
2025-10-15 15:11:54,985 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-15 15:11:54,986 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-15 15:11:54,987 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001bf7/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:11:55,001 - DEBUG - ya.test - execute: Command pid: 568515
2025-10-15 15:11:56,453 - DEBUG - ya.test - _save_outputs: Command (pid 568515) rc: 0
2025-10-15 15:11:56,454 - DEBUG - ya.test - _save_outputs: Command (pid 568515) elapsed time (sec): 1.4511287212371826
2025-10-15 15:11:56,454 - DEBUG - ya.test - _save_outputs: Command (pid 568515) idrss: 0
2025-10-15 15:11:56,454 - DEBUG - ya.test - _save_outputs: Command (pid 568515) inblock: 0
2025-10-15 15:11:56,455 - DEBUG - ya.test - _save_outputs: Command (pid 568515) isrss: 0
2025-10-15 15:11:56,455 - DEBUG - ya.test - _save_outputs: Command (pid 568515) ixrss: 0
2025-10-15 15:11:56,455 - DEBUG - ya.test - _save_outputs: Command (pid 568515) majflt: 0
2025-10-15 15:11:56,456 - DEBUG - ya.test - _save_outputs: Command (pid 568515) maxrss: 481620
2025-10-15 15:11:56,456 - DEBUG - ya.test - _save_outputs: Command (pid 568515) minflt: 45084
2025-10-15 15:11:56,456 - DEBUG - ya.test - _save_outputs: Command (pid 568515) msgrcv: 0
2025-10-15 15:11:56,457 - DEBUG - ya.test - _save_outputs: Command (pid 568515) msgsnd: 0
2025-10-15 15:11:56,457 - DEBUG - ya.test - _save_outputs: Command (pid 568515) nivcsw: 239
2025-10-15 15:11:56,458 - DEBUG - ya.test - _save_outputs: Command (pid 568515) nsignals: 0
2025-10-15 15:11:56,458 - DEBUG - ya.test - _save_outputs: Command (pid 568515) nswap: 0
2025-10-15 15:11:56,458 - DEBUG - ya.test - _save_outputs: Command (pid 568515) nvcsw: 14
2025-10-15 15:11:56,459 - DEBUG - ya.test - _save_outputs: Command (pid 568515) oublock: 8
2025-10-15 15:11:56,459 - DEBUG - ya.test - _save_outputs: Command (pid 568515) stime: 0.234318
2025-10-15 15:11:56,459 - DEBUG - ya.test - _save_outputs: Command (pid 568515) utime: 0.562809
2025-10-15 15:11:56,459 - DEBUG - ya.test - _save_outputs: Command (pid 568515) wtime: 1.465
2025-10-15 15:11:56,460 - DEBUG - ya.test - _save_outputs: Command (pid 568515) 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:56,460 - DEBUG - ya.test - _save_outputs: Command (pid 568515) errors:

2025-10-15 15:11:56,461 - 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:02,028 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001bf7/ydb/apps/ydbd/ydbd
2025-10-15 15:12:02,028 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2kl/001bf7/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:10960', '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: 61161\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-15 15:12:02,030 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-15 15:12:02,030 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-15 15:12:02,031 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001bf7/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:10960', '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: 61161\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-15 15:12:02,037 - DEBUG - ya.test - execute: Command pid: 570176
2025-10-15 15:12:03,577 - DEBUG - ya.test - _save_outputs: Command (pid 570176) rc: 0
2025-10-15 15:12:03,578 - DEBUG - ya.test - _save_outputs: Command (pid 570176) elapsed time (sec): 1.5393006801605225
2025-10-15 15:12:03,578 - DEBUG - ya.test - _save_outputs: Command (pid 570176) idrss: 0
2025-10-15 15:12:03,578 - DEBUG - ya.test - _save_outputs: Command (pid 570176) inblock: 0
2025-10-15 15:12:03,578 - DEBUG - ya.test - _save_outputs: Command (pid 570176) isrss: 0
2025-10-15 15:12:03,579 - DEBUG - ya.test - _save_outputs: Command (pid 570176) ixrss: 0
2025-10-15 15:12:03,579 - DEBUG - ya.test - _save_outputs: Command (pid 570176) majflt: 0
2025-10-15 15:12:03,579 - DEBUG - ya.test - _save_outputs: Command (pid 570176) maxrss: 585476
2025-10-15 15:12:03,579 - DEBUG - ya.test - _save_outputs: Command (pid 570176) minflt: 51416
2025-10-15 15:12:03,580 - DEBUG - ya.test - _save_outputs: Command (pid 570176) msgrcv: 0
2025-10-15 15:12:03,580 - DEBUG - ya.test - _save_outputs: Command (pid 570176) msgsnd: 0
2025-10-15 15:12:03,580 - DEBUG - ya.test - _save_outputs: Command (pid 570176) nivcsw: 264
2025-10-15 15:12:03,581 - DEBUG - ya.test - _save_outputs: Command (pid 570176) nsignals: 0
2025-10-15 15:12:03,586 - DEBUG - ya.test - _save_outputs: Command (pid 570176) nswap: 0
2025-10-15 15:12:03,587 - DEBUG - ya.test - _save_outputs: Command (pid 570176) nvcsw: 611
2025-10-15 15:12:03,587 - DEBUG - ya.test - _save_outputs: Command (pid 570176) oublock: 8
2025-10-15 15:12:03,587 - DEBUG - ya.test - _save_outputs: Command (pid 570176) stime: 0.29967099999999997
2025-10-15 15:12:03,588 - DEBUG - ya.test - _save_outputs: Command (pid 570176) utime: 0.583913
2025-10-15 15:12:03,588 - DEBUG - ya.test - _save_outputs: Command (pid 570176) wtime: 1.546
2025-10-15 15:12:03,588 - DEBUG - ya.test - _save_outputs: Command (pid 570176) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-15 15:12:03,588 - DEBUG - ya.test - _save_outputs: Command (pid 570176) errors:

2025-10-15 15:12:03,590 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001bf7/ydb/apps/ydbd/ydbd
2025-10-15 15:12:03,590 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2kl/001bf7/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:10960', '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:03,592 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-15 15:12:03,592 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-15 15:12:03,609 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001bf7/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:10960', '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:03,633 - DEBUG - ya.test - execute: Command pid: 570435
2025-10-15 15:12:05,185 - DEBUG - ya.test - _save_outputs: Command (pid 570435) rc: 0
2025-10-15 15:12:05,186 - DEBUG - ya.test - _save_outputs: Command (pid 570435) elapsed time (sec): 1.5510947704315186
2025-10-15 15:12:05,186 - DEBUG - ya.test - _save_outputs: Command (pid 570435) idrss: 0
2025-10-15 15:12:05,186 - DEBUG - ya.test - _save_outputs: Command (pid 570435) inblock: 0
2025-10-15 15:12:05,186 - DEBUG - ya.test - _save_outputs: Command (pid 570435) isrss: 0
2025-10-15 15:12:05,187 - DEBUG - ya.test - _save_outputs: Command (pid 570435) ixrss: 0
2025-10-15 15:12:05,187 - DEBUG - ya.test - _save_outputs: Command (pid 570435) majflt: 0
2025-10-15 15:12:05,187 - DEBUG - ya.test - _save_outputs: Command (pid 570435) maxrss: 585820
2025-10-15 15:12:05,188 - DEBUG - ya.test - _save_outputs: Command (pid 570435) minflt: 51479
2025-10-15 15:12:05,188 - DEBUG - ya.test - _save_outputs: Command (pid 570435) msgrcv: 0
2025-10-15 15:12:05,188 - DEBUG - ya.test - _save_outputs: Command (pid 570435) msgsnd: 0
2025-10-15 15:12:05,188 - DEBUG - ya.test - _save_outputs: Command (pid 570435) nivcsw: 347
2025-10-15 15:12:05,201 - DEBUG - ya.test - _save_outputs: Command (pid 570435) nsignals: 0
2025-10-15 15:12:05,201 - DEBUG - ya.test - _save_outputs: Command (pid 570435) nswap: 0
2025-10-15 15:12:05,202 - DEBUG - ya.test - _save_outputs: Command (pid 570435) nvcsw: 473
2025-10-15 15:12:05,202 - DEBUG - ya.test - _save_outputs: Command (pid 570435) oublock: 8
2025-10-15 15:12:05,202 - DEBUG - ya.test - _save_outputs: Command (pid 570435) stime: 0.335532
2025-10-15 15:12:05,202 - DEBUG - ya.test - _save_outputs: Command (pid 570435) utime: 0.5822769999999999
2025-10-15 15:12:05,203 - DEBUG - ya.test - _save_outputs: Command (pid 570435) wtime: 1.575
2025-10-15 15:12:05,203 - DEBUG - ya.test - _save_outputs: Command (pid 570435) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-15 15:12:05,203 - DEBUG - ya.test - _save_outputs: Command (pid 570435) errors:

2025-10-15 15:12:05,204 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-15 15:12:05,374 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-15 15:12:05,442 - 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:05,458 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:12:05,458 - 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:05,495 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:12:05,496 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 3e529e25-dda7-45e3-a0ea-3319f838bcfc, localhost:10960): creating call state
2025-10-15 15:12:05,497 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 3e529e25-dda7-45e3-a0ea-3319f838bcfc, localhost:10960): request = { database: "/Root" }
2025-10-15 15:12:05,510 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 3e529e25-dda7-45e3-a0ea-3319f838bcfc, localhost:10960): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 10960 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:05,511 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:10960, location 1, ssl: False>]>
2025-10-15 15:12:05,512 - 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:05,545 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10960
2025-10-15 15:12:05,547 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-10-15 15:12:05,549 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, aa063868-df66-4dbf-8344-c3b13ceb1e78, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:05,549 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, aa063868-df66-4dbf-8344-c3b13ceb1e78, ghrun-lvxbqnxafi.auto.internal:10960): request = {  }
2025-10-15 15:12:05,561 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, aa063868-df66-4dbf-8344-c3b13ceb1e78, ghrun-lvxbqnxafi.auto.internal:10960): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" node_id: 1 }
2025-10-15 15:12:05,562 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 0cd9065d-51f2-44fb-8d75-8cb9b405c328, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:05,563 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 0cd9065d-51f2-44fb-8d75-8cb9b405c328, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" }
2025-10-15 15:12:05,582 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 0cd9065d-51f2-44fb-8d75-8cb9b405c328, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:05,606 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:05,607 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 576276a9-8131-425e-83c2-99d5aebcc409, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:05,613 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 576276a9-8131-425e-83c2-99d5aebcc409, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:05,614 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 576276a9-8131-425e-83c2-99d5aebcc409, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:10,019 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:10,019 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-10-15 15:12:10,020 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:10,021 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f46c7e21-2bef-4e53-a218-cb2b19670c12, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:10,022 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f46c7e21-2bef-4e53-a218-cb2b19670c12, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:10,038 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f46c7e21-2bef-4e53-a218-cb2b19670c12, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:11,542 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:11,543 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:11,544 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c00d36f7-1ae5-40b2-8a16-fa1da799aeb0, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:11,547 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c00d36f7-1ae5-40b2-8a16-fa1da799aeb0, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:11,548 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c00d36f7-1ae5-40b2-8a16-fa1da799aeb0, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:13,501 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:13,502 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:13,502 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a3e38187-96ec-44e0-a185-87159cf241d3, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:13,503 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a3e38187-96ec-44e0-a185-87159cf241d3, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:13,504 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a3e38187-96ec-44e0-a185-87159cf241d3, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:14,723 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:14,724 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:14,725 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a3497cba-4df0-4438-93af-349834a7a1c6, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:14,726 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a3497cba-4df0-4438-93af-349834a7a1c6, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:14,727 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a3497cba-4df0-4438-93af-349834a7a1c6, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:17,085 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:17,086 - 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:17,086 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:17,087 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c36b3bf7-da93-4b2c-91d7-8daafb20bfa1, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:17,088 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c36b3bf7-da93-4b2c-91d7-8daafb20bfa1, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:17,091 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c36b3bf7-da93-4b2c-91d7-8daafb20bfa1, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:18,390 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:18,391 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:18,392 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b1b8c887-b5e4-4255-a293-64355bfdd38b, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:18,393 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b1b8c887-b5e4-4255-a293-64355bfdd38b, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:18,394 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b1b8c887-b5e4-4255-a293-64355bfdd38b, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:19,024 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:19,025 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:19,026 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3cefb794-ed15-4e39-aa10-5544de03fc10, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:19,027 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3cefb794-ed15-4e39-aa10-5544de03fc10, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:19,031 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3cefb794-ed15-4e39-aa10-5544de03fc10, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:19,547 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:19,548 - 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:19,548 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:19,549 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6c0a8b78-2103-41de-9fc2-272eef271839, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:19,550 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6c0a8b78-2103-41de-9fc2-272eef271839, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:19,552 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6c0a8b78-2103-41de-9fc2-272eef271839, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:20,796 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:20,797 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:20,797 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 55569d7a-fc3b-4024-81a3-4de1a280a55c, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:20,798 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 55569d7a-fc3b-4024-81a3-4de1a280a55c, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:20,800 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 55569d7a-fc3b-4024-81a3-4de1a280a55c, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:21,048 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:21,049 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:21,050 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cb32717f-efcf-45f6-8449-d58b163d2298, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:21,051 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cb32717f-efcf-45f6-8449-d58b163d2298, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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,052 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cb32717f-efcf-45f6-8449-d58b163d2298, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:21,423 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:21,424 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 303210, 'Portions': 185}}, blobs: {'__DEFAULT': {'Portions': 183, 'BlobSize': 4858288, 'BlobCount': 384}}
2025-10-15 15:12:21,424 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:21,425 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e990dcad-a35d-4fd3-b89e-844f3429477a, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:21,425 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e990dcad-a35d-4fd3-b89e-844f3429477a, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:21,427 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e990dcad-a35d-4fd3-b89e-844f3429477a, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:22,486 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:22,486 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:22,487 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f58f4a69-eadd-42b2-8b81-6da2fede584c, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:22,488 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f58f4a69-eadd-42b2-8b81-6da2fede584c, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:22,492 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f58f4a69-eadd-42b2-8b81-6da2fede584c, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:22,792 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:22,793 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:22,794 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1d3d41ad-1b3d-4ba4-847f-7dc1606181e3, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:22,795 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1d3d41ad-1b3d-4ba4-847f-7dc1606181e3, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:22,796 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1d3d41ad-1b3d-4ba4-847f-7dc1606181e3, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:23,298 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:23,299 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 241}}, blobs: {'__DEFAULT': {'Portions': 241, 'BlobSize': 6477184, 'BlobCount': 506}}
2025-10-15 15:12:23,299 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:23,300 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 39d78eac-8542-46fc-9421-7983c47cd49c, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:23,301 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 39d78eac-8542-46fc-9421-7983c47cd49c, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:23,302 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 39d78eac-8542-46fc-9421-7983c47cd49c, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:24,608 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:24,608 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:24,609 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cb5a9af7-0514-4c6b-9320-404b02a94a2e, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:24,610 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cb5a9af7-0514-4c6b-9320-404b02a94a2e, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:24,611 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cb5a9af7-0514-4c6b-9320-404b02a94a2e, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:24,902 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:24,903 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:24,903 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 22469c90-1cf8-4b31-884c-e74572f88645, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:24,904 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 22469c90-1cf8-4b31-884c-e74572f88645, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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,906 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 22469c90-1cf8-4b31-884c-e74572f88645, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:25,409 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:25,410 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 302}}, blobs: {'__DEFAULT': {'Portions': 302, 'BlobSize': 8095608, 'BlobCount': 630}}
2025-10-15 15:12:25,411 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:25,411 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 605e7f10-2e33-4c10-906f-563ca4deae34, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:25,412 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 605e7f10-2e33-4c10-906f-563ca4deae34, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:25,429 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 605e7f10-2e33-4c10-906f-563ca4deae34, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:26,739 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:26,739 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:26,740 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1bec158f-7603-4920-a6af-01e012959b76, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:26,741 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1bec158f-7603-4920-a6af-01e012959b76, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:26,742 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1bec158f-7603-4920-a6af-01e012959b76, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:27,213 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:27,214 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:27,215 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d81528ae-33f9-408a-a5be-81047fddb462, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:27,215 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d81528ae-33f9-408a-a5be-81047fddb462, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:27,219 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d81528ae-33f9-408a-a5be-81047fddb462, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:27,728 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:27,728 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 358}}, blobs: {'__DEFAULT': {'Portions': 358, 'BlobSize': 9713640, 'BlobCount': 746}}
2025-10-15 15:12:27,729 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:27,730 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1aa7a58c-621c-4424-8796-6adb54dea34d, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:27,731 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1aa7a58c-621c-4424-8796-6adb54dea34d, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:27,736 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1aa7a58c-621c-4424-8796-6adb54dea34d, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:28,919 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:28,920 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:28,921 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cd41733a-d88b-4068-9590-a70c947c75e3, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:28,922 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cd41733a-d88b-4068-9590-a70c947c75e3, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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,926 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cd41733a-d88b-4068-9590-a70c947c75e3, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:29,329 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:29,330 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:29,331 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dfdfdded-e510-4b41-a9eb-cd518d0a9fb3, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:29,332 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dfdfdded-e510-4b41-a9eb-cd518d0a9fb3, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:29,334 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dfdfdded-e510-4b41-a9eb-cd518d0a9fb3, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:29,993 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:29,994 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 709368, 'Portions': 413}}, blobs: {'__DEFAULT': {'Portions': 407, 'BlobSize': 11331064, 'BlobCount': 850}}
2025-10-15 15:12:29,995 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:29,996 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 202c0afd-0ff5-4b8b-ac82-f0e21fbd8483, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:30,004 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 202c0afd-0ff5-4b8b-ac82-f0e21fbd8483, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:30,006 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 202c0afd-0ff5-4b8b-ac82-f0e21fbd8483, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:31,248 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:31,249 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:31,250 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9859c0b2-2bdc-4096-bec1-c46594a90184, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:31,251 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9859c0b2-2bdc-4096-bec1-c46594a90184, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:31,252 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9859c0b2-2bdc-4096-bec1-c46594a90184, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:31,509 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:31,510 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:31,511 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, efd9317d-e593-4acc-880b-aa26375fd014, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:31,512 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, efd9317d-e593-4acc-880b-aa26375fd014, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:31,516 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, efd9317d-e593-4acc-880b-aa26375fd014, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:32,424 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:32,425 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 459}}, blobs: {'__DEFAULT': {'Portions': 459, 'BlobSize': 12948448, 'BlobCount': 958}}
2025-10-15 15:12:32,426 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:32,426 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d12617aa-e8f4-4134-91e6-564d9a3faa72, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:32,427 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d12617aa-e8f4-4134-91e6-564d9a3faa72, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:32,429 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d12617aa-e8f4-4134-91e6-564d9a3faa72, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:33,707 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:33,707 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:33,708 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, af506bce-59ca-4103-8d01-915ac2859e21, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:33,709 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, af506bce-59ca-4103-8d01-915ac2859e21, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:33,710 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, af506bce-59ca-4103-8d01-915ac2859e21, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:34,054 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:34,055 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:34,055 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dfe72c64-f376-495c-ab13-13ca813ea85e, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:34,056 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dfe72c64-f376-495c-ab13-13ca813ea85e, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:34,058 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dfe72c64-f376-495c-ab13-13ca813ea85e, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:34,731 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:34,731 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 912645, 'Portions': 517}}, blobs: {'__DEFAULT': {'Portions': 495, 'BlobSize': 14564144, 'BlobCount': 1038}}
2025-10-15 15:12:34,732 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:34,733 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 44d67604-061a-459c-800d-e28c36aa760f, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:34,734 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 44d67604-061a-459c-800d-e28c36aa760f, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:34,735 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 44d67604-061a-459c-800d-e28c36aa760f, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:35,954 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:35,955 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:35,956 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d32d7647-fc37-455c-953a-8da71854e5c8, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:35,957 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d32d7647-fc37-455c-953a-8da71854e5c8, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:35,960 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d32d7647-fc37-455c-953a-8da71854e5c8, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:36,473 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:36,474 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:36,475 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5c8619c3-3cf5-40e3-aec4-d966af0d4be7, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:36,476 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5c8619c3-3cf5-40e3-aec4-d966af0d4be7, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:36,478 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5c8619c3-3cf5-40e3-aec4-d966af0d4be7, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:37,285 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:37,286 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1014176, 'Portions': 552}}, blobs: {'__DEFAULT': {'Portions': 543, 'BlobSize': 16180848, 'BlobCount': 1138}}
2025-10-15 15:12:37,287 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:37,288 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 529e2940-8579-46aa-a5c2-8d0a7a0ab658, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:37,289 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 529e2940-8579-46aa-a5c2-8d0a7a0ab658, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:37,290 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 529e2940-8579-46aa-a5c2-8d0a7a0ab658, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:38,461 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:38,462 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:38,462 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e6101b05-2da1-49ea-8167-befff089607a, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:38,463 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e6101b05-2da1-49ea-8167-befff089607a, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:38,465 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e6101b05-2da1-49ea-8167-befff089607a, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:38,743 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:38,744 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:38,744 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 43da9560-9e63-4d44-a9f8-37ca41e607d7, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:38,745 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 43da9560-9e63-4d44-a9f8-37ca41e607d7, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:38,746 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 43da9560-9e63-4d44-a9f8-37ca41e607d7, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:39,295 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:39,296 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:39,297 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bd26b834-0b37-4786-affa-1055ab20f6a3, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:39,298 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bd26b834-0b37-4786-affa-1055ab20f6a3, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:39,300 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bd26b834-0b37-4786-affa-1055ab20f6a3, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:44,068 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:54,097 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:54,098 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b898ebb3-4fe6-49cf-abc5-e4432a2f91bd, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:54,099 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b898ebb3-4fe6-49cf-abc5-e4432a2f91bd, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:54,100 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b898ebb3-4fe6-49cf-abc5-e4432a2f91bd, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:58,048 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:58,082 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8078192)
2025-10-15 15:12:58,082 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:12:58,083 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 98a0b666-5992-4408-8c84-d6ef92672c39, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:12:58,084 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 98a0b666-5992-4408-8c84-d6ef92672c39, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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,085 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 98a0b666-5992-4408-8c84-d6ef92672c39, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:03,078 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:13,113 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:13,114 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3fc02708-662c-4b3f-8860-5d7e339abf8b, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:13:13,115 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3fc02708-662c-4b3f-8860-5d7e339abf8b, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:13,116 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3fc02708-662c-4b3f-8860-5d7e339abf8b, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:16,041 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:26,056 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:26,058 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f69f789b-af3d-4663-932e-edd1c976b61d, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:13:26,059 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f69f789b-af3d-4663-932e-edd1c976b61d, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:26,061 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f69f789b-af3d-4663-932e-edd1c976b61d, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:27,522 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:37,550 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:37,551 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, acbe29da-ade5-48ea-b04b-4ad9e55b27ba, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:13:37,552 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, acbe29da-ade5-48ea-b04b-4ad9e55b27ba, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:37,553 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, acbe29da-ade5-48ea-b04b-4ad9e55b27ba, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:39,032 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:39,046 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8030240)
2025-10-15 15:13:39,047 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:39,048 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e37ad207-9b80-4fa1-81a9-310322c68b56, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:13:39,049 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e37ad207-9b80-4fa1-81a9-310322c68b56, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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:39,051 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e37ad207-9b80-4fa1-81a9-310322c68b56, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:39,374 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:39,377 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_15_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 15])
2025-10-15 15:13:39,378 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:39,379 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6212b375-0f8f-4dae-9704-c0fe62106951, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:13:39,380 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6212b375-0f8f-4dae-9704-c0fe62106951, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_15_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 15)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:39,381 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6212b375-0f8f-4dae-9704-c0fe62106951, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:43,245 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:43,246 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_15_compression created
2025-10-15 15:13:43,247 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:43,248 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 441aae35-bd72-4b6b-a0f9-e5402570a28a, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:13:43,249 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 441aae35-bd72-4b6b-a0f9-e5402570a28a, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_15_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:43,251 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 441aae35-bd72-4b6b-a0f9-e5402570a28a, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:44,333 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:44,334 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:44,335 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e44475e3-9f3a-4680-9410-d160b2d1ee7a, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:13:44,336 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e44475e3-9f3a-4680-9410-d160b2d1ee7a, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:44,337 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e44475e3-9f3a-4680-9410-d160b2d1ee7a, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:48,828 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:13:48,828 - 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:48,851 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:13:48,851 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, d4d476cc-5a7d-4dfa-a40c-09fcbb8ab3b9, localhost:10960): creating call state
2025-10-15 15:13:48,852 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, d4d476cc-5a7d-4dfa-a40c-09fcbb8ab3b9, localhost:10960): request = { database: "/Root" }
2025-10-15 15:13:48,863 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, d4d476cc-5a7d-4dfa-a40c-09fcbb8ab3b9, localhost:10960): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 10960 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:48,864 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:10960, location 1, ssl: False>]>
2025-10-15 15:13:48,865 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10960
2025-10-15 15:13:49,078 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:49,079 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:49,079 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fffc7f42-0a81-4c7a-a1c0-3723af4ec87d, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:13:49,080 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fffc7f42-0a81-4c7a-a1c0-3723af4ec87d, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:49,086 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fffc7f42-0a81-4c7a-a1c0-3723af4ec87d, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:50,327 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:50,327 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:50,328 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, adaa0f5c-9ade-4a9f-9f7e-17308a3fc23b, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:13:50,329 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, adaa0f5c-9ade-4a9f-9f7e-17308a3fc23b, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_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:50,330 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, adaa0f5c-9ade-4a9f-9f7e-17308a3fc23b, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:52,817 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:52,818 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318544, 'BlobCount': 128}}
2025-10-15 15:13:52,819 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:52,820 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fdad184c-58d7-4b6f-bec3-d2a1d1840dde, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:13:52,821 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fdad184c-58d7-4b6f-bec3-d2a1d1840dde, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:52,823 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fdad184c-58d7-4b6f-bec3-d2a1d1840dde, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:56,977 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:56,978 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:56,979 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 854b5f25-caf1-4c5d-bc62-86ad01886f3d, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:13:56,980 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 854b5f25-caf1-4c5d-bc62-86ad01886f3d, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:56,981 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 854b5f25-caf1-4c5d-bc62-86ad01886f3d, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:57,536 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:57,537 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:57,538 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 440d3a07-725b-4a28-9b6e-4100bd2db2b1, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:13:57,539 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 440d3a07-725b-4a28-9b6e-4100bd2db2b1, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_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:57,541 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 440d3a07-725b-4a28-9b6e-4100bd2db2b1, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:57,988 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:57,989 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638064, 'BlobCount': 256}}
2025-10-15 15:13:57,989 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:13:57,990 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 76b0343f-641b-48d9-9545-f529a1abdeb3, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:13:57,991 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 76b0343f-641b-48d9-9545-f529a1abdeb3, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:58,002 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 76b0343f-641b-48d9-9545-f529a1abdeb3, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:01,938 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:01,939 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:01,940 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 58809bcd-daa3-4040-b0cd-cd12cd51bab3, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:01,940 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 58809bcd-daa3-4040-b0cd-cd12cd51bab3, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:01,942 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 58809bcd-daa3-4040-b0cd-cd12cd51bab3, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:02,405 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:02,406 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:02,406 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a898e153-7955-4b4b-9634-f045842d34d4, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:02,407 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a898e153-7955-4b4b-9634-f045842d34d4, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:02,411 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a898e153-7955-4b4b-9634-f045842d34d4, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:02,930 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:02,931 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 174}}, blobs: {'__DEFAULT': {'Portions': 174, 'BlobSize': 986424, 'BlobCount': 384}}
2025-10-15 15:14:02,931 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:02,932 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b1f07d06-fcf6-46cf-8ec8-ff9a16cf6316, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:02,933 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b1f07d06-fcf6-46cf-8ec8-ff9a16cf6316, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:02,934 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b1f07d06-fcf6-46cf-8ec8-ff9a16cf6316, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:06,517 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:06,518 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:06,518 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b203deb6-ed24-406a-818b-78ecc7564e4a, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:06,519 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b203deb6-ed24-406a-818b-78ecc7564e4a, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:06,522 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b203deb6-ed24-406a-818b-78ecc7564e4a, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:06,966 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:06,967 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:06,968 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 91806d54-5ac0-48f5-a61a-0a8204c27704, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:06,968 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 91806d54-5ac0-48f5-a61a-0a8204c27704, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:06,970 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 91806d54-5ac0-48f5-a61a-0a8204c27704, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:07,531 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:07,545 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 224}}, blobs: {'__DEFAULT': {'Portions': 224, 'BlobSize': 1319808, 'BlobCount': 498}}
2025-10-15 15:14:07,546 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:07,547 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4bfce1b6-8c9d-4dda-9a44-24437479b9ab, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:07,548 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4bfce1b6-8c9d-4dda-9a44-24437479b9ab, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:07,550 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4bfce1b6-8c9d-4dda-9a44-24437479b9ab, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:11,753 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:11,754 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:11,756 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f9f4a469-f486-468c-a1ec-13202a22dac1, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:11,757 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f9f4a469-f486-468c-a1ec-13202a22dac1, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:11,758 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f9f4a469-f486-468c-a1ec-13202a22dac1, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:12,168 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:12,169 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:12,170 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4ef3ddbe-85f7-4a4d-be80-50bce01cded1, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:12,171 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4ef3ddbe-85f7-4a4d-be80-50bce01cded1, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:12,173 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4ef3ddbe-85f7-4a4d-be80-50bce01cded1, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:12,910 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:12,911 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 288}}, blobs: {'__DEFAULT': {'Portions': 288, 'BlobSize': 1638832, 'BlobCount': 626}}
2025-10-15 15:14:12,912 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:12,913 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c3c9aac2-5859-4044-9af4-5b84e0f8f549, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:12,914 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c3c9aac2-5859-4044-9af4-5b84e0f8f549, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:12,915 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c3c9aac2-5859-4044-9af4-5b84e0f8f549, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:16,265 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:16,266 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:16,267 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 161b850b-f93c-4a34-a3d9-0a636b08f574, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:16,268 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 161b850b-f93c-4a34-a3d9-0a636b08f574, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:16,278 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 161b850b-f93c-4a34-a3d9-0a636b08f574, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:16,641 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:16,642 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:16,643 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3ee06fb9-9168-42e6-a1ac-84c156648f29, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:16,644 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3ee06fb9-9168-42e6-a1ac-84c156648f29, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:16,646 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3ee06fb9-9168-42e6-a1ac-84c156648f29, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:17,165 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:17,166 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1984408, 'BlobCount': 708}}
2025-10-15 15:14:17,167 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:17,168 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7f597506-7471-41fa-b36e-809ec3fa65e1, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:17,171 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7f597506-7471-41fa-b36e-809ec3fa65e1, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:17,173 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7f597506-7471-41fa-b36e-809ec3fa65e1, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:20,710 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:20,711 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:20,712 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 693a0375-9048-470c-ab86-f18c601f7379, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:20,713 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 693a0375-9048-470c-ab86-f18c601f7379, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:20,715 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 693a0375-9048-470c-ab86-f18c601f7379, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:21,005 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:21,006 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:21,007 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 363840e6-a796-428f-bc87-ef9f30135817, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:21,007 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 363840e6-a796-428f-bc87-ef9f30135817, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:21,009 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 363840e6-a796-428f-bc87-ef9f30135817, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:21,774 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:21,775 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 369}}, blobs: {'__DEFAULT': {'Portions': 369, 'BlobSize': 2322472, 'BlobCount': 812}}
2025-10-15 15:14:21,776 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:21,776 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d648fb7e-6fe1-4666-b3e9-5067e7a538ee, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:21,777 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d648fb7e-6fe1-4666-b3e9-5067e7a538ee, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:21,780 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d648fb7e-6fe1-4666-b3e9-5067e7a538ee, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:25,190 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:25,191 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:25,191 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a015c7cb-69d2-4f8a-a3dc-b6f93c7a7659, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:25,192 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a015c7cb-69d2-4f8a-a3dc-b6f93c7a7659, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:25,194 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a015c7cb-69d2-4f8a-a3dc-b6f93c7a7659, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:25,642 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:25,644 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:25,644 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5d40ebf3-3535-4342-ad68-6f5b6c3dec4c, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:25,645 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5d40ebf3-3535-4342-ad68-6f5b6c3dec4c, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:25,647 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5d40ebf3-3535-4342-ad68-6f5b6c3dec4c, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:26,579 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:26,580 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 433}}, blobs: {'__DEFAULT': {'Portions': 433, 'BlobSize': 2642088, 'BlobCount': 940}}
2025-10-15 15:14:26,581 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:26,581 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c0bd6371-e49d-48ee-9bf2-83d43c8b5085, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:26,583 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c0bd6371-e49d-48ee-9bf2-83d43c8b5085, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:26,584 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c0bd6371-e49d-48ee-9bf2-83d43c8b5085, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:30,461 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:30,462 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:30,463 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 65c68ed6-e617-4a65-a4f2-e5e2a19692ac, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:30,464 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 65c68ed6-e617-4a65-a4f2-e5e2a19692ac, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:30,466 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 65c68ed6-e617-4a65-a4f2-e5e2a19692ac, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:30,846 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:30,847 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:30,847 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d46509d-1157-4070-8a2e-2b6dc9ce7827, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:30,848 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d46509d-1157-4070-8a2e-2b6dc9ce7827, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:30,850 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d46509d-1157-4070-8a2e-2b6dc9ce7827, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:31,710 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:31,710 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 420}}, blobs: {'__DEFAULT': {'Portions': 420, 'BlobSize': 3041408, 'BlobCount': 936}}
2025-10-15 15:14:31,711 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:31,712 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c72351ad-79e5-4b0d-9d5a-2c793f9b5f79, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:31,715 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c72351ad-79e5-4b0d-9d5a-2c793f9b5f79, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:31,717 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c72351ad-79e5-4b0d-9d5a-2c793f9b5f79, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:35,207 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:35,208 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:35,209 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b4cb6a9e-5611-464f-8289-9278dc0ba67c, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:35,210 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b4cb6a9e-5611-464f-8289-9278dc0ba67c, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:35,211 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b4cb6a9e-5611-464f-8289-9278dc0ba67c, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:35,558 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:35,559 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:35,559 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2d277068-f664-42d0-9d04-d80b29375d5b, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:35,560 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2d277068-f664-42d0-9d04-d80b29375d5b, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:35,561 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2d277068-f664-42d0-9d04-d80b29375d5b, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:36,244 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:36,245 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 460}}, blobs: {'__DEFAULT': {'Portions': 460, 'BlobSize': 3388456, 'BlobCount': 1022}}
2025-10-15 15:14:36,245 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:36,245 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e06fb2e6-ed07-4de5-bad6-5473e5fec988, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:36,246 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e06fb2e6-ed07-4de5-bad6-5473e5fec988, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_15_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:36,247 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e06fb2e6-ed07-4de5-bad6-5473e5fec988, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:37,357 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:37,358 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:37,358 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 34984070-5005-4be9-82e4-e174975a0cf9, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:37,359 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 34984070-5005-4be9-82e4-e174975a0cf9, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:37,360 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 34984070-5005-4be9-82e4-e174975a0cf9, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:37,685 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:37,686 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:37,687 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8edb53f4-8f22-442e-90fa-7a2326a527f6, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:37,688 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8edb53f4-8f22-442e-90fa-7a2326a527f6, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:37,690 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8edb53f4-8f22-442e-90fa-7a2326a527f6, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:37,973 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:37,973 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:37,974 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ec92c07e-609c-4eb8-98ec-ca5e45a679b8, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:37,974 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ec92c07e-609c-4eb8-98ec-ca5e45a679b8, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:37,976 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ec92c07e-609c-4eb8-98ec-ca5e45a679b8, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:41,024 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:51,070 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:51,071 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 057e8b94-117f-41d0-ae0a-77376d6820b0, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:51,072 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 057e8b94-117f-41d0-ae0a-77376d6820b0, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:51,075 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 057e8b94-117f-41d0-ae0a-77376d6820b0, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:53,281 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:53,307 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_15_compression`, volumes `value` (8000000, 1726520)
2025-10-15 15:14:53,308 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:53,309 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 359953ef-6c5f-4a2d-8401-599fdb60ae03, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:53,310 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 359953ef-6c5f-4a2d-8401-599fdb60ae03, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" 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_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:53,313 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 359953ef-6c5f-4a2d-8401-599fdb60ae03, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:53,581 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:14:53,581 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-15 15:14:53,604 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:14:53,605 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 32006a08-1193-4f23-9634-2eb74a20a442, localhost:10960): creating call state
2025-10-15 15:14:53,606 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 32006a08-1193-4f23-9634-2eb74a20a442, localhost:10960): request = { database: "/Root" }
2025-10-15 15:14:53,613 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 32006a08-1193-4f23-9634-2eb74a20a442, localhost:10960): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 10960 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-10-15 15:14:53,615 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:10960, location 1, ssl: False>]>
2025-10-15 15:14:53,615 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10960
2025-10-15 15:14:53,617 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:53,617 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:14:53,618 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b9ab4c28-7ee3-4cde-9fb9-7fe66a310272, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:14:53,619 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b9ab4c28-7ee3-4cde-9fb9-7fe66a310272, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:53,620 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b9ab4c28-7ee3-4cde-9fb9-7fe66a310272, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:55,808 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:15:05,846 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:15:05,847 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5e9f718a-ceb8-417d-a84c-8e67ddda863b, ghrun-lvxbqnxafi.auto.internal:10960): creating call state
2025-10-15 15:15:05,847 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5e9f718a-ceb8-417d-a84c-8e67ddda863b, ghrun-lvxbqnxafi.auto.internal:10960): request = { session_id: "ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:15:05,853 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5e9f718a-ceb8-417d-a84c-8e67ddda863b, ghrun-lvxbqnxafi.auto.internal:10960): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:15:08,438 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzBkNjAxYS1hMTQxYzZlZi0yN2NmNzFhNC1kZTI3NzJkNA==
2025-10-15 15:15:08,466 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_15_compression`, volumes `value` (8000000, 1726520)
2025-10-15 15:15:08,467 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_15_compression` 8078192 / 1726520: 4.678887009707388
2025-10-15 15:15:08,470 - INFO - ya.test - pytest_runtest_teardown: Test teardown
