2025-10-31 14:32:10,928 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 14:32:10,937 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_6_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 6]
2025-10-31 14:32:10,937 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-31 14:32:10,938 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-31 14:32:10,945 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-31 14:32:10,946 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-31 14:32:10,947 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/zwqj/00149e/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-31 14:32:10,980 - DEBUG - ya.test - execute: Command pid: 1055214
2025-10-31 14:32:12,680 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) rc: 0
2025-10-31 14:32:12,680 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) elapsed time (sec): 1.6981008052825928
2025-10-31 14:32:12,681 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) idrss: 0
2025-10-31 14:32:12,681 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) inblock: 0
2025-10-31 14:32:12,681 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) isrss: 0
2025-10-31 14:32:12,682 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) ixrss: 0
2025-10-31 14:32:12,682 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) majflt: 0
2025-10-31 14:32:12,682 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) maxrss: 480448
2025-10-31 14:32:12,682 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) minflt: 45345
2025-10-31 14:32:12,683 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) msgrcv: 0
2025-10-31 14:32:12,683 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) msgsnd: 0
2025-10-31 14:32:12,683 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) nivcsw: 244
2025-10-31 14:32:12,684 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) nsignals: 0
2025-10-31 14:32:12,684 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) nswap: 0
2025-10-31 14:32:12,684 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) nvcsw: 14
2025-10-31 14:32:12,685 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) oublock: 8
2025-10-31 14:32:12,686 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) stime: 0.23110399999999998
2025-10-31 14:32:12,686 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) utime: 0.554377
2025-10-31 14:32:12,687 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) wtime: 1.732
2025-10-31 14:32:12,687 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) output:
Git info:
    Commit: 81e21063d590ae7636d4475c58381f90e065d70f
    Branch: 
    Author: xyliganSereja <matva0509@gmail.com>
    Summary: Merge 4453e1bb1feb0e82a9049e95374936e29fc264d7 into d79725158e2f111172387151aa3fd9735997d399

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-uk32wjuvr4
    Host information: 
        Linux ghrun-uk32wjuvr4 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 14:32:12,687 - DEBUG - ya.test - _save_outputs: Command (pid 1055214) errors:

2025-10-31 14:32:12,688 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: 81e21063d590ae7636d4475c58381f90e065d70f
    Branch: 
    Author: xyliganSereja <matva0509@gmail.com>
    Summary: Merge 4453e1bb1feb0e82a9049e95374936e29fc264d7 into d79725158e2f111172387151aa3fd9735997d399

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-uk32wjuvr4
    Host information: 
        Linux ghrun-uk32wjuvr4 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 14:32:12,885 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/zwqj/00149e/ydb/apps/ydbd/ydbd
2025-10-31 14:32:12,887 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13236
2025-10-31 14:32:12,890 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/zwqj/00149e/r3tmp/kikimr_cluster_rnqwmz4s
2025-10-31 14:32:12,941 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/zwqj/00149e/ydb/apps/ydbd/ydbd
2025-10-31 14:32:12,942 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10376
2025-10-31 14:32:12,943 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8834
2025-10-31 14:32:12,943 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26934
2025-10-31 14:32:12,944 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16136
2025-10-31 14:32:12,948 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/zwqj/00149e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/kikimr_configs"
2025-10-31 14:32:12,948 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/zwqj/00149e/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/zwqj/00149e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/node_1/logfile_0y5q4icx.log --grpc-port=10376 --mon-port=8834 --ic-port=13236
2025-10-31 14:32:12,949 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:10376/1, disk_size 68719476736
2025-10-31 14:32:12,949 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/zwqj/00149e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/kikimr_configs"
2025-10-31 14:32:12,950 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/zwqj/00149e/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/zwqj/00149e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/node_1/logfile_0y5q4icx.log --grpc-port=10376 --mon-port=8834 --ic-port=13236
2025-10-31 14:32:12,951 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/zwqj/00149e/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/zwqj/00149e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/zwqj/00149e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/node_1/logfile_0y5q4icx.log', '--grpc-port=10376', '--mon-port=8834', '--ic-port=13236')' in '/home/runner/.ya/build/build_root/zwqj/00149e/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk7/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_6_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.6/cluster/node_1' (no wait)
2025-10-31 14:32:12,969 - DEBUG - ya.test - execute: Command pid: 1055341
2025-10-31 14:32:12,970 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:10376/1
2025-10-31 14:32:12,972 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-31 14:32:12,976 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-31 14:32:12,976 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/zwqj/00149e/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-31 14:32:12,993 - DEBUG - ya.test - execute: Command pid: 1055342
2025-10-31 14:32:14,681 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) rc: 0
2025-10-31 14:32:14,682 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) elapsed time (sec): 1.6869733333587646
2025-10-31 14:32:14,682 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) idrss: 0
2025-10-31 14:32:14,682 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) inblock: 0
2025-10-31 14:32:14,683 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) isrss: 0
2025-10-31 14:32:14,683 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) ixrss: 0
2025-10-31 14:32:14,683 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) majflt: 0
2025-10-31 14:32:14,683 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) maxrss: 480532
2025-10-31 14:32:14,684 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) minflt: 45311
2025-10-31 14:32:14,684 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) msgrcv: 0
2025-10-31 14:32:14,685 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) msgsnd: 0
2025-10-31 14:32:14,686 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) nivcsw: 208
2025-10-31 14:32:14,686 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) nsignals: 0
2025-10-31 14:32:14,686 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) nswap: 0
2025-10-31 14:32:14,687 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) nvcsw: 15
2025-10-31 14:32:14,687 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) oublock: 8
2025-10-31 14:32:14,688 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) stime: 0.22969299999999998
2025-10-31 14:32:14,688 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) utime: 0.562186
2025-10-31 14:32:14,697 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) wtime: 1.7
2025-10-31 14:32:14,698 - DEBUG - ya.test - _save_outputs: Command (pid 1055342) output:
Git info:
    Commit: 81e21063d590ae7636d4475c58381f90e065d70f
    Branch: 
    Author: xyliganSereja <matva0509@gmail.com>
    Summary: Merge 4453e1bb1feb0e82a9049e95374936e29fc264d7 into d79725158e2f111172387151aa3fd9735997d399

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

2025-10-31 14:32:14,699 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 81e21063d590ae7636d4475c58381f90e065d70f
    Branch: 
    Author: xyliganSereja <matva0509@gmail.com>
    Summary: Merge 4453e1bb1feb0e82a9049e95374936e29fc264d7 into d79725158e2f111172387151aa3fd9735997d399
2025-10-31 14:32:20,918 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/zwqj/00149e/ydb/apps/ydbd/ydbd
2025-10-31 14:32:20,918 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/zwqj/00149e/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10376', '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: 13236\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-31 14:32:20,920 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-31 14:32:20,922 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-31 14:32:20,922 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/zwqj/00149e/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10376', '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: 13236\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-31 14:32:20,950 - DEBUG - ya.test - execute: Command pid: 1056540
2025-10-31 14:32:22,814 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) rc: 0
2025-10-31 14:32:22,815 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) elapsed time (sec): 1.862743854522705
2025-10-31 14:32:22,815 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) idrss: 0
2025-10-31 14:32:22,815 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) inblock: 0
2025-10-31 14:32:22,816 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) isrss: 0
2025-10-31 14:32:22,816 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) ixrss: 0
2025-10-31 14:32:22,816 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) majflt: 0
2025-10-31 14:32:22,817 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) maxrss: 595080
2025-10-31 14:32:22,817 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) minflt: 51607
2025-10-31 14:32:22,817 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) msgrcv: 0
2025-10-31 14:32:22,818 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) msgsnd: 0
2025-10-31 14:32:22,820 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) nivcsw: 260
2025-10-31 14:32:22,834 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) nsignals: 0
2025-10-31 14:32:22,835 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) nswap: 0
2025-10-31 14:32:22,835 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) nvcsw: 468
2025-10-31 14:32:22,836 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) oublock: 8
2025-10-31 14:32:22,836 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) stime: 0.26012199999999996
2025-10-31 14:32:22,836 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) utime: 0.67299
2025-10-31 14:32:22,843 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) wtime: 1.891
2025-10-31 14:32:22,844 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-31 14:32:22,844 - DEBUG - ya.test - _save_outputs: Command (pid 1056540) errors:

2025-10-31 14:32:22,846 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/zwqj/00149e/ydb/apps/ydbd/ydbd
2025-10-31 14:32:22,847 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/zwqj/00149e/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10376', '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 14:32:22,848 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-31 14:32:22,850 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-31 14:32:22,851 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/zwqj/00149e/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10376', '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 14:32:22,865 - DEBUG - ya.test - execute: Command pid: 1056693
2025-10-31 14:32:24,588 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) rc: 0
2025-10-31 14:32:24,589 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) elapsed time (sec): 1.7218785285949707
2025-10-31 14:32:24,589 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) idrss: 0
2025-10-31 14:32:24,589 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) inblock: 0
2025-10-31 14:32:24,590 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) isrss: 0
2025-10-31 14:32:24,590 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) ixrss: 0
2025-10-31 14:32:24,590 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) majflt: 0
2025-10-31 14:32:24,590 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) maxrss: 595336
2025-10-31 14:32:24,591 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) minflt: 51590
2025-10-31 14:32:24,591 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) msgrcv: 0
2025-10-31 14:32:24,591 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) msgsnd: 0
2025-10-31 14:32:24,591 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) nivcsw: 287
2025-10-31 14:32:24,592 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) nsignals: 0
2025-10-31 14:32:24,592 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) nswap: 0
2025-10-31 14:32:24,592 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) nvcsw: 405
2025-10-31 14:32:24,593 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) oublock: 8
2025-10-31 14:32:24,593 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) stime: 0.27260999999999996
2025-10-31 14:32:24,593 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) utime: 0.635361
2025-10-31 14:32:24,594 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) wtime: 1.736
2025-10-31 14:32:24,594 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-31 14:32:24,594 - DEBUG - ya.test - _save_outputs: Command (pid 1056693) errors:

2025-10-31 14:32:24,595 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-31 14:32:24,781 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-31 14:32:24,849 - 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 14:32:24,855 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 14:32:24,856 - 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 14:32:24,925 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 14:32:24,926 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 60a1c3cb-f54d-44ea-a634-800265123a1d, localhost:10376): creating call state
2025-10-31 14:32:24,927 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 60a1c3cb-f54d-44ea-a634-800265123a1d, localhost:10376): request = { database: "/Root" }
2025-10-31 14:32:25,068 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 60a1c3cb-f54d-44ea-a634-800265123a1d, localhost:10376): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-uk32wjuvr4.auto.internal" port: 10376 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 14:32:25,069 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-uk32wjuvr4.auto.internal:10376, location 1, ssl: False>]>
2025-10-31 14:32:25,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 14:32:25,121 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10376
2025-10-31 14:32:25,122 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-10-31 14:32:25,124 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 99c1636b-9ab8-41f8-b9eb-b3fa9fe0cb5e, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:25,124 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 99c1636b-9ab8-41f8-b9eb-b3fa9fe0cb5e, ghrun-uk32wjuvr4.auto.internal:10376): request = {  }
2025-10-31 14:32:25,142 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 99c1636b-9ab8-41f8-b9eb-b3fa9fe0cb5e, ghrun-uk32wjuvr4.auto.internal:10376): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" node_id: 1 }
2025-10-31 14:32:25,143 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, c50118fe-a8cb-4182-b20a-56a17f7cb950, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:25,144 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, c50118fe-a8cb-4182-b20a-56a17f7cb950, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" }
2025-10-31 14:32:25,167 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, c50118fe-a8cb-4182-b20a-56a17f7cb950, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:25,189 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:25,191 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eeb7d925-44b5-4911-adc7-e8bf2ad65d8f, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:25,192 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eeb7d925-44b5-4911-adc7-e8bf2ad65d8f, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:25,203 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eeb7d925-44b5-4911-adc7-e8bf2ad65d8f, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:29,325 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:29,325 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-10-31 14:32:29,326 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:29,327 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d97ec20d-afaf-481a-b88b-a5d4d12b5a2d, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:29,328 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d97ec20d-afaf-481a-b88b-a5d4d12b5a2d, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:29,331 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d97ec20d-afaf-481a-b88b-a5d4d12b5a2d, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:30,941 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:30,942 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:30,943 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b8f49115-2f88-4b82-974a-2d4784358e23, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:30,944 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b8f49115-2f88-4b82-974a-2d4784358e23, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:30,946 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b8f49115-2f88-4b82-974a-2d4784358e23, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:32,578 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:32,578 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:32,579 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7b002b21-0bba-49b4-a09a-ca13e07d911e, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:32,580 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7b002b21-0bba-49b4-a09a-ca13e07d911e, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:32,589 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7b002b21-0bba-49b4-a09a-ca13e07d911e, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:33,714 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:33,715 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:33,715 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4c6ec80c-8f4c-4429-9de2-76e1f9dd01d9, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:33,716 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4c6ec80c-8f4c-4429-9de2-76e1f9dd01d9, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:33,719 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4c6ec80c-8f4c-4429-9de2-76e1f9dd01d9, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:36,261 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:36,262 - 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 14:32:36,263 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:36,264 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d5b01a88-2635-4b7b-a03f-f8b6a456942d, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:36,265 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d5b01a88-2635-4b7b-a03f-f8b6a456942d, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:36,267 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d5b01a88-2635-4b7b-a03f-f8b6a456942d, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:37,425 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:37,426 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:37,426 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7eee1a49-c4c6-4d13-8fa7-c27bdc9ce718, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:37,427 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7eee1a49-c4c6-4d13-8fa7-c27bdc9ce718, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:37,433 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7eee1a49-c4c6-4d13-8fa7-c27bdc9ce718, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:37,748 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:37,749 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:37,749 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 66fc6cf1-9752-4b77-befa-595b5dfa23ef, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:37,750 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 66fc6cf1-9752-4b77-befa-595b5dfa23ef, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:37,752 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 66fc6cf1-9752-4b77-befa-595b5dfa23ef, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:38,226 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:38,226 - 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 14:32:38,227 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:38,228 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4c11d921-e90d-4e65-bc82-a95362fc07f2, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:38,229 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4c11d921-e90d-4e65-bc82-a95362fc07f2, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:38,230 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4c11d921-e90d-4e65-bc82-a95362fc07f2, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:39,601 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:39,602 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:39,602 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5cc92e06-6715-46dd-aae8-d4c0465d3e56, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:39,603 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5cc92e06-6715-46dd-aae8-d4c0465d3e56, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:39,605 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5cc92e06-6715-46dd-aae8-d4c0465d3e56, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:40,051 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:40,052 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:40,053 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e97afb8-be52-497f-9ed7-971e062d27fe, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:40,054 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e97afb8-be52-497f-9ed7-971e062d27fe, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:40,055 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e97afb8-be52-497f-9ed7-971e062d27fe, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:40,559 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:40,560 - 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 14:32:40,561 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:40,561 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e90621f7-21bf-467d-8186-049b232b4aa7, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:40,562 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e90621f7-21bf-467d-8186-049b232b4aa7, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:40,569 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e90621f7-21bf-467d-8186-049b232b4aa7, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:42,079 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:42,079 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:42,080 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b013f1bc-79f4-4cfc-afd6-e6835e0d6370, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:42,081 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b013f1bc-79f4-4cfc-afd6-e6835e0d6370, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:42,083 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b013f1bc-79f4-4cfc-afd6-e6835e0d6370, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:42,423 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:42,424 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:42,425 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0b44a2ee-366c-4f60-8c57-709d0493228d, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:42,426 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0b44a2ee-366c-4f60-8c57-709d0493228d, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:42,431 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0b44a2ee-366c-4f60-8c57-709d0493228d, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:43,379 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:43,380 - 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 14:32:43,380 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:43,381 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3e635f2b-80c1-44f3-a8d6-3136693b7df5, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:43,382 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3e635f2b-80c1-44f3-a8d6-3136693b7df5, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:43,391 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3e635f2b-80c1-44f3-a8d6-3136693b7df5, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:44,760 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:44,761 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:44,762 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3997b48f-4bf6-4205-bdf2-a3f6ca227a80, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:44,763 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3997b48f-4bf6-4205-bdf2-a3f6ca227a80, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:44,765 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3997b48f-4bf6-4205-bdf2-a3f6ca227a80, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:45,313 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:45,314 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:45,314 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9de0ed4a-c949-4584-973a-f3565311884d, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:45,316 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9de0ed4a-c949-4584-973a-f3565311884d, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:45,317 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9de0ed4a-c949-4584-973a-f3565311884d, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:45,871 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:45,871 - 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 14:32:45,872 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:45,873 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4e1a53a4-5be1-47c4-ba0d-934ab96235de, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:45,874 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4e1a53a4-5be1-47c4-ba0d-934ab96235de, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:45,876 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4e1a53a4-5be1-47c4-ba0d-934ab96235de, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:47,481 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:47,482 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:47,483 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 19190d90-afb6-41b9-b173-4379ab19aa23, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:47,484 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 19190d90-afb6-41b9-b173-4379ab19aa23, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:47,498 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 19190d90-afb6-41b9-b173-4379ab19aa23, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:47,995 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:47,996 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:47,996 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e0d350a3-2f41-426e-8577-309f2bf9363d, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:47,997 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e0d350a3-2f41-426e-8577-309f2bf9363d, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:48,014 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e0d350a3-2f41-426e-8577-309f2bf9363d, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:49,118 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:49,119 - 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 14:32:49,120 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:49,120 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a40fa54f-fb9c-4fb0-b331-03a30ae4db1c, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:49,121 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a40fa54f-fb9c-4fb0-b331-03a30ae4db1c, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:49,123 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a40fa54f-fb9c-4fb0-b331-03a30ae4db1c, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:50,905 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:50,906 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:50,907 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6112fcf6-7a5e-4c31-b0ce-426707882bef, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:50,908 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6112fcf6-7a5e-4c31-b0ce-426707882bef, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:50,917 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6112fcf6-7a5e-4c31-b0ce-426707882bef, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:51,830 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:51,831 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:51,832 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 39acd0dd-a3f7-44d0-bf78-320bf6e9f90c, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:51,833 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 39acd0dd-a3f7-44d0-bf78-320bf6e9f90c, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:51,834 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 39acd0dd-a3f7-44d0-bf78-320bf6e9f90c, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:53,137 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:53,138 - 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 14:32:53,138 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:53,139 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bec59fc8-6cff-413d-aa1c-102b1932725c, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:53,140 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bec59fc8-6cff-413d-aa1c-102b1932725c, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:53,150 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bec59fc8-6cff-413d-aa1c-102b1932725c, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:54,714 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:54,715 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:54,716 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0e109226-7a54-404e-af0d-f00217ef9460, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:54,717 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0e109226-7a54-404e-af0d-f00217ef9460, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:54,720 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0e109226-7a54-404e-af0d-f00217ef9460, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:55,426 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:55,427 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:55,428 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2d82394c-31e5-4ac4-b890-8b2b54c009f5, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:55,429 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2d82394c-31e5-4ac4-b890-8b2b54c009f5, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:55,446 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2d82394c-31e5-4ac4-b890-8b2b54c009f5, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:56,850 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:56,851 - 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 14:32:56,852 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:56,852 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4a5c7174-b980-467a-98a7-d7ed0166b71e, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:56,853 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4a5c7174-b980-467a-98a7-d7ed0166b71e, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:56,855 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4a5c7174-b980-467a-98a7-d7ed0166b71e, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:58,853 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:58,854 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:58,855 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0483c09d-b114-4061-84f6-2b5e7edc674c, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:58,856 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0483c09d-b114-4061-84f6-2b5e7edc674c, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:58,857 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0483c09d-b114-4061-84f6-2b5e7edc674c, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:32:59,523 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:59,523 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:32:59,524 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9a8202b3-87d4-4498-a184-c8ffd10966db, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:32:59,525 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9a8202b3-87d4-4498-a184-c8ffd10966db, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:32:59,528 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9a8202b3-87d4-4498-a184-c8ffd10966db, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:00,643 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:00,644 - 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 14:33:00,644 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:00,645 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e58ed35c-3c3f-48df-8072-f86834d98db4, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:33:00,646 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e58ed35c-3c3f-48df-8072-f86834d98db4, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:33:00,648 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e58ed35c-3c3f-48df-8072-f86834d98db4, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:02,491 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:02,492 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:02,492 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2e3d1c66-7aae-49b7-bc69-81377d37c1c2, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:33:02,494 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2e3d1c66-7aae-49b7-bc69-81377d37c1c2, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:33:02,496 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2e3d1c66-7aae-49b7-bc69-81377d37c1c2, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:02,937 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:02,938 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:02,939 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 11a49c29-21e2-4dc7-ac15-ead8c90ff751, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:33:02,940 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 11a49c29-21e2-4dc7-ac15-ead8c90ff751, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:33:02,952 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 11a49c29-21e2-4dc7-ac15-ead8c90ff751, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:04,021 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:04,022 - 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 14:33:04,023 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:04,024 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7be7d68d-2152-4a5a-87e5-98650dbb6aa3, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:33:04,030 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7be7d68d-2152-4a5a-87e5-98650dbb6aa3, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:33:04,031 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7be7d68d-2152-4a5a-87e5-98650dbb6aa3, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:07,292 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:07,307 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:07,308 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6d44c7cd-d576-4089-8991-5101705164b0, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:33:07,309 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6d44c7cd-d576-4089-8991-5101705164b0, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:33:07,311 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6d44c7cd-d576-4089-8991-5101705164b0, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:08,199 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:08,200 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:08,201 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5afaf351-6dd3-457e-a706-e7d23e6eb9ca, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:33:08,202 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5afaf351-6dd3-457e-a706-e7d23e6eb9ca, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:33:08,213 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5afaf351-6dd3-457e-a706-e7d23e6eb9ca, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:09,227 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:09,227 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:09,228 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a0c77041-b2c9-48b9-8648-aef2a5ea0055, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:33:09,229 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a0c77041-b2c9-48b9-8648-aef2a5ea0055, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:33:09,232 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a0c77041-b2c9-48b9-8648-aef2a5ea0055, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:16,194 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:26,344 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:26,345 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 79fc7a86-6a3a-4746-a721-4a177d0d9470, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:33:26,346 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 79fc7a86-6a3a-4746-a721-4a177d0d9470, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:33:26,348 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 79fc7a86-6a3a-4746-a721-4a177d0d9470, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:32,867 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:42,934 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:42,935 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 155278ea-c6be-4c92-a3f3-8e132915f04c, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:33:42,936 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 155278ea-c6be-4c92-a3f3-8e132915f04c, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:33:42,942 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 155278ea-c6be-4c92-a3f3-8e132915f04c, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:44,874 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:44,977 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 14:33:44,977 - 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 14:33:45,001 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 14:33:45,002 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 88963330-0467-44e0-84e0-b927c837a66e, localhost:10376): creating call state
2025-10-31 14:33:45,003 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 88963330-0467-44e0-84e0-b927c837a66e, localhost:10376): request = { database: "/Root" }
2025-10-31 14:33:45,023 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 88963330-0467-44e0-84e0-b927c837a66e, localhost:10376): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-uk32wjuvr4.auto.internal" port: 10376 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 14:33:45,024 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-uk32wjuvr4.auto.internal:10376, location 1, ssl: False>]>
2025-10-31 14:33:45,025 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10376
2025-10-31 14:33:54,881 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:54,882 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8705fe12-e385-4091-8071-b56fc0ef4f84, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:33:54,882 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8705fe12-e385-4091-8071-b56fc0ef4f84, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:33:54,884 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8705fe12-e385-4091-8071-b56fc0ef4f84, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:56,034 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:56,038 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-10-31 14:33:56,039 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:33:56,040 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1cc271d0-9914-4feb-b516-7f2cee4c1119, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:33:56,042 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1cc271d0-9914-4feb-b516-7f2cee4c1119, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:33:56,044 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1cc271d0-9914-4feb-b516-7f2cee4c1119, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:33:56,925 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:06,935 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:06,936 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d73727a0-395f-4011-89cd-5d5d0fb7ac4b, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:06,937 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d73727a0-395f-4011-89cd-5d5d0fb7ac4b, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:34:06,938 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d73727a0-395f-4011-89cd-5d5d0fb7ac4b, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:08,417 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:08,421 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-10-31 14:34:08,422 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:08,423 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4666b12d-0b26-4a36-9a41-55dce70e7646, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:08,424 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4666b12d-0b26-4a36-9a41-55dce70e7646, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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 14:34:08,445 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4666b12d-0b26-4a36-9a41-55dce70e7646, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:08,881 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:08,892 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_6_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 6])
2025-10-31 14:34:08,899 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:08,900 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 146a471a-ae71-4e47-90a1-5541a320bea4, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:08,901 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 146a471a-ae71-4e47-90a1-5541a320bea4, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_6_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 6)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:08,902 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 146a471a-ae71-4e47-90a1-5541a320bea4, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:14,250 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:14,250 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_6_compression created
2025-10-31 14:34:14,251 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:14,252 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd2c3b35-a9b3-4467-9798-bf0c58263fb3, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:14,253 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd2c3b35-a9b3-4467-9798-bf0c58263fb3, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_6_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:14,255 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd2c3b35-a9b3-4467-9798-bf0c58263fb3, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:15,641 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:15,642 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:15,642 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0bbcbf94-b803-47ce-b81c-9a1ac1729c98, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:15,643 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0bbcbf94-b803-47ce-b81c-9a1ac1729c98, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:15,645 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0bbcbf94-b803-47ce-b81c-9a1ac1729c98, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:17,719 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:17,720 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:17,721 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 11d211df-cd05-4cf5-90dc-9c09778ca82c, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:17,722 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 11d211df-cd05-4cf5-90dc-9c09778ca82c, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:17,725 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 11d211df-cd05-4cf5-90dc-9c09778ca82c, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:19,369 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:19,370 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:19,371 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b6140ba0-ebdc-4022-b8e8-2a445057d3db, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:19,372 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b6140ba0-ebdc-4022-b8e8-2a445057d3db, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:19,374 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b6140ba0-ebdc-4022-b8e8-2a445057d3db, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:22,246 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:22,247 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318016, 'BlobCount': 128}}
2025-10-31 14:34:22,247 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:22,248 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 11cf93c3-79f9-46a3-8cf7-574665cb8529, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:22,249 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 11cf93c3-79f9-46a3-8cf7-574665cb8529, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:22,251 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 11cf93c3-79f9-46a3-8cf7-574665cb8529, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:24,248 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:24,249 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:24,249 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 58a87a93-970f-497a-8db3-acff7c6b5478, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:24,250 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 58a87a93-970f-497a-8db3-acff7c6b5478, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:24,252 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 58a87a93-970f-497a-8db3-acff7c6b5478, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:24,726 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:24,727 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:24,728 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1e7cb003-a325-4f3f-a74e-3f17a4a3fd84, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:24,733 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1e7cb003-a325-4f3f-a74e-3f17a4a3fd84, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:24,735 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1e7cb003-a325-4f3f-a74e-3f17a4a3fd84, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:25,259 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:25,259 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637312, 'BlobCount': 256}}
2025-10-31 14:34:25,260 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:25,261 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 02c7f538-6fe2-40f9-a401-022d79f8a2b5, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:25,262 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 02c7f538-6fe2-40f9-a401-022d79f8a2b5, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:25,271 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 02c7f538-6fe2-40f9-a401-022d79f8a2b5, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:27,184 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:27,185 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:27,186 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 789b718c-bdae-4a55-9752-c6057ceb8d55, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:27,187 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 789b718c-bdae-4a55-9752-c6057ceb8d55, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:27,191 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 789b718c-bdae-4a55-9752-c6057ceb8d55, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:27,954 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:27,955 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:27,955 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 50b5820b-b155-4ee9-b1f9-3f6321bb8c92, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:27,964 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 50b5820b-b155-4ee9-b1f9-3f6321bb8c92, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:27,966 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 50b5820b-b155-4ee9-b1f9-3f6321bb8c92, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:29,130 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:29,131 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956352, 'BlobCount': 384}}
2025-10-31 14:34:29,131 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:29,132 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4d72a2d1-a9e4-462f-85ba-da2a1119976a, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:29,133 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4d72a2d1-a9e4-462f-85ba-da2a1119976a, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:29,136 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4d72a2d1-a9e4-462f-85ba-da2a1119976a, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:30,949 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:30,950 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:30,951 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2ea5fd27-bdfd-4a29-a3ed-2957a4088445, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:30,952 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2ea5fd27-bdfd-4a29-a3ed-2957a4088445, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:30,955 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2ea5fd27-bdfd-4a29-a3ed-2957a4088445, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:31,378 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:31,379 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:31,379 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d3c6bbca-57aa-4328-92bb-353effe2e73d, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:31,385 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d3c6bbca-57aa-4328-92bb-353effe2e73d, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:31,387 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d3c6bbca-57aa-4328-92bb-353effe2e73d, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:32,480 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:32,481 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1275712, 'BlobCount': 512}}
2025-10-31 14:34:32,482 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:32,483 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 086ad35d-9597-4575-a92c-fee92eaede64, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:32,484 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 086ad35d-9597-4575-a92c-fee92eaede64, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:32,498 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 086ad35d-9597-4575-a92c-fee92eaede64, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:34,644 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:34,645 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:34,646 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 573b47a1-ba27-4808-92c2-23bb1e6fd506, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:34,647 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 573b47a1-ba27-4808-92c2-23bb1e6fd506, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:34,648 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 573b47a1-ba27-4808-92c2-23bb1e6fd506, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:35,138 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:35,139 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:35,139 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 529cb167-1201-40b3-8477-c3525c4db3f3, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:35,147 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 529cb167-1201-40b3-8477-c3525c4db3f3, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:35,149 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 529cb167-1201-40b3-8477-c3525c4db3f3, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:36,110 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:36,111 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1594672, 'BlobCount': 640}}
2025-10-31 14:34:36,111 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:36,112 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4e9ffa4e-3557-4bf8-8ab5-a10997e9f697, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:36,113 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4e9ffa4e-3557-4bf8-8ab5-a10997e9f697, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:36,116 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4e9ffa4e-3557-4bf8-8ab5-a10997e9f697, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:37,714 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:37,715 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:37,716 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5e4943f4-d022-4fd9-aa11-80c45a35de0f, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:37,717 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5e4943f4-d022-4fd9-aa11-80c45a35de0f, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:37,719 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5e4943f4-d022-4fd9-aa11-80c45a35de0f, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:38,255 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:38,256 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:38,257 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b028e228-de29-4691-ab0b-f2defb7f4c84, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:38,258 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b028e228-de29-4691-ab0b-f2defb7f4c84, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:38,260 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b028e228-de29-4691-ab0b-f2defb7f4c84, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:38,873 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:38,874 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1913856, 'BlobCount': 768}}
2025-10-31 14:34:38,874 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:38,875 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6588d84e-16fb-4b9f-abb8-022dbb2efb62, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:38,876 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6588d84e-16fb-4b9f-abb8-022dbb2efb62, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:38,885 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6588d84e-16fb-4b9f-abb8-022dbb2efb62, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:40,896 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:40,896 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:40,897 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f18dee33-ac54-43b2-87d2-3399664c2e19, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:40,898 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f18dee33-ac54-43b2-87d2-3399664c2e19, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:40,902 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f18dee33-ac54-43b2-87d2-3399664c2e19, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:41,618 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:41,619 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:41,620 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9eb4dcca-adef-4367-a2f1-888643be848f, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:41,621 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9eb4dcca-adef-4367-a2f1-888643be848f, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:41,623 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9eb4dcca-adef-4367-a2f1-888643be848f, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:42,694 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:42,695 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2232992, 'BlobCount': 896}}
2025-10-31 14:34:42,696 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:42,696 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c1f2c2d2-7589-4864-acc5-d8ff0ee6a013, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:42,697 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c1f2c2d2-7589-4864-acc5-d8ff0ee6a013, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:42,699 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c1f2c2d2-7589-4864-acc5-d8ff0ee6a013, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:44,550 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:44,551 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:44,552 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a762d4ae-a4dc-4623-a8d0-2980d429ee03, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:44,553 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a762d4ae-a4dc-4623-a8d0-2980d429ee03, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:44,555 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a762d4ae-a4dc-4623-a8d0-2980d429ee03, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:44,946 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:44,947 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:44,948 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, abe9a908-0ec8-441f-89f6-a289d33a81d1, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:44,949 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, abe9a908-0ec8-441f-89f6-a289d33a81d1, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:44,969 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, abe9a908-0ec8-441f-89f6-a289d33a81d1, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:45,574 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:45,575 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552480, 'BlobCount': 1024}}
2025-10-31 14:34:45,576 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:45,576 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0f439758-e794-4bb4-a518-47fc4e3f2473, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:45,578 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0f439758-e794-4bb4-a518-47fc4e3f2473, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:45,579 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0f439758-e794-4bb4-a518-47fc4e3f2473, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:47,210 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:47,211 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:47,212 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 555e3f6c-1eb6-4f3a-ab96-939d45fe5f65, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:47,213 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 555e3f6c-1eb6-4f3a-ab96-939d45fe5f65, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:47,214 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 555e3f6c-1eb6-4f3a-ab96-939d45fe5f65, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:47,564 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:47,565 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:47,565 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8d381d17-7104-4b94-9358-939631739b21, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:47,566 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8d381d17-7104-4b94-9358-939631739b21, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:47,576 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8d381d17-7104-4b94-9358-939631739b21, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:48,364 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:48,364 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871488, 'BlobCount': 1152}}
2025-10-31 14:34:48,365 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:48,366 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cffebfa2-5dba-4bc4-af40-cc9722d2882e, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:48,367 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cffebfa2-5dba-4bc4-af40-cc9722d2882e, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:48,368 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cffebfa2-5dba-4bc4-af40-cc9722d2882e, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:50,181 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:50,182 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:50,183 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6ee9c5aa-68af-4058-8018-6212a02864e8, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:50,184 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6ee9c5aa-68af-4058-8018-6212a02864e8, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:50,191 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6ee9c5aa-68af-4058-8018-6212a02864e8, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:50,569 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:50,570 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:50,571 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 56dd921a-5811-438f-9248-4a6ec9e2192f, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:50,572 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 56dd921a-5811-438f-9248-4a6ec9e2192f, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:50,574 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 56dd921a-5811-438f-9248-4a6ec9e2192f, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:51,646 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:51,647 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_6_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3190992, 'BlobCount': 1280}}
2025-10-31 14:34:51,648 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:51,648 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9d202e79-736a-4627-b490-5e5bcc66447b, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:51,649 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9d202e79-736a-4627-b490-5e5bcc66447b, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_6_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:51,665 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9d202e79-736a-4627-b490-5e5bcc66447b, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:53,788 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:53,789 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:53,790 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bbcfb485-e8e2-49c6-984e-a766d2c0c6f5, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:53,791 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bbcfb485-e8e2-49c6-984e-a766d2c0c6f5, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:53,792 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bbcfb485-e8e2-49c6-984e-a766d2c0c6f5, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:54,436 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:54,437 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:54,438 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ea6688c0-e74c-4149-aa95-6d4afed393ad, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:54,439 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ea6688c0-e74c-4149-aa95-6d4afed393ad, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:54,440 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ea6688c0-e74c-4149-aa95-6d4afed393ad, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:55,039 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:55,040 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:34:55,041 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c30a0b76-dea0-4ee8-afc6-6af72338036e, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:34:55,042 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c30a0b76-dea0-4ee8-afc6-6af72338036e, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:34:55,055 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c30a0b76-dea0-4ee8-afc6-6af72338036e, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:34:58,761 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-31 14:34:58,761 - 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 14:34:58,785 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-31 14:34:58,785 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 62be4038-b0a6-45a2-a401-7fe362f52d32, localhost:10376): creating call state
2025-10-31 14:34:58,786 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 62be4038-b0a6-45a2-a401-7fe362f52d32, localhost:10376): request = { database: "/Root" }
2025-10-31 14:34:58,840 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 62be4038-b0a6-45a2-a401-7fe362f52d32, localhost:10376): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-uk32wjuvr4.auto.internal" port: 10376 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 14:34:58,841 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-uk32wjuvr4.auto.internal:10376, location 1, ssl: False>]>
2025-10-31 14:34:58,842 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10376
2025-10-31 14:35:00,707 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:35:10,757 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:35:10,758 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b2319f6e-d312-46f0-8b85-d0f29183cea6, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:35:10,759 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b2319f6e-d312-46f0-8b85-d0f29183cea6, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_6_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:35:10,761 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b2319f6e-d312-46f0-8b85-d0f29183cea6, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:35:16,758 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:35:16,795 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_6_compression`, volumes `value` (8000000, 1591448)
2025-10-31 14:35:16,795 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:35:16,796 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 276d67b1-57d4-4a39-8938-51a99d759a4a, ghrun-uk32wjuvr4.auto.internal:10376): creating call state
2025-10-31 14:35:16,797 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 276d67b1-57d4-4a39-8938-51a99d759a4a, ghrun-uk32wjuvr4.auto.internal:10376): request = { session_id: "ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=" 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_6_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-31 14:35:16,799 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 276d67b1-57d4-4a39-8938-51a99d759a4a, ghrun-uk32wjuvr4.auto.internal:10376): response = { <_MultiThreadedRendezvous object> }
2025-10-31 14:35:17,357 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2E0NjI0ZDYtM2ZjNzNjYWItZGNlOGVhNTctNDEwY2YyMzQ=
2025-10-31 14:35:18,452 - ERROR - ya.test - logreport: ydb/tests/olap/column_family/compression/alter_compression.py:194: in test_all_supported_compression
    assert table.get_portion_stat_by_tier()['__DEFAULT']['Rows'] == expected_raw // 8
E   assert 1031098 == (8000000 // 8)
2025-10-31 14:35:18,454 - INFO - ya.test - pytest_runtest_teardown: Test teardown
