2025-11-01 16:15:18,561 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:15:18,561 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_6_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 6]
2025-11-01 16:15:18,562 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:15:18,562 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 16:15:18,565 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-01 16:15:18,566 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-01 16:15:18,566 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ewjv/002566/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:15:18,571 - DEBUG - ya.test - execute: Command pid: 286462
2025-11-01 16:15:19,590 - DEBUG - ya.test - _save_outputs: Command (pid 286462) rc: 0
2025-11-01 16:15:19,590 - DEBUG - ya.test - _save_outputs: Command (pid 286462) elapsed time (sec): 1.0132472515106201
2025-11-01 16:15:19,591 - DEBUG - ya.test - _save_outputs: Command (pid 286462) idrss: 0
2025-11-01 16:15:19,591 - DEBUG - ya.test - _save_outputs: Command (pid 286462) inblock: 0
2025-11-01 16:15:19,591 - DEBUG - ya.test - _save_outputs: Command (pid 286462) isrss: 0
2025-11-01 16:15:19,592 - DEBUG - ya.test - _save_outputs: Command (pid 286462) ixrss: 0
2025-11-01 16:15:19,592 - DEBUG - ya.test - _save_outputs: Command (pid 286462) majflt: 0
2025-11-01 16:15:19,592 - DEBUG - ya.test - _save_outputs: Command (pid 286462) maxrss: 486768
2025-11-01 16:15:19,592 - DEBUG - ya.test - _save_outputs: Command (pid 286462) minflt: 45392
2025-11-01 16:15:19,593 - DEBUG - ya.test - _save_outputs: Command (pid 286462) msgrcv: 0
2025-11-01 16:15:19,593 - DEBUG - ya.test - _save_outputs: Command (pid 286462) msgsnd: 0
2025-11-01 16:15:19,593 - DEBUG - ya.test - _save_outputs: Command (pid 286462) nivcsw: 161
2025-11-01 16:15:19,594 - DEBUG - ya.test - _save_outputs: Command (pid 286462) nsignals: 0
2025-11-01 16:15:19,594 - DEBUG - ya.test - _save_outputs: Command (pid 286462) nswap: 0
2025-11-01 16:15:19,594 - DEBUG - ya.test - _save_outputs: Command (pid 286462) nvcsw: 14
2025-11-01 16:15:19,594 - DEBUG - ya.test - _save_outputs: Command (pid 286462) oublock: 8
2025-11-01 16:15:19,595 - DEBUG - ya.test - _save_outputs: Command (pid 286462) stime: 0.205674
2025-11-01 16:15:19,596 - DEBUG - ya.test - _save_outputs: Command (pid 286462) utime: 0.49940399999999996
2025-11-01 16:15:19,596 - DEBUG - ya.test - _save_outputs: Command (pid 286462) wtime: 1.019
2025-11-01 16:15:19,597 - DEBUG - ya.test - _save_outputs: Command (pid 286462) output:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jzwpcuvpwq
    Host information: 
        Linux ghrun-jzwpcuvpwq 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-11-01 16:15:19,597 - DEBUG - ya.test - _save_outputs: Command (pid 286462) errors:

2025-11-01 16:15:19,598 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jzwpcuvpwq
    Host information: 
        Linux ghrun-jzwpcuvpwq 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-11-01 16:15:19,828 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ewjv/002566/ydb/apps/ydbd/ydbd
2025-11-01 16:15:19,830 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29589
2025-11-01 16:15:19,837 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/ewjv/002566/r3tmp/kikimr_cluster_d466y3je
2025-11-01 16:15:19,936 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ewjv/002566/ydb/apps/ydbd/ydbd
2025-11-01 16:15:19,937 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7224
2025-11-01 16:15:19,938 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20381
2025-11-01 16:15:19,939 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3354
2025-11-01 16:15:19,944 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28356
2025-11-01 16:15:19,945 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ewjv/002566/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-11-01 16:15:19,945 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/ewjv/002566/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/ewjv/002566/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_reutxlp0.log --grpc-port=7224 --mon-port=20381 --ic-port=29589
2025-11-01 16:15:19,946 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:7224/1, disk_size 68719476736
2025-11-01 16:15:19,946 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ewjv/002566/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-11-01 16:15:19,947 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/ewjv/002566/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/ewjv/002566/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_reutxlp0.log --grpc-port=7224 --mon-port=20381 --ic-port=29589
2025-11-01 16:15:19,948 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/ewjv/002566/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/ewjv/002566/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/ewjv/002566/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_reutxlp0.log', '--grpc-port=7224', '--mon-port=20381', '--ic-port=29589')' in '/home/runner/.ya/build/build_root/ewjv/002566/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-11-01 16:15:19,967 - DEBUG - ya.test - execute: Command pid: 286794
2025-11-01 16:15:19,968 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:7224/1
2025-11-01 16:15:19,970 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-01 16:15:19,971 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-01 16:15:19,971 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ewjv/002566/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:15:19,978 - DEBUG - ya.test - execute: Command pid: 286796
2025-11-01 16:15:20,991 - DEBUG - ya.test - _save_outputs: Command (pid 286796) rc: 0
2025-11-01 16:15:20,992 - DEBUG - ya.test - _save_outputs: Command (pid 286796) elapsed time (sec): 1.0052077770233154
2025-11-01 16:15:20,992 - DEBUG - ya.test - _save_outputs: Command (pid 286796) idrss: 0
2025-11-01 16:15:20,992 - DEBUG - ya.test - _save_outputs: Command (pid 286796) inblock: 0
2025-11-01 16:15:20,992 - DEBUG - ya.test - _save_outputs: Command (pid 286796) isrss: 0
2025-11-01 16:15:20,993 - DEBUG - ya.test - _save_outputs: Command (pid 286796) ixrss: 0
2025-11-01 16:15:20,993 - DEBUG - ya.test - _save_outputs: Command (pid 286796) majflt: 0
2025-11-01 16:15:20,993 - DEBUG - ya.test - _save_outputs: Command (pid 286796) maxrss: 490032
2025-11-01 16:15:20,994 - DEBUG - ya.test - _save_outputs: Command (pid 286796) minflt: 45345
2025-11-01 16:15:20,994 - DEBUG - ya.test - _save_outputs: Command (pid 286796) msgrcv: 0
2025-11-01 16:15:20,994 - DEBUG - ya.test - _save_outputs: Command (pid 286796) msgsnd: 0
2025-11-01 16:15:20,994 - DEBUG - ya.test - _save_outputs: Command (pid 286796) nivcsw: 189
2025-11-01 16:15:20,995 - DEBUG - ya.test - _save_outputs: Command (pid 286796) nsignals: 0
2025-11-01 16:15:20,995 - DEBUG - ya.test - _save_outputs: Command (pid 286796) nswap: 0
2025-11-01 16:15:20,995 - DEBUG - ya.test - _save_outputs: Command (pid 286796) nvcsw: 14
2025-11-01 16:15:20,996 - DEBUG - ya.test - _save_outputs: Command (pid 286796) oublock: 8
2025-11-01 16:15:20,996 - DEBUG - ya.test - _save_outputs: Command (pid 286796) stime: 0.209423
2025-11-01 16:15:20,996 - DEBUG - ya.test - _save_outputs: Command (pid 286796) utime: 0.5286529999999999
2025-11-01 16:15:20,996 - DEBUG - ya.test - _save_outputs: Command (pid 286796) wtime: 1.013
2025-11-01 16:15:20,997 - DEBUG - ya.test - _save_outputs: Command (pid 286796) output:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jzwpcuvpwq
    Host information: 
        Linux ghrun-jzwpcuvpwq 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-11-01 16:15:20,997 - DEBUG - ya.test - _save_outputs: Command (pid 286796) errors:

2025-11-01 16:15:20,998 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e
2025-11-01 16:15:26,078 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ewjv/002566/ydb/apps/ydbd/ydbd
2025-11-01 16:15:26,078 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/ewjv/002566/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:7224', '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: 29589\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 16:15:26,080 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-01 16:15:26,080 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-01 16:15:26,081 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ewjv/002566/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:7224', '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: 29589\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 16:15:26,090 - DEBUG - ya.test - execute: Command pid: 289664
2025-11-01 16:15:27,459 - DEBUG - ya.test - _save_outputs: Command (pid 289664) rc: 0
2025-11-01 16:15:27,459 - DEBUG - ya.test - _save_outputs: Command (pid 289664) elapsed time (sec): 1.3566291332244873
2025-11-01 16:15:27,459 - DEBUG - ya.test - _save_outputs: Command (pid 289664) idrss: 0
2025-11-01 16:15:27,460 - DEBUG - ya.test - _save_outputs: Command (pid 289664) inblock: 16
2025-11-01 16:15:27,460 - DEBUG - ya.test - _save_outputs: Command (pid 289664) isrss: 0
2025-11-01 16:15:27,460 - DEBUG - ya.test - _save_outputs: Command (pid 289664) ixrss: 0
2025-11-01 16:15:27,460 - DEBUG - ya.test - _save_outputs: Command (pid 289664) majflt: 1
2025-11-01 16:15:27,461 - DEBUG - ya.test - _save_outputs: Command (pid 289664) maxrss: 592744
2025-11-01 16:15:27,461 - DEBUG - ya.test - _save_outputs: Command (pid 289664) minflt: 51723
2025-11-01 16:15:27,461 - DEBUG - ya.test - _save_outputs: Command (pid 289664) msgrcv: 0
2025-11-01 16:15:27,461 - DEBUG - ya.test - _save_outputs: Command (pid 289664) msgsnd: 0
2025-11-01 16:15:27,462 - DEBUG - ya.test - _save_outputs: Command (pid 289664) nivcsw: 258
2025-11-01 16:15:27,462 - DEBUG - ya.test - _save_outputs: Command (pid 289664) nsignals: 0
2025-11-01 16:15:27,462 - DEBUG - ya.test - _save_outputs: Command (pid 289664) nswap: 0
2025-11-01 16:15:27,462 - DEBUG - ya.test - _save_outputs: Command (pid 289664) nvcsw: 349
2025-11-01 16:15:27,467 - DEBUG - ya.test - _save_outputs: Command (pid 289664) oublock: 8
2025-11-01 16:15:27,467 - DEBUG - ya.test - _save_outputs: Command (pid 289664) stime: 0.37082699999999996
2025-11-01 16:15:27,468 - DEBUG - ya.test - _save_outputs: Command (pid 289664) utime: 0.538673
2025-11-01 16:15:27,468 - DEBUG - ya.test - _save_outputs: Command (pid 289664) wtime: 1.366
2025-11-01 16:15:27,468 - DEBUG - ya.test - _save_outputs: Command (pid 289664) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 16:15:27,469 - DEBUG - ya.test - _save_outputs: Command (pid 289664) errors:

2025-11-01 16:15:27,470 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ewjv/002566/ydb/apps/ydbd/ydbd
2025-11-01 16:15:27,471 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/ewjv/002566/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:7224', '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-11-01 16:15:27,478 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-01 16:15:27,479 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-01 16:15:27,479 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ewjv/002566/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:7224', '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-11-01 16:15:27,495 - DEBUG - ya.test - execute: Command pid: 290509
2025-11-01 16:15:28,798 - DEBUG - ya.test - _save_outputs: Command (pid 290509) rc: 0
2025-11-01 16:15:28,798 - DEBUG - ya.test - _save_outputs: Command (pid 290509) elapsed time (sec): 1.299764633178711
2025-11-01 16:15:28,799 - DEBUG - ya.test - _save_outputs: Command (pid 290509) idrss: 0
2025-11-01 16:15:28,799 - DEBUG - ya.test - _save_outputs: Command (pid 290509) inblock: 0
2025-11-01 16:15:28,799 - DEBUG - ya.test - _save_outputs: Command (pid 290509) isrss: 0
2025-11-01 16:15:28,800 - DEBUG - ya.test - _save_outputs: Command (pid 290509) ixrss: 0
2025-11-01 16:15:28,800 - DEBUG - ya.test - _save_outputs: Command (pid 290509) majflt: 0
2025-11-01 16:15:28,800 - DEBUG - ya.test - _save_outputs: Command (pid 290509) maxrss: 592980
2025-11-01 16:15:28,800 - DEBUG - ya.test - _save_outputs: Command (pid 290509) minflt: 51695
2025-11-01 16:15:28,801 - DEBUG - ya.test - _save_outputs: Command (pid 290509) msgrcv: 0
2025-11-01 16:15:28,801 - DEBUG - ya.test - _save_outputs: Command (pid 290509) msgsnd: 0
2025-11-01 16:15:28,801 - DEBUG - ya.test - _save_outputs: Command (pid 290509) nivcsw: 233
2025-11-01 16:15:28,801 - DEBUG - ya.test - _save_outputs: Command (pid 290509) nsignals: 0
2025-11-01 16:15:28,802 - DEBUG - ya.test - _save_outputs: Command (pid 290509) nswap: 0
2025-11-01 16:15:28,802 - DEBUG - ya.test - _save_outputs: Command (pid 290509) nvcsw: 515
2025-11-01 16:15:28,802 - DEBUG - ya.test - _save_outputs: Command (pid 290509) oublock: 8
2025-11-01 16:15:28,803 - DEBUG - ya.test - _save_outputs: Command (pid 290509) stime: 0.325119
2025-11-01 16:15:28,811 - DEBUG - ya.test - _save_outputs: Command (pid 290509) utime: 0.579612
2025-11-01 16:15:28,812 - DEBUG - ya.test - _save_outputs: Command (pid 290509) wtime: 1.316
2025-11-01 16:15:28,812 - DEBUG - ya.test - _save_outputs: Command (pid 290509) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-01 16:15:28,812 - DEBUG - ya.test - _save_outputs: Command (pid 290509) errors:

2025-11-01 16:15:28,813 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 16:15:28,983 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 16:15:29,043 - 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-11-01 16:15:29,051 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:15:29,055 - 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-11-01 16:15:29,090 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:15:29,091 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, b7c60718-b65d-4861-b6fd-9dbb28c7b2fa, localhost:7224): creating call state
2025-11-01 16:15:29,092 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, b7c60718-b65d-4861-b6fd-9dbb28c7b2fa, localhost:7224): request = { database: "/Root" }
2025-11-01 16:15:29,102 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, b7c60718-b65d-4861-b6fd-9dbb28c7b2fa, localhost:7224): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jzwpcuvpwq.auto.internal" port: 7224 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-11-01 16:15:29,104 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jzwpcuvpwq.auto.internal:7224, location 1, ssl: False>]>
2025-11-01 16:15:29,105 - 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-11-01 16:15:29,151 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:7224
2025-11-01 16:15:29,152 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-01 16:15:29,153 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 4be6aa73-807c-4e5d-8b45-2a922e604e6a, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:29,154 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 4be6aa73-807c-4e5d-8b45-2a922e604e6a, ghrun-jzwpcuvpwq.auto.internal:7224): request = {  }
2025-11-01 16:15:29,164 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 4be6aa73-807c-4e5d-8b45-2a922e604e6a, ghrun-jzwpcuvpwq.auto.internal:7224): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" node_id: 1 }
2025-11-01 16:15:29,166 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 61a4def0-7ef1-4121-a53c-ed3bc6848519, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:29,166 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 61a4def0-7ef1-4121-a53c-ed3bc6848519, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" }
2025-11-01 16:15:29,184 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 61a4def0-7ef1-4121-a53c-ed3bc6848519, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:29,208 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:29,209 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, edf924d4-ed81-4ed6-8528-5fa9f3b9483d, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:29,210 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, edf924d4-ed81-4ed6-8528-5fa9f3b9483d, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:29,211 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, edf924d4-ed81-4ed6-8528-5fa9f3b9483d, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:32,179 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:32,180 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-01 16:15:32,180 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:32,181 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4f2814f3-175b-43a2-a8be-2ec8eb5a90b8, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:32,182 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4f2814f3-175b-43a2-a8be-2ec8eb5a90b8, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:32,188 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4f2814f3-175b-43a2-a8be-2ec8eb5a90b8, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:33,673 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:33,674 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:33,675 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e26d8e9b-6071-4d12-8120-2e9f6e45be1e, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:33,676 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e26d8e9b-6071-4d12-8120-2e9f6e45be1e, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:33,677 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e26d8e9b-6071-4d12-8120-2e9f6e45be1e, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:35,227 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:35,228 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:35,229 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 246a1e53-8fc2-4183-be9a-7329d11cbba3, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:35,230 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 246a1e53-8fc2-4183-be9a-7329d11cbba3, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:35,231 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 246a1e53-8fc2-4183-be9a-7329d11cbba3, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:36,430 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:36,431 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:36,432 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 13155aba-48c6-49ea-b5d2-458048df633e, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:36,433 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 13155aba-48c6-49ea-b5d2-458048df633e, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:36,434 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 13155aba-48c6-49ea-b5d2-458048df633e, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:38,807 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:38,808 - 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-11-01 16:15:38,809 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:38,809 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8905bab5-51c5-4abd-b68b-c5cf7930885c, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:38,811 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8905bab5-51c5-4abd-b68b-c5cf7930885c, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:38,812 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8905bab5-51c5-4abd-b68b-c5cf7930885c, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:40,068 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:40,069 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:40,070 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 268cb054-5ab6-4d98-a13d-f672330103ba, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:40,071 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 268cb054-5ab6-4d98-a13d-f672330103ba, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:40,072 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 268cb054-5ab6-4d98-a13d-f672330103ba, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:40,411 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:40,412 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:40,413 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f3deca12-9597-4dd5-8899-45dddae61cdf, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:40,414 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f3deca12-9597-4dd5-8899-45dddae61cdf, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:40,425 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f3deca12-9597-4dd5-8899-45dddae61cdf, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:40,776 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:40,777 - 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-11-01 16:15:40,777 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:40,778 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 050663a6-cfde-4fd4-b718-d928c7cae16f, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:40,779 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 050663a6-cfde-4fd4-b718-d928c7cae16f, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:40,781 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 050663a6-cfde-4fd4-b718-d928c7cae16f, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:41,965 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:41,966 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:41,967 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, af75c16b-fa16-4f61-9d9f-b8af3d0f3667, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:41,968 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, af75c16b-fa16-4f61-9d9f-b8af3d0f3667, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:41,969 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, af75c16b-fa16-4f61-9d9f-b8af3d0f3667, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:42,352 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:42,353 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:42,354 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4b1a8f2b-04d9-437e-be2d-7fe705e9e6cc, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:42,355 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4b1a8f2b-04d9-437e-be2d-7fe705e9e6cc, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:42,358 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4b1a8f2b-04d9-437e-be2d-7fe705e9e6cc, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:42,752 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:42,753 - 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-11-01 16:15:42,753 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:42,754 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 12147b40-3c8a-4e2f-9aaf-7742cae30c63, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:42,755 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 12147b40-3c8a-4e2f-9aaf-7742cae30c63, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:42,759 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 12147b40-3c8a-4e2f-9aaf-7742cae30c63, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:44,096 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:44,097 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:44,097 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 37ac7d0a-7def-4871-bd48-f62b763e71a0, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:44,098 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 37ac7d0a-7def-4871-bd48-f62b763e71a0, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:44,099 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 37ac7d0a-7def-4871-bd48-f62b763e71a0, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:44,654 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:44,655 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:44,656 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2c079eef-02a6-48ad-bae8-2e782a49fcd2, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:44,657 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2c079eef-02a6-48ad-bae8-2e782a49fcd2, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:44,658 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2c079eef-02a6-48ad-bae8-2e782a49fcd2, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:46,262 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:46,263 - 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-11-01 16:15:46,263 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:46,264 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 03a7f24e-309b-4881-bcd0-a8b129eb00e3, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:46,265 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 03a7f24e-309b-4881-bcd0-a8b129eb00e3, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:46,283 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 03a7f24e-309b-4881-bcd0-a8b129eb00e3, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:47,681 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:47,681 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:47,682 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4c6d9222-5be2-45ed-a683-8fd3b2b36498, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:47,683 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4c6d9222-5be2-45ed-a683-8fd3b2b36498, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:47,684 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4c6d9222-5be2-45ed-a683-8fd3b2b36498, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:48,420 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:48,420 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:48,421 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 103dbf03-601d-4086-b919-085e648eeb16, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:48,422 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 103dbf03-601d-4086-b919-085e648eeb16, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:48,432 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 103dbf03-601d-4086-b919-085e648eeb16, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:49,396 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:49,396 - 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-11-01 16:15:49,397 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:49,398 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0f12fdf6-3403-4bf6-8411-dcd8c0bda957, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:49,399 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0f12fdf6-3403-4bf6-8411-dcd8c0bda957, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:49,404 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0f12fdf6-3403-4bf6-8411-dcd8c0bda957, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:51,377 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:51,378 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:51,379 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ec0a0f57-d6c7-4749-880c-f85daf8bdb14, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:51,380 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ec0a0f57-d6c7-4749-880c-f85daf8bdb14, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:51,381 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ec0a0f57-d6c7-4749-880c-f85daf8bdb14, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:52,132 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:52,132 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:52,133 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 929f9740-537a-4da1-9c55-8b2294590cc6, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:52,134 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 929f9740-537a-4da1-9c55-8b2294590cc6, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:52,148 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 929f9740-537a-4da1-9c55-8b2294590cc6, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:53,180 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:53,181 - 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-11-01 16:15:53,181 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:53,182 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b2c17b2a-7c62-432c-b676-39b8608ea1f3, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:53,183 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b2c17b2a-7c62-432c-b676-39b8608ea1f3, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:53,184 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b2c17b2a-7c62-432c-b676-39b8608ea1f3, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:55,080 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:55,080 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:55,081 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d004e0ea-f9e2-4add-8b87-5900873a0046, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:55,082 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d004e0ea-f9e2-4add-8b87-5900873a0046, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:55,083 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d004e0ea-f9e2-4add-8b87-5900873a0046, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:55,454 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:55,455 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:55,456 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8dfc7726-2ac3-40f3-9e03-518cc1065c68, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:55,457 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8dfc7726-2ac3-40f3-9e03-518cc1065c68, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:55,459 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8dfc7726-2ac3-40f3-9e03-518cc1065c68, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:56,530 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:56,531 - 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-11-01 16:15:56,532 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:56,533 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f269cc3d-b10d-4ce0-bdf6-7fa6ea324c09, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:56,534 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f269cc3d-b10d-4ce0-bdf6-7fa6ea324c09, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:56,535 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f269cc3d-b10d-4ce0-bdf6-7fa6ea324c09, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:57,886 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:57,893 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:57,895 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c57ec1aa-9ca3-4aa2-869e-b258f97f8914, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:57,896 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c57ec1aa-9ca3-4aa2-869e-b258f97f8914, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:57,897 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c57ec1aa-9ca3-4aa2-869e-b258f97f8914, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:58,247 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:58,248 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:58,249 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, db5707d2-9ea6-4432-8717-be89b28c3ba3, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:58,250 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, db5707d2-9ea6-4432-8717-be89b28c3ba3, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:58,251 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, db5707d2-9ea6-4432-8717-be89b28c3ba3, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:58,717 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:58,718 - 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-11-01 16:15:58,719 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:15:58,719 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 96f4602b-e356-45d0-a24f-23372ac4ff99, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:15:58,720 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 96f4602b-e356-45d0-a24f-23372ac4ff99, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:15:58,722 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 96f4602b-e356-45d0-a24f-23372ac4ff99, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:00,361 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:00,362 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:00,363 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ed0656c9-ad18-43db-ba82-a8285a95df6e, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:16:00,364 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ed0656c9-ad18-43db-ba82-a8285a95df6e, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:16:00,365 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ed0656c9-ad18-43db-ba82-a8285a95df6e, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:00,600 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:00,601 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:00,602 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2614896c-e0be-4e12-a294-d45d64d39262, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:16:00,602 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2614896c-e0be-4e12-a294-d45d64d39262, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:16:00,604 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2614896c-e0be-4e12-a294-d45d64d39262, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:01,249 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:01,249 - 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-11-01 16:16:01,250 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:01,250 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2006bc06-6ab6-42aa-9937-d9fc0872276a, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:16:01,251 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2006bc06-6ab6-42aa-9937-d9fc0872276a, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:16:01,272 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2006bc06-6ab6-42aa-9937-d9fc0872276a, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:02,358 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:02,359 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:02,360 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e7bf7599-ccbc-449c-a201-2538f16db27d, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:16:02,360 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e7bf7599-ccbc-449c-a201-2538f16db27d, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:16:02,362 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e7bf7599-ccbc-449c-a201-2538f16db27d, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:02,589 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:02,590 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:02,591 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 92fbefe6-88f3-4e7f-9d23-66d56ceab441, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:16:02,592 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 92fbefe6-88f3-4e7f-9d23-66d56ceab441, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:16:02,593 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 92fbefe6-88f3-4e7f-9d23-66d56ceab441, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:03,560 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:03,561 - 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-11-01 16:16:03,562 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:03,562 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 69cbcd82-648c-4e6f-a509-ab4fee75a9ee, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:16:03,563 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 69cbcd82-648c-4e6f-a509-ab4fee75a9ee, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:16:03,565 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 69cbcd82-648c-4e6f-a509-ab4fee75a9ee, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:05,108 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:05,109 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:05,110 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 600370f3-7032-4a33-8b81-faef72a3f7b8, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:16:05,111 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 600370f3-7032-4a33-8b81-faef72a3f7b8, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:16:05,112 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 600370f3-7032-4a33-8b81-faef72a3f7b8, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:05,574 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:05,575 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:05,575 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 173024b2-e91d-4703-9620-372f8ad068ee, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:16:05,576 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 173024b2-e91d-4703-9620-372f8ad068ee, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:16:05,587 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 173024b2-e91d-4703-9620-372f8ad068ee, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:06,280 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:06,287 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:06,288 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 538dec2c-02f2-4d7b-930d-37d2140d50ed, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:16:06,289 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 538dec2c-02f2-4d7b-930d-37d2140d50ed, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:16:06,290 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 538dec2c-02f2-4d7b-930d-37d2140d50ed, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:13,596 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:23,643 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:23,644 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 667c5934-2a10-445b-89fb-6bf71646c5fe, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:16:23,645 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 667c5934-2a10-445b-89fb-6bf71646c5fe, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:16:23,647 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 667c5934-2a10-445b-89fb-6bf71646c5fe, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:34,342 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:34,550 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-01 16:16:34,550 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:34,551 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6ec121c0-48e1-4a40-aecf-cdb362841fe6, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:16:34,552 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6ec121c0-48e1-4a40-aecf-cdb362841fe6, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:16:34,564 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6ec121c0-48e1-4a40-aecf-cdb362841fe6, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:40,057 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:16:40,058 - 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-11-01 16:16:40,103 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:16:40,104 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, d21949b4-d51e-4f0c-a712-dabb9b6a486d, localhost:7224): creating call state
2025-11-01 16:16:40,105 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, d21949b4-d51e-4f0c-a712-dabb9b6a486d, localhost:7224): request = { database: "/Root" }
2025-11-01 16:16:40,256 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, d21949b4-d51e-4f0c-a712-dabb9b6a486d, localhost:7224): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jzwpcuvpwq.auto.internal" port: 7224 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-11-01 16:16:40,257 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jzwpcuvpwq.auto.internal:7224, location 1, ssl: False>]>
2025-11-01 16:16:40,258 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:7224
2025-11-01 16:16:42,461 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:52,495 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:16:52,503 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e2804339-8979-4b02-8448-7f15cc11e9fb, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:16:52,504 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e2804339-8979-4b02-8448-7f15cc11e9fb, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:16:52,506 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e2804339-8979-4b02-8448-7f15cc11e9fb, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:54,184 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:04,191 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:04,192 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6bb809c4-447c-44c3-9c60-7d0dc0c36221, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:04,193 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6bb809c4-447c-44c3-9c60-7d0dc0c36221, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:04,194 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6bb809c4-447c-44c3-9c60-7d0dc0c36221, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:05,343 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:05,350 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-11-01 16:17:05,350 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:05,351 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d41ba99d-bab2-4e8c-91c5-bf0a4b26e7be, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:05,352 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d41ba99d-bab2-4e8c-91c5-bf0a4b26e7be, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:05,355 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d41ba99d-bab2-4e8c-91c5-bf0a4b26e7be, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:05,990 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:05,992 - 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-11-01 16:17:05,993 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:05,994 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d7b8515e-4c1a-4bf3-b442-61abf86a098d, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:05,995 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d7b8515e-4c1a-4bf3-b442-61abf86a098d, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:05,996 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d7b8515e-4c1a-4bf3-b442-61abf86a098d, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:12,717 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:12,718 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_6_compression created
2025-11-01 16:17:12,719 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:12,720 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 97bee0ca-1510-4f93-bc1f-cc7c1014b98f, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:12,720 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 97bee0ca-1510-4f93-bc1f-cc7c1014b98f, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:12,729 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 97bee0ca-1510-4f93-bc1f-cc7c1014b98f, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:13,641 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:13,642 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:13,643 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 83dfa5dd-799d-42a8-82fb-c088e30af27c, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:13,644 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 83dfa5dd-799d-42a8-82fb-c088e30af27c, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:13,645 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 83dfa5dd-799d-42a8-82fb-c088e30af27c, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:15,989 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:15,990 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:15,990 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 19f8d7cd-fc5c-471b-b3ef-2416c99561d1, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:15,992 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 19f8d7cd-fc5c-471b-b3ef-2416c99561d1, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:15,993 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 19f8d7cd-fc5c-471b-b3ef-2416c99561d1, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:17,138 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:17,139 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:17,140 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 007bf55f-6dcf-479e-b451-73d7be7ec2e7, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:17,141 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 007bf55f-6dcf-479e-b451-73d7be7ec2e7, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:17,142 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 007bf55f-6dcf-479e-b451-73d7be7ec2e7, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:19,993 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:19,994 - 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-11-01 16:17:19,994 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:19,995 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5cc7a1dc-86ef-467b-849d-d5d76699b760, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:19,996 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5cc7a1dc-86ef-467b-849d-d5d76699b760, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:20,001 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5cc7a1dc-86ef-467b-849d-d5d76699b760, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:22,087 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:22,087 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:22,088 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 53c8a417-4260-4071-b004-19bf3735df17, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:22,089 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 53c8a417-4260-4071-b004-19bf3735df17, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:22,090 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 53c8a417-4260-4071-b004-19bf3735df17, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:22,512 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:22,513 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:22,514 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bfc8fa3b-54d8-43c8-b74b-2c2d41beefe8, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:22,527 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bfc8fa3b-54d8-43c8-b74b-2c2d41beefe8, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:22,528 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bfc8fa3b-54d8-43c8-b74b-2c2d41beefe8, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:23,120 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:23,120 - 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-11-01 16:17:23,121 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:23,121 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b35e4ad7-9147-45e8-b6fd-ad2bfd5b8e10, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:23,122 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b35e4ad7-9147-45e8-b6fd-ad2bfd5b8e10, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:23,125 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b35e4ad7-9147-45e8-b6fd-ad2bfd5b8e10, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:25,274 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:25,343 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:25,344 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 161f6b2e-bd0e-4478-8cf0-b30bfa72bf11, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:25,345 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 161f6b2e-bd0e-4478-8cf0-b30bfa72bf11, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:25,346 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 161f6b2e-bd0e-4478-8cf0-b30bfa72bf11, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:25,975 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:25,976 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:25,977 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 121bc764-05c2-4c90-ab13-6810157b7c7b, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:25,978 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 121bc764-05c2-4c90-ab13-6810157b7c7b, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:25,999 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 121bc764-05c2-4c90-ab13-6810157b7c7b, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:26,592 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:26,593 - 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-11-01 16:17:26,594 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:26,594 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 893767e2-032b-44a2-9b80-78c9a56de5bb, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:26,595 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 893767e2-032b-44a2-9b80-78c9a56de5bb, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:26,597 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 893767e2-032b-44a2-9b80-78c9a56de5bb, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:28,512 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:28,513 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:28,514 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a6a97302-93d7-4d22-88c3-7d8aa30e7b09, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:28,514 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a6a97302-93d7-4d22-88c3-7d8aa30e7b09, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:28,520 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a6a97302-93d7-4d22-88c3-7d8aa30e7b09, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:29,140 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:29,141 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:29,142 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ea8f9e8b-e6a1-4c7f-a609-7919088d4191, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:29,143 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ea8f9e8b-e6a1-4c7f-a609-7919088d4191, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:29,145 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ea8f9e8b-e6a1-4c7f-a609-7919088d4191, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:29,924 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:29,925 - 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-11-01 16:17:29,926 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:29,927 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e471c325-555f-4560-af98-c5c9f82a9c90, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:29,928 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e471c325-555f-4560-af98-c5c9f82a9c90, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:29,935 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e471c325-555f-4560-af98-c5c9f82a9c90, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:31,839 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:31,840 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:31,841 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c5de8c59-8c6d-4caf-8a90-4312c40c0c93, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:31,842 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c5de8c59-8c6d-4caf-8a90-4312c40c0c93, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:31,843 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c5de8c59-8c6d-4caf-8a90-4312c40c0c93, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:32,541 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:32,542 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:32,542 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d99f216-94ee-4ca2-8a79-24d6571612ce, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:32,543 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d99f216-94ee-4ca2-8a79-24d6571612ce, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:32,546 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d99f216-94ee-4ca2-8a79-24d6571612ce, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:33,119 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:33,120 - 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-11-01 16:17:33,121 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:33,121 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bf144106-6de3-431c-878a-27a0d2caf835, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:33,122 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bf144106-6de3-431c-878a-27a0d2caf835, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:33,123 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bf144106-6de3-431c-878a-27a0d2caf835, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:34,504 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:34,504 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:34,505 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8c6af5af-3a57-48d2-922a-0872142634ea, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:34,506 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8c6af5af-3a57-48d2-922a-0872142634ea, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:34,510 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8c6af5af-3a57-48d2-922a-0872142634ea, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:35,287 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:35,288 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:35,289 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, af7f0461-5a2c-472b-bd09-99fd395ee31f, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:35,290 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, af7f0461-5a2c-472b-bd09-99fd395ee31f, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:35,292 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, af7f0461-5a2c-472b-bd09-99fd395ee31f, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:36,239 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:36,240 - 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-11-01 16:17:36,240 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:36,241 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 06567946-1a25-4de3-9206-b61b17d8ae60, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:36,242 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 06567946-1a25-4de3-9206-b61b17d8ae60, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:36,244 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 06567946-1a25-4de3-9206-b61b17d8ae60, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:37,848 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:37,848 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:37,849 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4c219265-4578-492c-9b50-c83ba83aaa77, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:37,850 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4c219265-4578-492c-9b50-c83ba83aaa77, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:37,852 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4c219265-4578-492c-9b50-c83ba83aaa77, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:38,109 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:38,110 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:38,111 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c73fd646-83a9-4a07-a283-ddaf8e9d4e13, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:38,112 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c73fd646-83a9-4a07-a283-ddaf8e9d4e13, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:38,113 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c73fd646-83a9-4a07-a283-ddaf8e9d4e13, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:38,823 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:38,824 - 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-11-01 16:17:38,824 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:38,825 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1410c1fc-a7dd-447f-8353-44e24f5e4018, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:38,826 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1410c1fc-a7dd-447f-8353-44e24f5e4018, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:38,829 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1410c1fc-a7dd-447f-8353-44e24f5e4018, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:40,272 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:40,273 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:40,273 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3b2f2119-6cad-477d-92a2-02e50f3cab7c, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:40,274 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3b2f2119-6cad-477d-92a2-02e50f3cab7c, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:40,275 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3b2f2119-6cad-477d-92a2-02e50f3cab7c, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:40,693 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:40,694 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:40,695 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fd4ca55f-82b6-48c8-8158-1162a3b67509, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:40,695 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fd4ca55f-82b6-48c8-8158-1162a3b67509, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:40,697 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fd4ca55f-82b6-48c8-8158-1162a3b67509, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:41,391 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:41,391 - 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-11-01 16:17:41,392 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:41,393 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9ae18220-d2b7-4c58-837b-729d1aebc170, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:41,394 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9ae18220-d2b7-4c58-837b-729d1aebc170, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:41,400 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9ae18220-d2b7-4c58-837b-729d1aebc170, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:43,015 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:43,016 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:43,016 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, edb9d7bd-7fa7-41b8-8a1f-9e110a712fb1, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:43,017 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, edb9d7bd-7fa7-41b8-8a1f-9e110a712fb1, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:43,018 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, edb9d7bd-7fa7-41b8-8a1f-9e110a712fb1, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:43,551 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:43,552 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:43,553 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1a06a16d-0bd6-4280-8082-a894c5e97d11, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:43,553 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1a06a16d-0bd6-4280-8082-a894c5e97d11, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:43,555 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1a06a16d-0bd6-4280-8082-a894c5e97d11, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:45,162 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:45,163 - 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-11-01 16:17:45,164 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:45,164 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c6368005-abca-4af1-b830-63ed84d9d956, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:45,165 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c6368005-abca-4af1-b830-63ed84d9d956, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:45,188 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c6368005-abca-4af1-b830-63ed84d9d956, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:47,376 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:47,377 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:47,377 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 978e0d60-a6a1-400f-a005-e21cca56ad95, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:47,378 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 978e0d60-a6a1-400f-a005-e21cca56ad95, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:47,384 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 978e0d60-a6a1-400f-a005-e21cca56ad95, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:48,062 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:48,063 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:48,064 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 502ee4d6-6956-44f9-b429-2bf70d2b6b95, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:48,065 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 502ee4d6-6956-44f9-b429-2bf70d2b6b95, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:48,079 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 502ee4d6-6956-44f9-b429-2bf70d2b6b95, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:49,501 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:49,501 - 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-11-01 16:17:49,502 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:49,502 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c3d811e6-0356-4bfa-8e8f-790dc425d1dc, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:49,503 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c3d811e6-0356-4bfa-8e8f-790dc425d1dc, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:49,507 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c3d811e6-0356-4bfa-8e8f-790dc425d1dc, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:52,097 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:52,098 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:52,099 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a06cc24f-244e-47f4-9bf3-e17c853801c7, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:52,100 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a06cc24f-244e-47f4-9bf3-e17c853801c7, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:52,121 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a06cc24f-244e-47f4-9bf3-e17c853801c7, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:52,783 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:52,784 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:52,785 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9dca7c93-9ff4-4764-a9fb-4c6b16cad331, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:52,786 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9dca7c93-9ff4-4764-a9fb-4c6b16cad331, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:52,791 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9dca7c93-9ff4-4764-a9fb-4c6b16cad331, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:53,522 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:53,523 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:17:53,524 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2561c852-f647-47aa-8740-4c98e2dfb037, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:17:53,525 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2561c852-f647-47aa-8740-4c98e2dfb037, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:17:53,537 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2561c852-f647-47aa-8740-4c98e2dfb037, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:00,403 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:18:10,461 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:18:10,462 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f04e5691-0c78-4ad5-8afc-a598c195f60f, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:18:10,463 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f04e5691-0c78-4ad5-8afc-a598c195f60f, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:18:10,465 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f04e5691-0c78-4ad5-8afc-a598c195f60f, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:12,616 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:18:12,617 - 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-11-01 16:18:12,659 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:18:12,660 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 71d6b38a-4a55-46e7-81d4-ffee9b76c27f, localhost:7224): creating call state
2025-11-01 16:18:12,660 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 71d6b38a-4a55-46e7-81d4-ffee9b76c27f, localhost:7224): request = { database: "/Root" }
2025-11-01 16:18:13,129 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 71d6b38a-4a55-46e7-81d4-ffee9b76c27f, localhost:7224): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jzwpcuvpwq.auto.internal" port: 7224 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-11-01 16:18:13,130 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jzwpcuvpwq.auto.internal:7224, location 1, ssl: False>]>
2025-11-01 16:18:13,131 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:7224
2025-11-01 16:18:18,242 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:18:28,345 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:18:28,347 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a1726b76-6269-49f5-886d-068887c20817, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:18:28,347 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a1726b76-6269-49f5-886d-068887c20817, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:18:28,349 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a1726b76-6269-49f5-886d-068887c20817, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:29,819 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:18:39,831 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:18:39,832 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5676f4f1-d0aa-4f4b-b118-c0084f138ffd, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:18:39,833 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5676f4f1-d0aa-4f4b-b118-c0084f138ffd, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:18:39,834 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5676f4f1-d0aa-4f4b-b118-c0084f138ffd, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:40,585 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:18:40,590 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_6_compression`, volumes `value` (8000000, 1466256)
2025-11-01 16:18:40,590 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:18:40,591 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8c1d5757-36d9-48d4-823a-eaf3343870f3, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:18:40,592 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8c1d5757-36d9-48d4-823a-eaf3343870f3, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:18:40,593 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8c1d5757-36d9-48d4-823a-eaf3343870f3, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:40,900 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:18:40,901 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:18:40,901 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 501e0587-df0c-4872-b2d0-3dfd1b6878bb, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:18:40,902 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 501e0587-df0c-4872-b2d0-3dfd1b6878bb, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:18:40,904 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 501e0587-df0c-4872-b2d0-3dfd1b6878bb, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:41,887 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:18:51,891 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:18:51,893 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8f1d8686-26b8-4d91-809b-8de01a4c3d45, ghrun-jzwpcuvpwq.auto.internal:7224): creating call state
2025-11-01 16:18:51,894 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8f1d8686-26b8-4d91-809b-8de01a4c3d45, ghrun-jzwpcuvpwq.auto.internal:7224): request = { session_id: "ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=" 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-11-01 16:18:51,895 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8f1d8686-26b8-4d91-809b-8de01a4c3d45, ghrun-jzwpcuvpwq.auto.internal:7224): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:52,617 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWRkNzZiMjItMTAzYzUzN2MtMjIwMTYxOGItZmM3Yjg4M2I=
2025-11-01 16:18:52,621 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_6_compression`, volumes `value` (8000000, 1466256)
2025-11-01 16:18:52,622 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_6_compression` 8092160 / 1466256: 5.518927117774795
2025-11-01 16:18:52,636 - INFO - ya.test - pytest_runtest_teardown: Test teardown
