2025-10-31 11:32:26,104 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 11:32:26,104 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_8_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 8]
2025-10-31 11:32:26,105 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 11:32:26,105 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 11:32:26,107 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-31 11:32:26,108 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-31 11:32:26,109 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70wh/0019d5/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-31 11:32:26,110 - DEBUG - ya.test - execute: Command pid: 922985
2025-10-31 11:32:27,185 - DEBUG - ya.test - _save_outputs: Command (pid 922985) rc: 0
2025-10-31 11:32:27,186 - DEBUG - ya.test - _save_outputs: Command (pid 922985) elapsed time (sec): 1.0739758014678955
2025-10-31 11:32:27,186 - DEBUG - ya.test - _save_outputs: Command (pid 922985) idrss: 0
2025-10-31 11:32:27,187 - DEBUG - ya.test - _save_outputs: Command (pid 922985) inblock: 0
2025-10-31 11:32:27,187 - DEBUG - ya.test - _save_outputs: Command (pid 922985) isrss: 0
2025-10-31 11:32:27,187 - DEBUG - ya.test - _save_outputs: Command (pid 922985) ixrss: 0
2025-10-31 11:32:27,188 - DEBUG - ya.test - _save_outputs: Command (pid 922985) majflt: 0
2025-10-31 11:32:27,188 - DEBUG - ya.test - _save_outputs: Command (pid 922985) maxrss: 491700
2025-10-31 11:32:27,188 - DEBUG - ya.test - _save_outputs: Command (pid 922985) minflt: 45418
2025-10-31 11:32:27,189 - DEBUG - ya.test - _save_outputs: Command (pid 922985) msgrcv: 0
2025-10-31 11:32:27,190 - DEBUG - ya.test - _save_outputs: Command (pid 922985) msgsnd: 0
2025-10-31 11:32:27,190 - DEBUG - ya.test - _save_outputs: Command (pid 922985) nivcsw: 192
2025-10-31 11:32:27,191 - DEBUG - ya.test - _save_outputs: Command (pid 922985) nsignals: 0
2025-10-31 11:32:27,191 - DEBUG - ya.test - _save_outputs: Command (pid 922985) nswap: 0
2025-10-31 11:32:27,191 - DEBUG - ya.test - _save_outputs: Command (pid 922985) nvcsw: 13
2025-10-31 11:32:27,191 - DEBUG - ya.test - _save_outputs: Command (pid 922985) oublock: 8
2025-10-31 11:32:27,192 - DEBUG - ya.test - _save_outputs: Command (pid 922985) stime: 0.215795
2025-10-31 11:32:27,192 - DEBUG - ya.test - _save_outputs: Command (pid 922985) utime: 0.5182209999999999
2025-10-31 11:32:27,192 - DEBUG - ya.test - _save_outputs: Command (pid 922985) wtime: 1.076
2025-10-31 11:32:27,193 - DEBUG - ya.test - _save_outputs: Command (pid 922985) 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:27,194 - DEBUG - ya.test - _save_outputs: Command (pid 922985) errors:

2025-10-31 11:32:27,194 - 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:27,462 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70wh/0019d5/ydb/apps/ydbd/ydbd
2025-10-31 11:32:27,464 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15540
2025-10-31 11:32:27,466 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/70wh/0019d5/r3tmp/kikimr_cluster_3r1r69i_
2025-10-31 11:32:27,512 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70wh/0019d5/ydb/apps/ydbd/ydbd
2025-10-31 11:32:27,517 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63459
2025-10-31 11:32:27,522 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13030
2025-10-31 11:32:27,522 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/32210
2025-10-31 11:32:27,523 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15937
2025-10-31 11:32:27,524 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/70wh/0019d5/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/kikimr_configs"
2025-10-31 11:32:27,525 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/70wh/0019d5/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/0019d5/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/node_1/logfile_w68to_cu.log --grpc-port=63459 --mon-port=13030 --ic-port=15540
2025-10-31 11:32:27,526 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:63459/1, disk_size 68719476736
2025-10-31 11:32:27,526 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/70wh/0019d5/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/kikimr_configs"
2025-10-31 11:32:27,527 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/70wh/0019d5/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/0019d5/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/node_1/logfile_w68to_cu.log --grpc-port=63459 --mon-port=13030 --ic-port=15540
2025-10-31 11:32:27,537 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/70wh/0019d5/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/70wh/0019d5/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/70wh/0019d5/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/node_1/logfile_w68to_cu.log', '--grpc-port=63459', '--mon-port=13030', '--ic-port=15540')' in '/home/runner/.ya/build/build_root/70wh/0019d5/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk8/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_8_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.8/cluster/node_1' (no wait)
2025-10-31 11:32:27,551 - DEBUG - ya.test - execute: Command pid: 923198
2025-10-31 11:32:27,552 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:63459/1
2025-10-31 11:32:27,553 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-31 11:32:27,554 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-31 11:32:27,554 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70wh/0019d5/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-31 11:32:27,575 - DEBUG - ya.test - execute: Command pid: 923199
2025-10-31 11:32:28,677 - DEBUG - ya.test - _save_outputs: Command (pid 923199) rc: 0
2025-10-31 11:32:28,677 - DEBUG - ya.test - _save_outputs: Command (pid 923199) elapsed time (sec): 1.101198673248291
2025-10-31 11:32:28,678 - DEBUG - ya.test - _save_outputs: Command (pid 923199) idrss: 0
2025-10-31 11:32:28,678 - DEBUG - ya.test - _save_outputs: Command (pid 923199) inblock: 0
2025-10-31 11:32:28,678 - DEBUG - ya.test - _save_outputs: Command (pid 923199) isrss: 0
2025-10-31 11:32:28,678 - DEBUG - ya.test - _save_outputs: Command (pid 923199) ixrss: 0
2025-10-31 11:32:28,679 - DEBUG - ya.test - _save_outputs: Command (pid 923199) majflt: 0
2025-10-31 11:32:28,679 - DEBUG - ya.test - _save_outputs: Command (pid 923199) maxrss: 498984
2025-10-31 11:32:28,679 - DEBUG - ya.test - _save_outputs: Command (pid 923199) minflt: 45402
2025-10-31 11:32:28,679 - DEBUG - ya.test - _save_outputs: Command (pid 923199) msgrcv: 0
2025-10-31 11:32:28,679 - DEBUG - ya.test - _save_outputs: Command (pid 923199) msgsnd: 0
2025-10-31 11:32:28,680 - DEBUG - ya.test - _save_outputs: Command (pid 923199) nivcsw: 219
2025-10-31 11:32:28,680 - DEBUG - ya.test - _save_outputs: Command (pid 923199) nsignals: 0
2025-10-31 11:32:28,680 - DEBUG - ya.test - _save_outputs: Command (pid 923199) nswap: 0
2025-10-31 11:32:28,680 - DEBUG - ya.test - _save_outputs: Command (pid 923199) nvcsw: 14
2025-10-31 11:32:28,680 - DEBUG - ya.test - _save_outputs: Command (pid 923199) oublock: 8
2025-10-31 11:32:28,681 - DEBUG - ya.test - _save_outputs: Command (pid 923199) stime: 0.193575
2025-10-31 11:32:28,682 - DEBUG - ya.test - _save_outputs: Command (pid 923199) utime: 0.506109
2025-10-31 11:32:28,683 - DEBUG - ya.test - _save_outputs: Command (pid 923199) wtime: 1.122
2025-10-31 11:32:28,683 - DEBUG - ya.test - _save_outputs: Command (pid 923199) 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:28,683 - DEBUG - ya.test - _save_outputs: Command (pid 923199) errors:

2025-10-31 11:32:28,684 - 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:33,874 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70wh/0019d5/ydb/apps/ydbd/ydbd
2025-10-31 11:32:33,875 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/70wh/0019d5/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:63459', '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: 15540\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-31 11:32:33,876 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-31 11:32:33,877 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-31 11:32:33,877 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70wh/0019d5/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:63459', '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: 15540\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-31 11:32:33,889 - DEBUG - ya.test - execute: Command pid: 925261
2025-10-31 11:32:35,283 - DEBUG - ya.test - _save_outputs: Command (pid 925261) rc: 0
2025-10-31 11:32:35,283 - DEBUG - ya.test - _save_outputs: Command (pid 925261) elapsed time (sec): 1.3924643993377686
2025-10-31 11:32:35,284 - DEBUG - ya.test - _save_outputs: Command (pid 925261) idrss: 0
2025-10-31 11:32:35,284 - DEBUG - ya.test - _save_outputs: Command (pid 925261) inblock: 0
2025-10-31 11:32:35,284 - DEBUG - ya.test - _save_outputs: Command (pid 925261) isrss: 0
2025-10-31 11:32:35,284 - DEBUG - ya.test - _save_outputs: Command (pid 925261) ixrss: 0
2025-10-31 11:32:35,285 - DEBUG - ya.test - _save_outputs: Command (pid 925261) majflt: 0
2025-10-31 11:32:35,285 - DEBUG - ya.test - _save_outputs: Command (pid 925261) maxrss: 585948
2025-10-31 11:32:35,285 - DEBUG - ya.test - _save_outputs: Command (pid 925261) minflt: 51622
2025-10-31 11:32:35,286 - DEBUG - ya.test - _save_outputs: Command (pid 925261) msgrcv: 0
2025-10-31 11:32:35,286 - DEBUG - ya.test - _save_outputs: Command (pid 925261) msgsnd: 0
2025-10-31 11:32:35,286 - DEBUG - ya.test - _save_outputs: Command (pid 925261) nivcsw: 215
2025-10-31 11:32:35,287 - DEBUG - ya.test - _save_outputs: Command (pid 925261) nsignals: 0
2025-10-31 11:32:35,287 - DEBUG - ya.test - _save_outputs: Command (pid 925261) nswap: 0
2025-10-31 11:32:35,287 - DEBUG - ya.test - _save_outputs: Command (pid 925261) nvcsw: 576
2025-10-31 11:32:35,287 - DEBUG - ya.test - _save_outputs: Command (pid 925261) oublock: 8
2025-10-31 11:32:35,287 - DEBUG - ya.test - _save_outputs: Command (pid 925261) stime: 0.274618
2025-10-31 11:32:35,288 - DEBUG - ya.test - _save_outputs: Command (pid 925261) utime: 0.50064
2025-10-31 11:32:35,288 - DEBUG - ya.test - _save_outputs: Command (pid 925261) wtime: 1.405
2025-10-31 11:32:35,288 - DEBUG - ya.test - _save_outputs: Command (pid 925261) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-31 11:32:35,289 - DEBUG - ya.test - _save_outputs: Command (pid 925261) errors:

2025-10-31 11:32:35,290 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/70wh/0019d5/ydb/apps/ydbd/ydbd
2025-10-31 11:32:35,291 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/70wh/0019d5/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:63459', '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:35,292 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-31 11:32:35,293 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-31 11:32:35,293 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/70wh/0019d5/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:63459', '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:35,305 - DEBUG - ya.test - execute: Command pid: 925509
2025-10-31 11:32:36,702 - DEBUG - ya.test - _save_outputs: Command (pid 925509) rc: 0
2025-10-31 11:32:36,702 - DEBUG - ya.test - _save_outputs: Command (pid 925509) elapsed time (sec): 1.3953886032104492
2025-10-31 11:32:36,702 - DEBUG - ya.test - _save_outputs: Command (pid 925509) idrss: 0
2025-10-31 11:32:36,703 - DEBUG - ya.test - _save_outputs: Command (pid 925509) inblock: 0
2025-10-31 11:32:36,703 - DEBUG - ya.test - _save_outputs: Command (pid 925509) isrss: 0
2025-10-31 11:32:36,703 - DEBUG - ya.test - _save_outputs: Command (pid 925509) ixrss: 0
2025-10-31 11:32:36,703 - DEBUG - ya.test - _save_outputs: Command (pid 925509) majflt: 0
2025-10-31 11:32:36,704 - DEBUG - ya.test - _save_outputs: Command (pid 925509) maxrss: 585948
2025-10-31 11:32:36,704 - DEBUG - ya.test - _save_outputs: Command (pid 925509) minflt: 51726
2025-10-31 11:32:36,704 - DEBUG - ya.test - _save_outputs: Command (pid 925509) msgrcv: 0
2025-10-31 11:32:36,705 - DEBUG - ya.test - _save_outputs: Command (pid 925509) msgsnd: 0
2025-10-31 11:32:36,710 - DEBUG - ya.test - _save_outputs: Command (pid 925509) nivcsw: 216
2025-10-31 11:32:36,710 - DEBUG - ya.test - _save_outputs: Command (pid 925509) nsignals: 0
2025-10-31 11:32:36,711 - DEBUG - ya.test - _save_outputs: Command (pid 925509) nswap: 0
2025-10-31 11:32:36,711 - DEBUG - ya.test - _save_outputs: Command (pid 925509) nvcsw: 558
2025-10-31 11:32:36,711 - DEBUG - ya.test - _save_outputs: Command (pid 925509) oublock: 8
2025-10-31 11:32:36,711 - DEBUG - ya.test - _save_outputs: Command (pid 925509) stime: 0.285614
2025-10-31 11:32:36,712 - DEBUG - ya.test - _save_outputs: Command (pid 925509) utime: 0.53079
2025-10-31 11:32:36,712 - DEBUG - ya.test - _save_outputs: Command (pid 925509) wtime: 1.407
2025-10-31 11:32:36,712 - DEBUG - ya.test - _save_outputs: Command (pid 925509) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-31 11:32:36,712 - DEBUG - ya.test - _save_outputs: Command (pid 925509) errors:

2025-10-31 11:32:36,717 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-31 11:32:36,910 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-31 11:32:36,975 - 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:36,991 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 11:32:36,992 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-31 11:32:37,054 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 11:32:37,055 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 189abb39-3229-41fa-abd9-713f279fad94, localhost:63459): creating call state
2025-10-31 11:32:37,056 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 189abb39-3229-41fa-abd9-713f279fad94, localhost:63459): request = { database: "/Root" }
2025-10-31 11:32:37,069 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 189abb39-3229-41fa-abd9-713f279fad94, localhost:63459): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-xve6mkyrcq.auto.internal" port: 63459 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:37,070 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-xve6mkyrcq.auto.internal:63459, location 1, ssl: False>]>
2025-10-31 11:32:37,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:32:37,095 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:63459
2025-10-31 11:32:37,097 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-10-31 11:32:37,099 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, d0897c06-785b-42f6-bf28-7d6f47626be3, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:37,099 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, d0897c06-785b-42f6-bf28-7d6f47626be3, ghrun-xve6mkyrcq.auto.internal:63459): request = {  }
2025-10-31 11:32:37,106 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, d0897c06-785b-42f6-bf28-7d6f47626be3, ghrun-xve6mkyrcq.auto.internal:63459): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" node_id: 1 }
2025-10-31 11:32:37,107 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, d1ac29c0-c935-473c-9f74-80c877e68734, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:37,108 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, d1ac29c0-c935-473c-9f74-80c877e68734, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" }
2025-10-31 11:32:37,135 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, d1ac29c0-c935-473c-9f74-80c877e68734, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:37,154 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:37,155 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9c8d1ac6-9a2e-4906-8b66-d1128d2455ee, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:37,156 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9c8d1ac6-9a2e-4906-8b66-d1128d2455ee, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:37,161 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9c8d1ac6-9a2e-4906-8b66-d1128d2455ee, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:41,039 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:41,040 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-10-31 11:32:41,040 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:41,041 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6c5914fc-806e-403c-a777-d6b46d8d9720, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:41,042 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6c5914fc-806e-403c-a777-d6b46d8d9720, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:41,043 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6c5914fc-806e-403c-a777-d6b46d8d9720, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:42,169 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:42,170 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:42,171 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7b6ec602-78d9-4b6c-aa63-9c5f3523c7a3, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:42,172 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7b6ec602-78d9-4b6c-aa63-9c5f3523c7a3, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:42,173 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7b6ec602-78d9-4b6c-aa63-9c5f3523c7a3, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:43,198 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:43,199 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:43,200 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1ef28f74-4d42-4d4a-b3bc-bbc87fcb90e5, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:43,201 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1ef28f74-4d42-4d4a-b3bc-bbc87fcb90e5, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:43,202 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1ef28f74-4d42-4d4a-b3bc-bbc87fcb90e5, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:44,049 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:44,050 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:44,051 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 565546a4-1f58-44db-be79-71e49e201bd7, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:44,052 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 565546a4-1f58-44db-be79-71e49e201bd7, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:44,054 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 565546a4-1f58-44db-be79-71e49e201bd7, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:45,741 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:45,742 - 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:45,743 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:45,743 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 797b3778-914f-482a-801b-1f0cfd9c2af4, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:45,744 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 797b3778-914f-482a-801b-1f0cfd9c2af4, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:45,755 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 797b3778-914f-482a-801b-1f0cfd9c2af4, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:46,829 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:46,830 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:46,831 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f0f74f45-2d79-4033-90c5-22201ebfd246, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:46,831 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f0f74f45-2d79-4033-90c5-22201ebfd246, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:46,833 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f0f74f45-2d79-4033-90c5-22201ebfd246, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:47,169 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:47,170 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:47,171 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 00d13832-b60f-4794-9212-1403b61d3f7c, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:47,172 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 00d13832-b60f-4794-9212-1403b61d3f7c, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:47,173 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 00d13832-b60f-4794-9212-1403b61d3f7c, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:47,572 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:47,573 - 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:47,574 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:47,574 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8112e14c-e918-4a78-9ee0-2563c7e06cdc, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:47,575 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8112e14c-e918-4a78-9ee0-2563c7e06cdc, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:47,586 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8112e14c-e918-4a78-9ee0-2563c7e06cdc, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:48,729 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:48,730 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:48,731 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 396adc15-dad8-46c5-ba12-da3d4ce1d510, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:48,731 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 396adc15-dad8-46c5-ba12-da3d4ce1d510, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:48,740 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 396adc15-dad8-46c5-ba12-da3d4ce1d510, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:49,078 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:49,079 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:49,080 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 47b70584-184f-48f9-82c1-8a505d528b16, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:49,080 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 47b70584-184f-48f9-82c1-8a505d528b16, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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,086 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 47b70584-184f-48f9-82c1-8a505d528b16, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:49,496 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:49,497 - 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:49,497 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:49,498 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d89a46d-a9b8-426a-89df-72fd73403a49, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:49,499 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d89a46d-a9b8-426a-89df-72fd73403a49, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:49,501 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d89a46d-a9b8-426a-89df-72fd73403a49, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:50,599 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:50,600 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:50,601 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4c880012-ef9f-4c6a-a017-882c3fce6512, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:50,601 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4c880012-ef9f-4c6a-a017-882c3fce6512, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:50,602 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4c880012-ef9f-4c6a-a017-882c3fce6512, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:50,856 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:50,857 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:50,857 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 95d85358-21ca-4696-ab2e-bbebf3ff169f, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:50,858 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 95d85358-21ca-4696-ab2e-bbebf3ff169f, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:50,860 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 95d85358-21ca-4696-ab2e-bbebf3ff169f, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:51,841 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:51,842 - 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:51,842 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:51,842 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 67e3b87c-645c-48a3-a313-1b2d410dbdb6, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:51,843 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 67e3b87c-645c-48a3-a313-1b2d410dbdb6, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:51,844 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 67e3b87c-645c-48a3-a313-1b2d410dbdb6, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:52,961 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:52,962 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:52,962 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cf0977be-5fef-46db-8002-e2747563c462, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:52,963 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cf0977be-5fef-46db-8002-e2747563c462, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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,964 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cf0977be-5fef-46db-8002-e2747563c462, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:53,412 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:53,413 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:53,414 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3fe401af-5341-4d57-9685-cdb711686937, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:53,415 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3fe401af-5341-4d57-9685-cdb711686937, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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,417 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3fe401af-5341-4d57-9685-cdb711686937, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:54,058 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:54,059 - 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:54,060 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:54,060 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6b913a23-c97d-4589-b1c9-4a17fec1d590, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:54,061 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6b913a23-c97d-4589-b1c9-4a17fec1d590, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:54,074 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6b913a23-c97d-4589-b1c9-4a17fec1d590, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:55,032 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:55,033 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:55,033 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f954bfc1-7018-48a6-8c9c-7cb73dc5ca61, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:55,034 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f954bfc1-7018-48a6-8c9c-7cb73dc5ca61, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:55,035 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f954bfc1-7018-48a6-8c9c-7cb73dc5ca61, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:55,278 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:55,279 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:55,279 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 281559f9-b2aa-454d-ad5e-b9ab38ba18a3, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:55,280 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 281559f9-b2aa-454d-ad5e-b9ab38ba18a3, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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,286 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 281559f9-b2aa-454d-ad5e-b9ab38ba18a3, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:55,729 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:55,730 - 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:55,730 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:55,731 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 310011bf-d26d-43e5-be7a-a5bc3b3c2d2e, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:55,732 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 310011bf-d26d-43e5-be7a-a5bc3b3c2d2e, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:55,736 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 310011bf-d26d-43e5-be7a-a5bc3b3c2d2e, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:56,989 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:56,990 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:56,991 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9a058d50-b734-4714-b14b-a83d18224f3d, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:56,992 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9a058d50-b734-4714-b14b-a83d18224f3d, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:56,994 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9a058d50-b734-4714-b14b-a83d18224f3d, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:57,387 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:57,388 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:57,389 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0d434a40-6351-49a8-a2ff-67d177d56320, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:57,390 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0d434a40-6351-49a8-a2ff-67d177d56320, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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,392 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0d434a40-6351-49a8-a2ff-67d177d56320, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:57,969 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:57,970 - 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:57,970 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:57,971 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bf49b56c-6242-4e1b-b5f5-28dd924fa46e, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:57,972 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bf49b56c-6242-4e1b-b5f5-28dd924fa46e, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:57,975 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bf49b56c-6242-4e1b-b5f5-28dd924fa46e, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:59,042 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:59,042 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:59,043 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 692b195b-5884-439c-b903-a580283632b7, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:59,044 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 692b195b-5884-439c-b903-a580283632b7, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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,049 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 692b195b-5884-439c-b903-a580283632b7, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:59,357 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:59,358 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:59,359 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b6f4e10a-22b5-48cc-9f60-360bd6a3cb04, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:59,360 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b6f4e10a-22b5-48cc-9f60-360bd6a3cb04, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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,363 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b6f4e10a-22b5-48cc-9f60-360bd6a3cb04, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:32:59,933 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:59,934 - 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:32:59,934 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:32:59,935 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 21f10122-82dd-4a67-bd87-df438c3ea6b0, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:32:59,936 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 21f10122-82dd-4a67-bd87-df438c3ea6b0, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:32:59,938 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 21f10122-82dd-4a67-bd87-df438c3ea6b0, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:01,093 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:01,094 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:01,095 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f5e2ac45-718f-4596-bc7e-4b12bb13c64a, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:33:01,096 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f5e2ac45-718f-4596-bc7e-4b12bb13c64a, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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,098 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f5e2ac45-718f-4596-bc7e-4b12bb13c64a, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:01,505 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:01,506 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:01,507 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e0b4603-509f-4fa9-8f7d-e3765db4bef2, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:33:01,508 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e0b4603-509f-4fa9-8f7d-e3765db4bef2, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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,510 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e0b4603-509f-4fa9-8f7d-e3765db4bef2, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:02,339 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:02,340 - 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:02,341 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:02,342 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 15b6c174-b742-49d9-aff4-1eee5621fe81, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:33:02,345 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 15b6c174-b742-49d9-aff4-1eee5621fe81, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:02,346 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 15b6c174-b742-49d9-aff4-1eee5621fe81, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:03,488 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:03,489 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:03,490 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7e5c0f7b-3a2c-4ead-8d78-fc79b7a499f0, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:33:03,491 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7e5c0f7b-3a2c-4ead-8d78-fc79b7a499f0, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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,492 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7e5c0f7b-3a2c-4ead-8d78-fc79b7a499f0, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:03,912 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:03,913 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:03,914 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3eae39af-acdc-42de-a98c-dab7cbbbb2fd, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:33:03,915 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3eae39af-acdc-42de-a98c-dab7cbbbb2fd, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:03,917 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3eae39af-acdc-42de-a98c-dab7cbbbb2fd, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:04,921 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:04,922 - 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:04,923 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:04,923 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e06d0a2d-55c7-4a7b-a691-57ff434a5bb3, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:33:04,924 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e06d0a2d-55c7-4a7b-a691-57ff434a5bb3, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:04,925 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e06d0a2d-55c7-4a7b-a691-57ff434a5bb3, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:06,782 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:06,783 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:06,784 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8d423d04-f337-4e3a-bae5-bae538ffd2e7, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:33:06,785 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8d423d04-f337-4e3a-bae5-bae538ffd2e7, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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,786 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8d423d04-f337-4e3a-bae5-bae538ffd2e7, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:07,210 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:07,211 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:07,211 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a1c6403e-5834-4afc-997b-a6fc78001914, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:33:07,212 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a1c6403e-5834-4afc-997b-a6fc78001914, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:07,214 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a1c6403e-5834-4afc-997b-a6fc78001914, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:07,548 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:07,549 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:07,549 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f4dc706e-360b-4901-812a-35d1b40fe696, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:33:07,550 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f4dc706e-360b-4901-812a-35d1b40fe696, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:07,551 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f4dc706e-360b-4901-812a-35d1b40fe696, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:12,067 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:22,133 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:22,134 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 84bb2642-2d01-41b6-9db5-5cffad8b51de, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:33:22,135 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 84bb2642-2d01-41b6-9db5-5cffad8b51de, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:22,136 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 84bb2642-2d01-41b6-9db5-5cffad8b51de, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:25,746 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:25,787 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-10-31 11:33:25,788 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:25,789 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 65ff9918-e9b6-4455-bed3-d4c15850e07f, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:33:25,790 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 65ff9918-e9b6-4455-bed3-d4c15850e07f, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:25,791 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 65ff9918-e9b6-4455-bed3-d4c15850e07f, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:29,646 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:39,685 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:39,686 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 103d7219-6299-4fb3-a8f1-1b69c1909259, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:33:39,687 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 103d7219-6299-4fb3-a8f1-1b69c1909259, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:39,688 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 103d7219-6299-4fb3-a8f1-1b69c1909259, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:43,804 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:53,870 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:33:53,871 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 67837ca4-fef1-491b-9314-82ffe6fa6be1, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:33:53,872 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 67837ca4-fef1-491b-9314-82ffe6fa6be1, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:53,874 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 67837ca4-fef1-491b-9314-82ffe6fa6be1, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:33:54,529 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:04,536 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:04,538 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3af32c5b-1728-4295-8870-849ca05ea59c, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:04,538 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3af32c5b-1728-4295-8870-849ca05ea59c, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:34:04,540 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3af32c5b-1728-4295-8870-849ca05ea59c, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:05,199 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:05,203 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-10-31 11:34:05,203 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:05,204 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fd9d0a1f-b7d1-421a-9159-7408c18096f1, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:05,205 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fd9d0a1f-b7d1-421a-9159-7408c18096f1, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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:34:05,206 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fd9d0a1f-b7d1-421a-9159-7408c18096f1, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:05,509 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:05,512 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_8_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 8])
2025-10-31 11:34:05,513 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:05,514 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0ed429db-e063-4946-a388-ea97f693114e, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:05,515 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0ed429db-e063-4946-a388-ea97f693114e, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_8_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 8)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:05,516 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0ed429db-e063-4946-a388-ea97f693114e, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:07,853 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 11:34:07,854 - 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:07,890 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 11:34:07,891 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 678681b7-7eec-4a8d-b58d-2af0596c808c, localhost:63459): creating call state
2025-10-31 11:34:07,891 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 678681b7-7eec-4a8d-b58d-2af0596c808c, localhost:63459): request = { database: "/Root" }
2025-10-31 11:34:07,959 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 678681b7-7eec-4a8d-b58d-2af0596c808c, localhost:63459): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-xve6mkyrcq.auto.internal" port: 63459 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:07,960 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-xve6mkyrcq.auto.internal:63459, location 1, ssl: False>]>
2025-10-31 11:34:07,963 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:63459
2025-10-31 11:34:08,409 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:08,410 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_8_compression created
2025-10-31 11:34:08,411 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:08,411 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b8853c97-fdf5-4621-8ff5-9cd8d57dd900, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:08,412 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b8853c97-fdf5-4621-8ff5-9cd8d57dd900, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_8_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:08,414 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b8853c97-fdf5-4621-8ff5-9cd8d57dd900, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:09,266 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:09,267 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:09,268 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 865ef6d6-2527-4443-858c-f76317fb3491, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:09,269 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 865ef6d6-2527-4443-858c-f76317fb3491, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:09,270 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 865ef6d6-2527-4443-858c-f76317fb3491, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:11,300 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:11,300 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:11,301 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 94ee8c9d-3b3b-4f4b-b84c-ce98ffbe536d, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:11,302 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 94ee8c9d-3b3b-4f4b-b84c-ce98ffbe536d, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:11,304 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 94ee8c9d-3b3b-4f4b-b84c-ce98ffbe536d, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:12,325 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:12,326 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:12,327 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 93ab709a-59b4-4758-9db7-6ea31589f24c, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:12,328 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 93ab709a-59b4-4758-9db7-6ea31589f24c, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_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:12,329 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 93ab709a-59b4-4758-9db7-6ea31589f24c, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:14,005 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:14,006 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318016, 'BlobCount': 128}}
2025-10-31 11:34:14,007 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:14,007 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dc77a85c-b3ba-476b-9e70-cee4f177faba, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:14,008 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dc77a85c-b3ba-476b-9e70-cee4f177faba, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:14,010 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dc77a85c-b3ba-476b-9e70-cee4f177faba, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:15,781 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:15,782 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:15,783 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8f6db7da-8292-41e5-8850-dffac3a9a556, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:15,784 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8f6db7da-8292-41e5-8850-dffac3a9a556, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:15,785 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8f6db7da-8292-41e5-8850-dffac3a9a556, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:16,038 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:16,039 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:16,040 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4cf286ac-5a2f-4ecc-b510-3cb0c7506bf6, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:16,041 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4cf286ac-5a2f-4ecc-b510-3cb0c7506bf6, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_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:16,042 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4cf286ac-5a2f-4ecc-b510-3cb0c7506bf6, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:16,338 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:16,338 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637312, 'BlobCount': 256}}
2025-10-31 11:34:16,339 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:16,339 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4fa7073c-a347-4897-b9ab-e81508445436, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:16,340 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4fa7073c-a347-4897-b9ab-e81508445436, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:16,342 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4fa7073c-a347-4897-b9ab-e81508445436, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:18,153 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:18,154 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:18,155 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, edaade1e-5085-4f63-b5ec-05139714cb32, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:18,155 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, edaade1e-5085-4f63-b5ec-05139714cb32, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:18,157 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, edaade1e-5085-4f63-b5ec-05139714cb32, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:18,357 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:18,358 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:18,358 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 60dfb6fa-3be4-4f9a-a470-50f72ba6f776, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:18,360 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 60dfb6fa-3be4-4f9a-a470-50f72ba6f776, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_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:18,361 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 60dfb6fa-3be4-4f9a-a470-50f72ba6f776, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:18,733 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:18,734 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956352, 'BlobCount': 384}}
2025-10-31 11:34:18,735 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:18,736 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 012ddcbe-fb26-4d4b-9fb9-152c823db67f, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:18,737 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 012ddcbe-fb26-4d4b-9fb9-152c823db67f, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:18,738 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 012ddcbe-fb26-4d4b-9fb9-152c823db67f, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:20,580 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:20,581 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:20,582 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 40897325-f256-4458-b958-fa5fe411f601, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:20,583 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 40897325-f256-4458-b958-fa5fe411f601, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:20,584 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 40897325-f256-4458-b958-fa5fe411f601, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:20,817 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:20,818 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:20,819 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, faf65de0-dd5c-4665-ab25-1d76944fbb54, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:20,820 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, faf65de0-dd5c-4665-ab25-1d76944fbb54, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_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:20,821 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, faf65de0-dd5c-4665-ab25-1d76944fbb54, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:21,149 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:21,149 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275712, 'BlobCount': 512}}
2025-10-31 11:34:21,150 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:21,151 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 141a5a9a-576b-42f7-a86f-0f305431463a, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:21,152 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 141a5a9a-576b-42f7-a86f-0f305431463a, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:21,156 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 141a5a9a-576b-42f7-a86f-0f305431463a, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:22,884 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:22,885 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:22,886 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, abaa68f9-10da-417f-ada1-19d484dc1dc5, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:22,887 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, abaa68f9-10da-417f-ada1-19d484dc1dc5, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:22,888 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, abaa68f9-10da-417f-ada1-19d484dc1dc5, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:23,111 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:23,112 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:23,112 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c66c1fa3-9a2a-44b1-9c82-aebdbe2847c7, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:23,113 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c66c1fa3-9a2a-44b1-9c82-aebdbe2847c7, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_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:23,114 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c66c1fa3-9a2a-44b1-9c82-aebdbe2847c7, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:23,566 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:23,567 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594672, 'BlobCount': 640}}
2025-10-31 11:34:23,568 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:23,568 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b300bb4b-f4a3-4806-9289-03bcbfff33d7, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:23,569 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b300bb4b-f4a3-4806-9289-03bcbfff33d7, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:23,572 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b300bb4b-f4a3-4806-9289-03bcbfff33d7, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:25,269 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:25,270 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:25,271 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c4266e81-7f02-4e04-a54e-a780a451736f, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:25,271 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c4266e81-7f02-4e04-a54e-a780a451736f, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:25,273 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c4266e81-7f02-4e04-a54e-a780a451736f, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:25,488 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:25,489 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:25,490 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e586f8b0-2470-41f5-9972-ba0ab3250e57, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:25,490 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e586f8b0-2470-41f5-9972-ba0ab3250e57, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_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:25,492 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e586f8b0-2470-41f5-9972-ba0ab3250e57, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:26,003 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:26,003 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913856, 'BlobCount': 768}}
2025-10-31 11:34:26,004 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:26,005 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4284b1d5-30c0-4cc4-a755-bfd26a3b2125, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:26,006 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4284b1d5-30c0-4cc4-a755-bfd26a3b2125, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:26,007 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4284b1d5-30c0-4cc4-a755-bfd26a3b2125, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:27,845 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:27,846 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:27,846 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aba6cacf-dd49-43b4-b39b-7e09e549c24d, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:27,847 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aba6cacf-dd49-43b4-b39b-7e09e549c24d, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:27,848 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aba6cacf-dd49-43b4-b39b-7e09e549c24d, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:28,101 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:28,102 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:28,103 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 78d56325-8ab4-46b3-98d4-b8053f66c1e9, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:28,103 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 78d56325-8ab4-46b3-98d4-b8053f66c1e9, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_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:28,106 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 78d56325-8ab4-46b3-98d4-b8053f66c1e9, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:28,573 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:28,574 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2232992, 'BlobCount': 896}}
2025-10-31 11:34:28,575 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:28,575 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 804a9cb9-d613-4379-bbe8-d241e5045853, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:28,576 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 804a9cb9-d613-4379-bbe8-d241e5045853, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:28,582 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 804a9cb9-d613-4379-bbe8-d241e5045853, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:30,177 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:30,178 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:30,179 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 19f32afe-a254-4d00-b807-3f696a0f8ca6, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:30,179 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 19f32afe-a254-4d00-b807-3f696a0f8ca6, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:30,181 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 19f32afe-a254-4d00-b807-3f696a0f8ca6, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:30,403 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:30,404 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:30,405 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b7d9980d-b738-467c-a02b-45ffb2a06cdb, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:30,406 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b7d9980d-b738-467c-a02b-45ffb2a06cdb, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_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:30,408 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b7d9980d-b738-467c-a02b-45ffb2a06cdb, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:30,925 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:30,926 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552480, 'BlobCount': 1024}}
2025-10-31 11:34:30,926 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:30,927 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 651aa3a4-7560-474f-95ce-4e4464c07bec, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:30,928 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 651aa3a4-7560-474f-95ce-4e4464c07bec, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:30,930 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 651aa3a4-7560-474f-95ce-4e4464c07bec, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:32,631 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:32,632 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:32,633 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 763da000-ce14-48ca-958a-10081d85b2c6, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:32,634 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 763da000-ce14-48ca-958a-10081d85b2c6, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:32,635 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 763da000-ce14-48ca-958a-10081d85b2c6, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:32,916 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:32,917 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:32,917 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3b034654-ec83-4fe6-9327-58ad73845414, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:32,918 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3b034654-ec83-4fe6-9327-58ad73845414, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_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:32,919 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3b034654-ec83-4fe6-9327-58ad73845414, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:33,452 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:33,452 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871488, 'BlobCount': 1152}}
2025-10-31 11:34:33,453 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:33,454 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cb847389-d926-492e-a6af-aee21265e284, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:33,455 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cb847389-d926-492e-a6af-aee21265e284, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:33,463 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cb847389-d926-492e-a6af-aee21265e284, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:35,133 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:35,134 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:35,135 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 305dfb0c-fb59-4993-80d3-8a8fc904fe4d, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:35,135 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 305dfb0c-fb59-4993-80d3-8a8fc904fe4d, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:35,141 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 305dfb0c-fb59-4993-80d3-8a8fc904fe4d, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:35,444 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:35,445 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:35,446 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aa7b4611-4606-4dbc-908c-06ab53be9753, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:35,447 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aa7b4611-4606-4dbc-908c-06ab53be9753, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_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:35,448 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aa7b4611-4606-4dbc-908c-06ab53be9753, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:36,137 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:36,138 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_8_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3190992, 'BlobCount': 1280}}
2025-10-31 11:34:36,138 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:36,139 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fb55e7de-f3d1-4653-b8c0-e43f9b86ba9d, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:36,140 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fb55e7de-f3d1-4653-b8c0-e43f9b86ba9d, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_8_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:36,141 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fb55e7de-f3d1-4653-b8c0-e43f9b86ba9d, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:37,291 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:37,292 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:37,293 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0d8dac25-6ce5-446d-8984-6b1e7842d551, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:37,294 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0d8dac25-6ce5-446d-8984-6b1e7842d551, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:37,295 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0d8dac25-6ce5-446d-8984-6b1e7842d551, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:37,665 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:37,666 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:37,667 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5a901e7f-aef7-458f-b391-53ba87491ada, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:37,668 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5a901e7f-aef7-458f-b391-53ba87491ada, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:37,669 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5a901e7f-aef7-458f-b391-53ba87491ada, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:37,898 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:37,898 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:37,899 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 602fa5f4-d22f-4b97-85a0-b015f5464f10, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:37,900 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 602fa5f4-d22f-4b97-85a0-b015f5464f10, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:37,901 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 602fa5f4-d22f-4b97-85a0-b015f5464f10, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:41,307 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:51,344 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:51,345 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 219455c4-f972-4e57-a209-964e9dd5e923, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:51,346 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 219455c4-f972-4e57-a209-964e9dd5e923, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:51,347 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 219455c4-f972-4e57-a209-964e9dd5e923, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:55,055 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:55,102 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_8_compression`, volumes `value` (8000000, 1595496)
2025-10-31 11:34:55,102 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:55,103 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d1ca79b9-92f8-4417-a8cc-2b3313a59285, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:55,104 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d1ca79b9-92f8-4417-a8cc-2b3313a59285, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" 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_8_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:55,105 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d1ca79b9-92f8-4417-a8cc-2b3313a59285, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:55,489 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:55,490 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:34:55,491 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c68429a7-4b7f-4594-8a9c-11905a8fb70b, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:34:55,492 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c68429a7-4b7f-4594-8a9c-11905a8fb70b, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:34:55,493 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c68429a7-4b7f-4594-8a9c-11905a8fb70b, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:34:58,754 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:35:08,833 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:35:08,834 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b2a8fd80-d2ae-4602-90b1-aeca2eef3950, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:35:08,835 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b2a8fd80-d2ae-4602-90b1-aeca2eef3950, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:35:08,836 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b2a8fd80-d2ae-4602-90b1-aeca2eef3950, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:35:12,867 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:35:22,897 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:35:22,898 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8b814e02-2a61-4746-8be4-fb37474ca516, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:35:22,899 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8b814e02-2a61-4746-8be4-fb37474ca516, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:35:22,900 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8b814e02-2a61-4746-8be4-fb37474ca516, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:35:23,671 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:35:23,717 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 11:35:23,718 - 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:35:23,730 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 11:35:23,731 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 3d3d40ed-3214-4407-aa01-bf86e50dbd36, localhost:63459): creating call state
2025-10-31 11:35:23,731 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 3d3d40ed-3214-4407-aa01-bf86e50dbd36, localhost:63459): request = { database: "/Root" }
2025-10-31 11:35:23,738 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 3d3d40ed-3214-4407-aa01-bf86e50dbd36, localhost:63459): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-xve6mkyrcq.auto.internal" port: 63459 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:35:23,739 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-xve6mkyrcq.auto.internal:63459, location 1, ssl: False>]>
2025-10-31 11:35:23,740 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:63459
2025-10-31 11:35:33,676 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:35:33,677 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4de0af00-ae1a-45e7-bb1a-8d74daf10e73, ghrun-xve6mkyrcq.auto.internal:63459): creating call state
2025-10-31 11:35:33,677 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4de0af00-ae1a-45e7-bb1a-8d74daf10e73, ghrun-xve6mkyrcq.auto.internal:63459): request = { session_id: "ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_8_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 11:35:33,679 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4de0af00-ae1a-45e7-bb1a-8d74daf10e73, ghrun-xve6mkyrcq.auto.internal:63459): response = { <_MultiThreadedRendezvous object> }
2025-10-31 11:35:34,546 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTRjZTNjMDEtMTRiZDdhOWEtZDQ2YzA1YjQtMTdlOGU3NWU=
2025-10-31 11:35:34,550 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_8_compression`, volumes `value` (8000000, 1465240)
2025-10-31 11:35:34,550 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_8_compression` 8092160 / 1465240: 5.522753951571073
2025-10-31 11:35:34,555 - INFO - ya.test - pytest_runtest_teardown: Test teardown
