2025-10-31 11:32:28,490 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 11:32:28,491 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_compression-COMPRESSION = "zstd"]
2025-10-31 11:32:28,491 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 11:32:28,491 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 11:32:28,494 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-31 11:32:28,495 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-31 11:32:28,495 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70wh/0019c3/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-31 11:32:28,506 - DEBUG - ya.test - execute: Command pid: 923431
2025-10-31 11:32:29,461 - DEBUG - ya.test - _save_outputs: Command (pid 923431) rc: 0
2025-10-31 11:32:29,462 - DEBUG - ya.test - _save_outputs: Command (pid 923431) elapsed time (sec): 0.9542012214660645
2025-10-31 11:32:29,462 - DEBUG - ya.test - _save_outputs: Command (pid 923431) idrss: 0
2025-10-31 11:32:29,463 - DEBUG - ya.test - _save_outputs: Command (pid 923431) inblock: 0
2025-10-31 11:32:29,463 - DEBUG - ya.test - _save_outputs: Command (pid 923431) isrss: 0
2025-10-31 11:32:29,463 - DEBUG - ya.test - _save_outputs: Command (pid 923431) ixrss: 0
2025-10-31 11:32:29,464 - DEBUG - ya.test - _save_outputs: Command (pid 923431) majflt: 0
2025-10-31 11:32:29,464 - DEBUG - ya.test - _save_outputs: Command (pid 923431) maxrss: 487440
2025-10-31 11:32:29,464 - DEBUG - ya.test - _save_outputs: Command (pid 923431) minflt: 45301
2025-10-31 11:32:29,464 - DEBUG - ya.test - _save_outputs: Command (pid 923431) msgrcv: 0
2025-10-31 11:32:29,465 - DEBUG - ya.test - _save_outputs: Command (pid 923431) msgsnd: 0
2025-10-31 11:32:29,467 - DEBUG - ya.test - _save_outputs: Command (pid 923431) nivcsw: 222
2025-10-31 11:32:29,467 - DEBUG - ya.test - _save_outputs: Command (pid 923431) nsignals: 0
2025-10-31 11:32:29,467 - DEBUG - ya.test - _save_outputs: Command (pid 923431) nswap: 0
2025-10-31 11:32:29,467 - DEBUG - ya.test - _save_outputs: Command (pid 923431) nvcsw: 14
2025-10-31 11:32:29,468 - DEBUG - ya.test - _save_outputs: Command (pid 923431) oublock: 8
2025-10-31 11:32:29,468 - DEBUG - ya.test - _save_outputs: Command (pid 923431) stime: 0.228065
2025-10-31 11:32:29,468 - DEBUG - ya.test - _save_outputs: Command (pid 923431) utime: 0.479186
2025-10-31 11:32:29,468 - DEBUG - ya.test - _save_outputs: Command (pid 923431) wtime: 0.965
2025-10-31 11:32:29,469 - DEBUG - ya.test - _save_outputs: Command (pid 923431) output:
Git info:
    Commit: bf6ff911495926b6bb7f5256c196b59a842137c6
    Branch: 
    Author: vporyadke <mczalyalov@gmail.com>
    Summary: Merge b9809b09cb4b5083a45f9e458df813fff23b2cde into a04e6ba412e7d697c2b9458e46daee2c991a915c

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-xve6mkyrcq
    Host information: 
        Linux ghrun-xve6mkyrcq 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-31 11:32:29,469 - DEBUG - ya.test - _save_outputs: Command (pid 923431) errors:

2025-10-31 11:32:29,470 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: bf6ff911495926b6bb7f5256c196b59a842137c6
    Branch: 
    Author: vporyadke <mczalyalov@gmail.com>
    Summary: Merge b9809b09cb4b5083a45f9e458df813fff23b2cde into a04e6ba412e7d697c2b9458e46daee2c991a915c

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-xve6mkyrcq
    Host information: 
        Linux ghrun-xve6mkyrcq 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-31 11:32:29,636 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70wh/0019c3/ydb/apps/ydbd/ydbd
2025-10-31 11:32:29,638 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16262
2025-10-31 11:32:29,641 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/70wh/0019c3/r3tmp/kikimr_cluster_kwopmkw5
2025-10-31 11:32:29,686 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70wh/0019c3/ydb/apps/ydbd/ydbd
2025-10-31 11:32:29,687 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/61781
2025-10-31 11:32:29,687 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6785
2025-10-31 11:32:29,688 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18243
2025-10-31 11:32:29,688 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6942
2025-10-31 11:32:29,691 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/70wh/0019c3/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/kikimr_configs"
2025-10-31 11:32:29,691 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/70wh/0019c3/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/70wh/0019c3/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/node_1/logfile_9egh4k_8.log --grpc-port=61781 --mon-port=6785 --ic-port=16262
2025-10-31 11:32:29,691 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:61781/1, disk_size 68719476736
2025-10-31 11:32:29,692 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/70wh/0019c3/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/kikimr_configs"
2025-10-31 11:32:29,692 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/70wh/0019c3/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/70wh/0019c3/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/node_1/logfile_9egh4k_8.log --grpc-port=61781 --mon-port=6785 --ic-port=16262
2025-10-31 11:32:29,694 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/70wh/0019c3/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/70wh/0019c3/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/70wh/0019c3/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/node_1/logfile_9egh4k_8.log', '--grpc-port=61781', '--mon-port=6785', '--ic-port=16262')' in '/home/runner/.ya/build/build_root/70wh/0019c3/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/node_1' (no wait)
2025-10-31 11:32:29,699 - DEBUG - ya.test - execute: Command pid: 923698
2025-10-31 11:32:29,700 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:61781/1
2025-10-31 11:32:29,703 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-31 11:32:29,710 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-31 11:32:29,710 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70wh/0019c3/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-31 11:32:29,720 - DEBUG - ya.test - execute: Command pid: 923721
2025-10-31 11:32:30,755 - DEBUG - ya.test - _save_outputs: Command (pid 923721) rc: 0
2025-10-31 11:32:30,756 - DEBUG - ya.test - _save_outputs: Command (pid 923721) elapsed time (sec): 1.0336627960205078
2025-10-31 11:32:30,757 - DEBUG - ya.test - _save_outputs: Command (pid 923721) idrss: 0
2025-10-31 11:32:30,757 - DEBUG - ya.test - _save_outputs: Command (pid 923721) inblock: 0
2025-10-31 11:32:30,757 - DEBUG - ya.test - _save_outputs: Command (pid 923721) isrss: 0
2025-10-31 11:32:30,758 - DEBUG - ya.test - _save_outputs: Command (pid 923721) ixrss: 0
2025-10-31 11:32:30,758 - DEBUG - ya.test - _save_outputs: Command (pid 923721) majflt: 0
2025-10-31 11:32:30,758 - DEBUG - ya.test - _save_outputs: Command (pid 923721) maxrss: 487440
2025-10-31 11:32:30,759 - DEBUG - ya.test - _save_outputs: Command (pid 923721) minflt: 45335
2025-10-31 11:32:30,759 - DEBUG - ya.test - _save_outputs: Command (pid 923721) msgrcv: 0
2025-10-31 11:32:30,759 - DEBUG - ya.test - _save_outputs: Command (pid 923721) msgsnd: 0
2025-10-31 11:32:30,759 - DEBUG - ya.test - _save_outputs: Command (pid 923721) nivcsw: 180
2025-10-31 11:32:30,760 - DEBUG - ya.test - _save_outputs: Command (pid 923721) nsignals: 0
2025-10-31 11:32:30,760 - DEBUG - ya.test - _save_outputs: Command (pid 923721) nswap: 0
2025-10-31 11:32:30,760 - DEBUG - ya.test - _save_outputs: Command (pid 923721) nvcsw: 14
2025-10-31 11:32:30,761 - DEBUG - ya.test - _save_outputs: Command (pid 923721) oublock: 8
2025-10-31 11:32:30,761 - DEBUG - ya.test - _save_outputs: Command (pid 923721) stime: 0.186515
2025-10-31 11:32:30,761 - DEBUG - ya.test - _save_outputs: Command (pid 923721) utime: 0.525857
2025-10-31 11:32:30,762 - DEBUG - ya.test - _save_outputs: Command (pid 923721) wtime: 1.044
2025-10-31 11:32:30,762 - DEBUG - ya.test - _save_outputs: Command (pid 923721) output:
Git info:
    Commit: bf6ff911495926b6bb7f5256c196b59a842137c6
    Branch: 
    Author: vporyadke <mczalyalov@gmail.com>
    Summary: Merge b9809b09cb4b5083a45f9e458df813fff23b2cde into a04e6ba412e7d697c2b9458e46daee2c991a915c

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-xve6mkyrcq
    Host information: 
        Linux ghrun-xve6mkyrcq 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-31 11:32:30,762 - DEBUG - ya.test - _save_outputs: Command (pid 923721) errors:

2025-10-31 11:32:30,763 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: bf6ff911495926b6bb7f5256c196b59a842137c6
    Branch: 
    Author: vporyadke <mczalyalov@gmail.com>
    Summary: Merge b9809b09cb4b5083a45f9e458df813fff23b2cde into a04e6ba412e7d697c2b9458e46daee2c991a915c
2025-10-31 11:32:36,292 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70wh/0019c3/ydb/apps/ydbd/ydbd
2025-10-31 11:32:36,293 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/70wh/0019c3/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:61781', '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: 16262\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-31 11:32:36,295 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-31 11:32:36,296 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-31 11:32:36,296 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70wh/0019c3/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:61781', '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: 16262\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-31 11:32:36,309 - DEBUG - ya.test - execute: Command pid: 925680
2025-10-31 11:32:37,833 - DEBUG - ya.test - _save_outputs: Command (pid 925680) rc: 0
2025-10-31 11:32:37,833 - DEBUG - ya.test - _save_outputs: Command (pid 925680) elapsed time (sec): 1.5224151611328125
2025-10-31 11:32:37,834 - DEBUG - ya.test - _save_outputs: Command (pid 925680) idrss: 0
2025-10-31 11:32:37,834 - DEBUG - ya.test - _save_outputs: Command (pid 925680) inblock: 0
2025-10-31 11:32:37,834 - DEBUG - ya.test - _save_outputs: Command (pid 925680) isrss: 0
2025-10-31 11:32:37,834 - DEBUG - ya.test - _save_outputs: Command (pid 925680) ixrss: 0
2025-10-31 11:32:37,835 - DEBUG - ya.test - _save_outputs: Command (pid 925680) majflt: 0
2025-10-31 11:32:37,835 - DEBUG - ya.test - _save_outputs: Command (pid 925680) maxrss: 598408
2025-10-31 11:32:37,835 - DEBUG - ya.test - _save_outputs: Command (pid 925680) minflt: 51673
2025-10-31 11:32:37,836 - DEBUG - ya.test - _save_outputs: Command (pid 925680) msgrcv: 0
2025-10-31 11:32:37,836 - DEBUG - ya.test - _save_outputs: Command (pid 925680) msgsnd: 0
2025-10-31 11:32:37,836 - DEBUG - ya.test - _save_outputs: Command (pid 925680) nivcsw: 304
2025-10-31 11:32:37,837 - DEBUG - ya.test - _save_outputs: Command (pid 925680) nsignals: 0
2025-10-31 11:32:37,837 - DEBUG - ya.test - _save_outputs: Command (pid 925680) nswap: 0
2025-10-31 11:32:37,838 - DEBUG - ya.test - _save_outputs: Command (pid 925680) nvcsw: 674
2025-10-31 11:32:37,838 - DEBUG - ya.test - _save_outputs: Command (pid 925680) oublock: 8
2025-10-31 11:32:37,838 - DEBUG - ya.test - _save_outputs: Command (pid 925680) stime: 0.465302
2025-10-31 11:32:37,838 - DEBUG - ya.test - _save_outputs: Command (pid 925680) utime: 0.5280239999999999
2025-10-31 11:32:37,839 - DEBUG - ya.test - _save_outputs: Command (pid 925680) wtime: 1.536
2025-10-31 11:32:37,839 - DEBUG - ya.test - _save_outputs: Command (pid 925680) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-31 11:32:37,839 - DEBUG - ya.test - _save_outputs: Command (pid 925680) errors:

2025-10-31 11:32:37,846 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70wh/0019c3/ydb/apps/ydbd/ydbd
2025-10-31 11:32:37,846 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/70wh/0019c3/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:61781', '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-31 11:32:37,848 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-31 11:32:37,849 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-31 11:32:37,853 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70wh/0019c3/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:61781', '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-31 11:32:37,859 - DEBUG - ya.test - execute: Command pid: 926014
2025-10-31 11:32:39,116 - DEBUG - ya.test - _save_outputs: Command (pid 926014) rc: 0
2025-10-31 11:32:39,117 - DEBUG - ya.test - _save_outputs: Command (pid 926014) elapsed time (sec): 1.255882740020752
2025-10-31 11:32:39,117 - DEBUG - ya.test - _save_outputs: Command (pid 926014) idrss: 0
2025-10-31 11:32:39,118 - DEBUG - ya.test - _save_outputs: Command (pid 926014) inblock: 0
2025-10-31 11:32:39,118 - DEBUG - ya.test - _save_outputs: Command (pid 926014) isrss: 0
2025-10-31 11:32:39,118 - DEBUG - ya.test - _save_outputs: Command (pid 926014) ixrss: 0
2025-10-31 11:32:39,118 - DEBUG - ya.test - _save_outputs: Command (pid 926014) majflt: 0
2025-10-31 11:32:39,119 - DEBUG - ya.test - _save_outputs: Command (pid 926014) maxrss: 598688
2025-10-31 11:32:39,119 - DEBUG - ya.test - _save_outputs: Command (pid 926014) minflt: 51651
2025-10-31 11:32:39,119 - DEBUG - ya.test - _save_outputs: Command (pid 926014) msgrcv: 0
2025-10-31 11:32:39,120 - DEBUG - ya.test - _save_outputs: Command (pid 926014) msgsnd: 0
2025-10-31 11:32:39,120 - DEBUG - ya.test - _save_outputs: Command (pid 926014) nivcsw: 201
2025-10-31 11:32:39,120 - DEBUG - ya.test - _save_outputs: Command (pid 926014) nsignals: 0
2025-10-31 11:32:39,120 - DEBUG - ya.test - _save_outputs: Command (pid 926014) nswap: 0
2025-10-31 11:32:39,122 - DEBUG - ya.test - _save_outputs: Command (pid 926014) nvcsw: 729
2025-10-31 11:32:39,122 - DEBUG - ya.test - _save_outputs: Command (pid 926014) oublock: 8
2025-10-31 11:32:39,122 - DEBUG - ya.test - _save_outputs: Command (pid 926014) stime: 0.236203
2025-10-31 11:32:39,123 - DEBUG - ya.test - _save_outputs: Command (pid 926014) utime: 0.560407
2025-10-31 11:32:39,123 - DEBUG - ya.test - _save_outputs: Command (pid 926014) wtime: 1.262
2025-10-31 11:32:39,123 - DEBUG - ya.test - _save_outputs: Command (pid 926014) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-31 11:32:39,124 - DEBUG - ya.test - _save_outputs: Command (pid 926014) errors:

2025-10-31 11:32:39,124 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-31 11:32:39,298 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-31 11:32:39,366 - 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-31 11:32:39,378 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 11:32:39,378 - 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-31 11:32:39,445 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 11:32:39,446 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 4c41e00b-ba6f-4286-8505-6f417b54909c, localhost:61781): creating call state
2025-10-31 11:32:39,447 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 4c41e00b-ba6f-4286-8505-6f417b54909c, localhost:61781): request = { database: "/Root" }
2025-10-31 11:32:39,457 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 4c41e00b-ba6f-4286-8505-6f417b54909c, localhost:61781): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-xve6mkyrcq.auto.internal" port: 61781 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-31 11:32:39,458 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-xve6mkyrcq.auto.internal:61781, location 1, ssl: False>]>
2025-10-31 11:32:39,459 - 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-31 11:32:39,481 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:61781
2025-10-31 11:32:39,482 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-10-31 11:32:39,484 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 7fd8a304-20dc-4632-95d4-70f69e54af4f, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:39,485 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 7fd8a304-20dc-4632-95d4-70f69e54af4f, ghrun-xve6mkyrcq.auto.internal:61781): request = {  }
2025-10-31 11:32:39,493 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 7fd8a304-20dc-4632-95d4-70f69e54af4f, ghrun-xve6mkyrcq.auto.internal:61781): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" node_id: 1 }
2025-10-31 11:32:39,494 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, b865e6c9-74c2-4aa3-81c6-01e656fb9ebc, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:39,501 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, b865e6c9-74c2-4aa3-81c6-01e656fb9ebc, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" }
2025-10-31 11:32:39,511 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, b865e6c9-74c2-4aa3-81c6-01e656fb9ebc, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:39,522 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:39,523 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7278f58f-5364-455a-bf12-a9f0ebaa1c97, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:39,524 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7278f58f-5364-455a-bf12-a9f0ebaa1c97, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:39,526 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7278f58f-5364-455a-bf12-a9f0ebaa1c97, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:43,363 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:43,363 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-10-31 11:32:43,364 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:43,365 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 64357979-930e-4203-89c8-b692bf3e063c, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:43,366 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 64357979-930e-4203-89c8-b692bf3e063c, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:43,370 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 64357979-930e-4203-89c8-b692bf3e063c, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:44,521 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:44,522 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:44,523 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ca8a0583-3a44-4118-a9eb-17bc4373bde2, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:44,524 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ca8a0583-3a44-4118-a9eb-17bc4373bde2, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:44,525 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ca8a0583-3a44-4118-a9eb-17bc4373bde2, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:45,599 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:45,599 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:45,600 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c346cd7b-6afa-4ed7-8456-ff0e93ebb92f, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:45,601 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c346cd7b-6afa-4ed7-8456-ff0e93ebb92f, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:45,602 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c346cd7b-6afa-4ed7-8456-ff0e93ebb92f, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:46,534 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:46,535 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:46,535 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2a9af731-c2fe-497c-9de9-0f3bbfd5f834, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:46,536 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2a9af731-c2fe-497c-9de9-0f3bbfd5f834, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:46,539 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2a9af731-c2fe-497c-9de9-0f3bbfd5f834, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:48,131 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:48,132 - 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-31 11:32:48,132 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:48,133 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f8cf3459-8039-4d81-a10a-7c9f4bf60d07, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:48,133 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f8cf3459-8039-4d81-a10a-7c9f4bf60d07, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:48,135 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f8cf3459-8039-4d81-a10a-7c9f4bf60d07, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:49,203 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:49,204 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:49,205 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cf31224e-9840-4988-b30a-9155243f48ac, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:49,205 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cf31224e-9840-4988-b30a-9155243f48ac, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:49,207 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cf31224e-9840-4988-b30a-9155243f48ac, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:49,467 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:49,468 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:49,468 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 84e6f45b-f37b-4ca6-8b43-6c8b3de69f43, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:49,469 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 84e6f45b-f37b-4ca6-8b43-6c8b3de69f43, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:49,470 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 84e6f45b-f37b-4ca6-8b43-6c8b3de69f43, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:49,782 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:49,782 - 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-31 11:32:49,783 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:49,784 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ad1a11b9-a92b-453c-9060-5434cde2c7a3, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:49,784 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ad1a11b9-a92b-453c-9060-5434cde2c7a3, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:49,787 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ad1a11b9-a92b-453c-9060-5434cde2c7a3, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:51,038 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:51,039 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:51,040 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fbca076d-54fc-491f-ae70-9a9c2d4d64f3, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:51,041 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fbca076d-54fc-491f-ae70-9a9c2d4d64f3, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:51,042 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fbca076d-54fc-491f-ae70-9a9c2d4d64f3, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:51,339 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:51,339 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:51,340 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 22e845bf-a0e6-48f3-8111-ec669a08f8fd, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:51,341 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 22e845bf-a0e6-48f3-8111-ec669a08f8fd, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:51,343 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 22e845bf-a0e6-48f3-8111-ec669a08f8fd, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:51,764 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:51,765 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 4855296, 'BlobCount': 384}}
2025-10-31 11:32:51,765 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:51,766 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bdfc2951-d8fc-413c-ad22-814adfd7deb4, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:51,767 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bdfc2951-d8fc-413c-ad22-814adfd7deb4, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:51,768 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bdfc2951-d8fc-413c-ad22-814adfd7deb4, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:52,903 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:52,903 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:52,904 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 66c1283b-3c8d-4084-a5ba-3c502dfa0e79, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:52,905 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 66c1283b-3c8d-4084-a5ba-3c502dfa0e79, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:52,906 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 66c1283b-3c8d-4084-a5ba-3c502dfa0e79, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:53,200 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:53,201 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:53,201 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4dcdabaa-95fd-4a2f-a36b-f6943a2e3050, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:53,202 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4dcdabaa-95fd-4a2f-a36b-f6943a2e3050, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:53,204 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4dcdabaa-95fd-4a2f-a36b-f6943a2e3050, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:53,757 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:53,758 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 6473728, 'BlobCount': 512}}
2025-10-31 11:32:53,758 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:53,759 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 67e8b614-9cd7-4ec3-9d99-2f23beb9e54f, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:53,760 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 67e8b614-9cd7-4ec3-9d99-2f23beb9e54f, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:53,761 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 67e8b614-9cd7-4ec3-9d99-2f23beb9e54f, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:54,866 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:54,867 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:54,867 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 80c39571-526e-46c4-9887-a3243b1ddcfd, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:54,868 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 80c39571-526e-46c4-9887-a3243b1ddcfd, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:54,869 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 80c39571-526e-46c4-9887-a3243b1ddcfd, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:55,208 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:55,209 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:55,209 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f1028359-ed0d-4b4a-ae21-8542560b18d4, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:55,210 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f1028359-ed0d-4b4a-ae21-8542560b18d4, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:55,211 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f1028359-ed0d-4b4a-ae21-8542560b18d4, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:55,954 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:55,955 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 8092160, 'BlobCount': 640}}
2025-10-31 11:32:55,955 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:55,956 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d24e4f5a-b6db-4c6a-b08d-8649041e5104, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:55,957 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d24e4f5a-b6db-4c6a-b08d-8649041e5104, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:55,959 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d24e4f5a-b6db-4c6a-b08d-8649041e5104, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:56,996 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:56,997 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:56,998 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3c895779-5378-4295-af05-3e9e287e4387, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:56,999 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3c895779-5378-4295-af05-3e9e287e4387, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:57,000 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3c895779-5378-4295-af05-3e9e287e4387, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:57,355 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:57,355 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:57,356 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 63dc3c0e-4502-4ae3-a70c-dc4b2194f0bb, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:57,357 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 63dc3c0e-4502-4ae3-a70c-dc4b2194f0bb, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:57,360 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 63dc3c0e-4502-4ae3-a70c-dc4b2194f0bb, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:57,963 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:57,964 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 9710592, 'BlobCount': 768}}
2025-10-31 11:32:57,965 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:57,965 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 928a0e77-f7f4-4bca-ac7d-a32fc51e04c2, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:57,966 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 928a0e77-f7f4-4bca-ac7d-a32fc51e04c2, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:57,968 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 928a0e77-f7f4-4bca-ac7d-a32fc51e04c2, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:59,073 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:59,074 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:59,075 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fa53d8ec-c4df-4281-b5cc-9ed1208c988b, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:59,076 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fa53d8ec-c4df-4281-b5cc-9ed1208c988b, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:59,079 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fa53d8ec-c4df-4281-b5cc-9ed1208c988b, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:59,478 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:59,479 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:59,479 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ec1914db-16ce-41d8-995a-90e27e5bee48, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:59,480 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ec1914db-16ce-41d8-995a-90e27e5bee48, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:59,482 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ec1914db-16ce-41d8-995a-90e27e5bee48, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:59,955 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:59,956 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 11329024, 'BlobCount': 896}}
2025-10-31 11:32:59,956 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:32:59,957 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9582eb01-95ad-4774-8650-4ad1524ccdbf, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:32:59,958 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9582eb01-95ad-4774-8650-4ad1524ccdbf, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:32:59,960 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9582eb01-95ad-4774-8650-4ad1524ccdbf, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:01,185 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:01,186 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:01,186 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8507c230-8b30-41f6-ab0e-3c3c93e69d49, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:01,187 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8507c230-8b30-41f6-ab0e-3c3c93e69d49, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:33:01,188 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8507c230-8b30-41f6-ab0e-3c3c93e69d49, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:01,488 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:01,488 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:01,489 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 60d64dd7-e7f0-4b45-91c8-1cf1ceeb3896, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:01,490 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 60d64dd7-e7f0-4b45-91c8-1cf1ceeb3896, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:33:01,493 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 60d64dd7-e7f0-4b45-91c8-1cf1ceeb3896, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:02,496 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:02,496 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 12947456, 'BlobCount': 1024}}
2025-10-31 11:33:02,497 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:02,497 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 018d9182-de2d-43c8-8fe3-954a4de6f50e, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:02,498 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 018d9182-de2d-43c8-8fe3-954a4de6f50e, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:33:02,501 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 018d9182-de2d-43c8-8fe3-954a4de6f50e, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:03,736 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:03,737 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:03,737 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2c29250c-b917-4a3c-962b-60a7db9c1592, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:03,738 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2c29250c-b917-4a3c-962b-60a7db9c1592, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:33:03,740 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2c29250c-b917-4a3c-962b-60a7db9c1592, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:04,103 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:04,104 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:04,105 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 89f00129-8373-475b-ad9c-b7cabbb491f6, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:04,106 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 89f00129-8373-475b-ad9c-b7cabbb491f6, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:33:04,118 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 89f00129-8373-475b-ad9c-b7cabbb491f6, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:05,157 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:05,158 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 14565888, 'BlobCount': 1152}}
2025-10-31 11:33:05,158 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:05,159 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4cb24852-2825-4f3f-8f1b-35b83ded7ce4, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:05,160 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4cb24852-2825-4f3f-8f1b-35b83ded7ce4, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:33:05,163 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4cb24852-2825-4f3f-8f1b-35b83ded7ce4, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:06,435 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:06,436 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:06,437 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2dbc62c5-b4f6-4918-9a5b-82f1bd607426, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:06,438 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2dbc62c5-b4f6-4918-9a5b-82f1bd607426, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:33:06,439 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2dbc62c5-b4f6-4918-9a5b-82f1bd607426, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:06,827 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:06,827 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:06,828 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b7bf8f8e-daa5-4f04-90dc-93007e6e7715, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:06,828 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b7bf8f8e-daa5-4f04-90dc-93007e6e7715, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:33:06,830 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b7bf8f8e-daa5-4f04-90dc-93007e6e7715, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:07,545 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:07,546 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 16184320, 'BlobCount': 1280}}
2025-10-31 11:33:07,547 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:07,547 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ef717dbf-a518-4290-a978-646fceb3fd20, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:07,548 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ef717dbf-a518-4290-a978-646fceb3fd20, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:33:07,550 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ef717dbf-a518-4290-a978-646fceb3fd20, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:09,010 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:09,011 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:09,012 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ec3cb133-4202-4277-9f89-a77609ba2949, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:09,013 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ec3cb133-4202-4277-9f89-a77609ba2949, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:33:09,014 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ec3cb133-4202-4277-9f89-a77609ba2949, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:09,390 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:09,391 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:09,392 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4041ab41-b844-459a-a7a5-1e399376f0fa, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:09,393 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4041ab41-b844-459a-a7a5-1e399376f0fa, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:33:09,394 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4041ab41-b844-459a-a7a5-1e399376f0fa, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:09,702 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:09,702 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:09,703 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0bbb3078-ef8d-43b9-8ebe-9398261fd64c, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:09,703 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0bbb3078-ef8d-43b9-8ebe-9398261fd64c, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:33:09,704 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0bbb3078-ef8d-43b9-8ebe-9398261fd64c, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:13,739 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:23,775 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:23,775 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5fbb512c-e10c-45fd-900d-e61a396d6775, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:23,776 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5fbb512c-e10c-45fd-900d-e61a396d6775, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:33:23,777 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5fbb512c-e10c-45fd-900d-e61a396d6775, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:27,111 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:27,178 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-10-31 11:33:27,179 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:27,180 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 96a07b9e-9a9f-4034-bcf9-48231fed3da0, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:27,181 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 96a07b9e-9a9f-4034-bcf9-48231fed3da0, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:33:27,185 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 96a07b9e-9a9f-4034-bcf9-48231fed3da0, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:30,735 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:40,779 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:40,780 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e52f7aa0-12e6-4dcf-b844-44fb4a8365bd, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:40,781 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e52f7aa0-12e6-4dcf-b844-44fb4a8365bd, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:33:40,782 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e52f7aa0-12e6-4dcf-b844-44fb4a8365bd, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:45,152 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:45,210 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-10-31 11:33:45,211 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:45,211 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4ca8ef9f-05da-43d1-811f-5199362e7a3f, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:45,212 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4ca8ef9f-05da-43d1-811f-5199362e7a3f, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" 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-31 11:33:45,215 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4ca8ef9f-05da-43d1-811f-5199362e7a3f, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:45,763 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:45,766 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_compression-COMPRESSION = "zstd"])
2025-10-31 11:33:45,767 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:45,768 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e4b03bfe-4d5a-4a76-9d8c-dee210820a68, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:45,768 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e4b03bfe-4d5a-4a76-9d8c-dee210820a68, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:33:45,770 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e4b03bfe-4d5a-4a76-9d8c-dee210820a68, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:49,617 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:49,618 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_compression created
2025-10-31 11:33:49,618 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:49,619 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3f281ba0-c99b-46ee-a8f8-7c74cb5d2f9b, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:49,620 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3f281ba0-c99b-46ee-a8f8-7c74cb5d2f9b, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:33:49,622 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3f281ba0-c99b-46ee-a8f8-7c74cb5d2f9b, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:50,469 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:50,470 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:50,471 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b2fe1c05-61ed-47ed-b00d-b923b07dab14, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:50,472 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b2fe1c05-61ed-47ed-b00d-b923b07dab14, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:33:50,473 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b2fe1c05-61ed-47ed-b00d-b923b07dab14, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:51,781 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:51,781 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:51,782 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9053b106-b063-4c64-b554-e8e8d0ff074b, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:51,783 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9053b106-b063-4c64-b554-e8e8d0ff074b, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:33:51,786 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9053b106-b063-4c64-b554-e8e8d0ff074b, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:52,801 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:52,802 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:52,802 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 48c25c63-501b-4889-af8e-cc1874d14ff4, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:52,803 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 48c25c63-501b-4889-af8e-cc1874d14ff4, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:33:52,806 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 48c25c63-501b-4889-af8e-cc1874d14ff4, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:54,403 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:54,404 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318880, 'BlobCount': 128}}
2025-10-31 11:33:54,404 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:54,405 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3f7fee1a-e872-4140-9a6e-8bc6c2fbaaa4, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:54,406 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3f7fee1a-e872-4140-9a6e-8bc6c2fbaaa4, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:33:54,407 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3f7fee1a-e872-4140-9a6e-8bc6c2fbaaa4, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:55,414 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:55,415 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:55,416 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a00df40a-88c6-46a5-9188-b827c95c8eac, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:55,416 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a00df40a-88c6-46a5-9188-b827c95c8eac, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:33:55,418 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a00df40a-88c6-46a5-9188-b827c95c8eac, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:55,698 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:55,699 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:55,700 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7a7cebfb-5677-48cd-b70d-ebe96b249fff, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:55,703 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7a7cebfb-5677-48cd-b70d-ebe96b249fff, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:33:55,704 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7a7cebfb-5677-48cd-b70d-ebe96b249fff, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:56,043 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:56,043 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638384, 'BlobCount': 256}}
2025-10-31 11:33:56,044 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:56,044 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b35f87fc-79e3-425c-9d3d-5e93476f2ce1, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:56,045 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b35f87fc-79e3-425c-9d3d-5e93476f2ce1, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:33:56,052 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b35f87fc-79e3-425c-9d3d-5e93476f2ce1, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:57,048 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:57,049 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:57,050 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ccbec6e6-a1c2-4344-a79d-b28f94cb58a3, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:57,051 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ccbec6e6-a1c2-4344-a79d-b28f94cb58a3, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:33:57,052 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ccbec6e6-a1c2-4344-a79d-b28f94cb58a3, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:57,260 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:57,260 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:57,261 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 03a8da3e-08d9-4082-af99-a0a3a501cbdd, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:57,261 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 03a8da3e-08d9-4082-af99-a0a3a501cbdd, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:33:57,262 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 03a8da3e-08d9-4082-af99-a0a3a501cbdd, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:57,578 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:57,579 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957552, 'BlobCount': 384}}
2025-10-31 11:33:57,579 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:57,580 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c99bf1f0-6dd1-423d-9ab7-4c91bfe5830e, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:57,581 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c99bf1f0-6dd1-423d-9ab7-4c91bfe5830e, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:33:57,583 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c99bf1f0-6dd1-423d-9ab7-4c91bfe5830e, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:58,596 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:58,597 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:58,598 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0b62fe9b-7836-494d-a584-18d0a5823a8d, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:58,599 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0b62fe9b-7836-494d-a584-18d0a5823a8d, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:33:58,600 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0b62fe9b-7836-494d-a584-18d0a5823a8d, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:58,814 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:58,815 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:58,816 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 68679d95-77d1-45c8-8461-adabbc40103d, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:58,817 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 68679d95-77d1-45c8-8461-adabbc40103d, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:33:58,818 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 68679d95-77d1-45c8-8461-adabbc40103d, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:59,229 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:59,230 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1277056, 'BlobCount': 512}}
2025-10-31 11:33:59,231 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:33:59,231 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3ea7f427-3ea6-47f3-ba17-030cefb8cc4b, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:33:59,232 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3ea7f427-3ea6-47f3-ba17-030cefb8cc4b, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:33:59,234 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3ea7f427-3ea6-47f3-ba17-030cefb8cc4b, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:00,386 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:00,387 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:00,388 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c4efd640-d0b7-4d12-b30a-c51be1840f17, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:00,388 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c4efd640-d0b7-4d12-b30a-c51be1840f17, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:00,390 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c4efd640-d0b7-4d12-b30a-c51be1840f17, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:00,634 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:00,635 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:00,635 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 97cea59f-bf55-452d-8521-39afe782f26e, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:00,636 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 97cea59f-bf55-452d-8521-39afe782f26e, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:00,638 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 97cea59f-bf55-452d-8521-39afe782f26e, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:01,158 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:01,159 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1596256, 'BlobCount': 640}}
2025-10-31 11:34:01,159 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:01,160 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 198b215a-791c-404b-890d-88839e58c92b, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:01,161 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 198b215a-791c-404b-890d-88839e58c92b, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:01,162 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 198b215a-791c-404b-890d-88839e58c92b, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:02,305 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:02,306 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:02,306 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 89575a61-dc06-4071-a18b-1e25989c6cb9, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:02,307 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 89575a61-dc06-4071-a18b-1e25989c6cb9, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:02,309 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 89575a61-dc06-4071-a18b-1e25989c6cb9, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:02,607 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:02,608 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:02,609 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2d62b95d-f4e5-4222-bc38-f607281f9924, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:02,610 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2d62b95d-f4e5-4222-bc38-f607281f9924, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:02,612 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2d62b95d-f4e5-4222-bc38-f607281f9924, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:03,121 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:03,122 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1915600, 'BlobCount': 768}}
2025-10-31 11:34:03,123 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:03,123 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8d2e669a-e805-4e9d-ae1d-e26396399417, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:03,124 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8d2e669a-e805-4e9d-ae1d-e26396399417, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:03,126 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8d2e669a-e805-4e9d-ae1d-e26396399417, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:04,342 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:04,343 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:04,344 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8ae323fb-dfbe-4021-8115-574ed920cf56, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:04,344 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8ae323fb-dfbe-4021-8115-574ed920cf56, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:04,346 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8ae323fb-dfbe-4021-8115-574ed920cf56, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:04,598 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:04,599 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:04,599 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e2f7d32e-48a4-490d-8f74-fc8627633b05, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:04,621 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e2f7d32e-48a4-490d-8f74-fc8627633b05, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:04,623 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e2f7d32e-48a4-490d-8f74-fc8627633b05, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:05,193 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:05,194 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234896, 'BlobCount': 896}}
2025-10-31 11:34:05,195 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:05,195 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3c4b39c0-b0b9-430c-856c-e38a49d3addb, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:05,196 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3c4b39c0-b0b9-430c-856c-e38a49d3addb, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:05,198 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3c4b39c0-b0b9-430c-856c-e38a49d3addb, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:06,358 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:06,359 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:06,360 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b2c7f435-5d49-4ab4-82fe-5b8fdad371e5, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:06,360 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b2c7f435-5d49-4ab4-82fe-5b8fdad371e5, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:06,362 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b2c7f435-5d49-4ab4-82fe-5b8fdad371e5, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:06,715 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:06,716 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:06,716 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d34ef773-51bd-40a0-b9f9-745ec3c8c9ab, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:06,717 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d34ef773-51bd-40a0-b9f9-745ec3c8c9ab, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:06,718 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d34ef773-51bd-40a0-b9f9-745ec3c8c9ab, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:07,299 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:07,300 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2554464, 'BlobCount': 1024}}
2025-10-31 11:34:07,300 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:07,301 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, afd5affc-52eb-4148-acd2-2e0c894d9489, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:07,302 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, afd5affc-52eb-4148-acd2-2e0c894d9489, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:07,303 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, afd5affc-52eb-4148-acd2-2e0c894d9489, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:08,369 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:08,370 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:08,371 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6976d980-809f-4f5c-b547-5209e19e8747, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:08,371 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6976d980-809f-4f5c-b547-5209e19e8747, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:08,373 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6976d980-809f-4f5c-b547-5209e19e8747, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:08,630 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:08,631 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:08,631 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 315af4ce-00d9-4c23-a148-35ba68443ce4, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:08,632 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 315af4ce-00d9-4c23-a148-35ba68443ce4, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:08,636 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 315af4ce-00d9-4c23-a148-35ba68443ce4, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:09,275 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:09,276 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2873568, 'BlobCount': 1152}}
2025-10-31 11:34:09,277 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:09,277 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d003fbb0-6a7f-48e2-b563-56a80baf5ca7, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:09,278 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d003fbb0-6a7f-48e2-b563-56a80baf5ca7, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:09,280 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d003fbb0-6a7f-48e2-b563-56a80baf5ca7, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:10,069 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 11:34:10,070 - 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-31 11:34:10,087 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 11:34:10,088 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 5eb93c16-02c1-477d-807f-305652ee59e5, localhost:61781): creating call state
2025-10-31 11:34:10,088 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 5eb93c16-02c1-477d-807f-305652ee59e5, localhost:61781): request = { database: "/Root" }
2025-10-31 11:34:10,095 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 5eb93c16-02c1-477d-807f-305652ee59e5, localhost:61781): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-xve6mkyrcq.auto.internal" port: 61781 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-31 11:34:10,096 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-xve6mkyrcq.auto.internal:61781, location 1, ssl: False>]>
2025-10-31 11:34:10,096 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:61781
2025-10-31 11:34:10,272 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:10,273 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:10,274 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ff15d199-5184-4c47-9935-0151248c81b4, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:10,274 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ff15d199-5184-4c47-9935-0151248c81b4, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:10,276 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ff15d199-5184-4c47-9935-0151248c81b4, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:10,569 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:10,570 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:10,571 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6aef19f5-d2b2-46ed-a334-e2d6df947d83, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:10,572 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6aef19f5-d2b2-46ed-a334-e2d6df947d83, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:10,577 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6aef19f5-d2b2-46ed-a334-e2d6df947d83, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:11,201 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:11,202 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3193216, 'BlobCount': 1280}}
2025-10-31 11:34:11,202 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:11,203 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 431be2ce-834a-4cd1-925c-f37cb5acbf5a, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:11,204 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 431be2ce-834a-4cd1-925c-f37cb5acbf5a, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:11,206 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 431be2ce-834a-4cd1-925c-f37cb5acbf5a, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:12,260 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:12,261 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:12,262 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2425cb47-eb34-42d7-9303-f70fc525a033, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:12,262 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2425cb47-eb34-42d7-9303-f70fc525a033, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:12,264 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2425cb47-eb34-42d7-9303-f70fc525a033, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:12,503 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:12,504 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:12,505 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cbaae8ac-30de-43a6-b34f-81d88f8f0379, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:12,505 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cbaae8ac-30de-43a6-b34f-81d88f8f0379, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:12,507 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cbaae8ac-30de-43a6-b34f-81d88f8f0379, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:12,755 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:12,756 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:12,756 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5144d07a-131d-417f-b414-62cf529b15d6, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:12,757 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5144d07a-131d-417f-b414-62cf529b15d6, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:12,759 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5144d07a-131d-417f-b414-62cf529b15d6, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:16,586 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:26,637 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:26,638 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ade8cbee-1a8e-43e0-8c1f-bfbcf18aba27, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:26,639 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ade8cbee-1a8e-43e0-8c1f-bfbcf18aba27, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:26,641 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ade8cbee-1a8e-43e0-8c1f-bfbcf18aba27, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:29,595 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:29,627 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_compression`, volumes `value` (8000000, 1596608)
2025-10-31 11:34:29,628 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:29,628 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 33d67b51-ed7f-4b3e-8dc8-7cb8b0309f49, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:29,629 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 33d67b51-ed7f-4b3e-8dc8-7cb8b0309f49, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:29,631 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 33d67b51-ed7f-4b3e-8dc8-7cb8b0309f49, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:29,946 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:29,947 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:29,948 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 94b53b82-4941-4219-8a6e-d2d144cb4fae, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:29,949 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 94b53b82-4941-4219-8a6e-d2d144cb4fae, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:29,950 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 94b53b82-4941-4219-8a6e-d2d144cb4fae, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:33,593 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:43,630 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:43,631 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a979f25c-6c82-48aa-9279-1498d80c5566, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:43,632 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a979f25c-6c82-48aa-9279-1498d80c5566, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:43,633 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a979f25c-6c82-48aa-9279-1498d80c5566, ghrun-xve6mkyrcq.auto.internal:61781): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:46,894 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=
2025-10-31 11:34:46,965 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_compression`, volumes `value` (8000000, 1596608)
2025-10-31 11:34:46,965 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_compression` 8092160 / 1596608: 5.0683448911692786
2025-10-31 11:34:46,967 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-31 11:34:46,968 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 34b57c7e-6a8a-467b-86da-e7ee9898557e, ghrun-xve6mkyrcq.auto.internal:61781): creating call state
2025-10-31 11:34:46,969 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 34b57c7e-6a8a-467b-86da-e7ee9898557e, ghrun-xve6mkyrcq.auto.internal:61781): request = { session_id: "ydb://session/3?node_id=1&id=YTg2N2MwOWMtZWQxYzYyMmUtNDU1Y2I0ZDEtMjRlMjM4YzI=" }
2025-10-31 11:34:46,978 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 34b57c7e-6a8a-467b-86da-e7ee9898557e, ghrun-xve6mkyrcq.auto.internal:61781): response = { status: SUCCESS }
2025-10-31 11:34:46,979 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-10-31 11:34:46,980 - DEBUG - ydb.topic - close: Close topic client
2025-10-31 11:34:46,980 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-31 11:34:46,980 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:61781
2025-10-31 11:34:46,982 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, b865e6c9-74c2-4aa3-81c6-01e656fb9ebc, ghrun-xve6mkyrcq.auto.internal:61781): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-10-31 11:34:46,984 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-xve6mkyrcq.auto.internal:61781
2025-10-31 11:34:46,986 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-31 11:35:04,009 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:61781/1
2025-10-31 11:35:04,013 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6942
2025-10-31 11:35:04,014 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18243
2025-10-31 11:35:04,014 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6785
2025-10-31 11:35:04,015 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/61781
2025-10-31 11:35:04,015 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16262
