2025-11-01 16:15:20,191 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:15:20,192 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_15_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 15]
2025-11-01 16:15:20,192 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:15:20,192 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 16:15:20,203 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-01 16:15:20,204 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-01 16:15:20,205 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ewjv/002553/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:15:20,223 - DEBUG - ya.test - execute: Command pid: 286845
2025-11-01 16:15:21,259 - DEBUG - ya.test - _save_outputs: Command (pid 286845) rc: 0
2025-11-01 16:15:21,260 - DEBUG - ya.test - _save_outputs: Command (pid 286845) elapsed time (sec): 1.0326850414276123
2025-11-01 16:15:21,260 - DEBUG - ya.test - _save_outputs: Command (pid 286845) idrss: 0
2025-11-01 16:15:21,260 - DEBUG - ya.test - _save_outputs: Command (pid 286845) inblock: 0
2025-11-01 16:15:21,260 - DEBUG - ya.test - _save_outputs: Command (pid 286845) isrss: 0
2025-11-01 16:15:21,261 - DEBUG - ya.test - _save_outputs: Command (pid 286845) ixrss: 0
2025-11-01 16:15:21,261 - DEBUG - ya.test - _save_outputs: Command (pid 286845) majflt: 0
2025-11-01 16:15:21,261 - DEBUG - ya.test - _save_outputs: Command (pid 286845) maxrss: 486792
2025-11-01 16:15:21,262 - DEBUG - ya.test - _save_outputs: Command (pid 286845) minflt: 45405
2025-11-01 16:15:21,262 - DEBUG - ya.test - _save_outputs: Command (pid 286845) msgrcv: 0
2025-11-01 16:15:21,262 - DEBUG - ya.test - _save_outputs: Command (pid 286845) msgsnd: 0
2025-11-01 16:15:21,262 - DEBUG - ya.test - _save_outputs: Command (pid 286845) nivcsw: 169
2025-11-01 16:15:21,263 - DEBUG - ya.test - _save_outputs: Command (pid 286845) nsignals: 0
2025-11-01 16:15:21,263 - DEBUG - ya.test - _save_outputs: Command (pid 286845) nswap: 0
2025-11-01 16:15:21,263 - DEBUG - ya.test - _save_outputs: Command (pid 286845) nvcsw: 14
2025-11-01 16:15:21,264 - DEBUG - ya.test - _save_outputs: Command (pid 286845) oublock: 8
2025-11-01 16:15:21,264 - DEBUG - ya.test - _save_outputs: Command (pid 286845) stime: 0.23097099999999998
2025-11-01 16:15:21,264 - DEBUG - ya.test - _save_outputs: Command (pid 286845) utime: 0.509004
2025-11-01 16:15:21,265 - DEBUG - ya.test - _save_outputs: Command (pid 286845) wtime: 1.052
2025-11-01 16:15:21,265 - DEBUG - ya.test - _save_outputs: Command (pid 286845) 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:21,265 - DEBUG - ya.test - _save_outputs: Command (pid 286845) errors:

2025-11-01 16:15:21,266 - 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:21,392 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ewjv/002553/ydb/apps/ydbd/ydbd
2025-11-01 16:15:21,393 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3024
2025-11-01 16:15:21,395 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/ewjv/002553/r3tmp/kikimr_cluster_cxjzfcke
2025-11-01 16:15:21,441 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ewjv/002553/ydb/apps/ydbd/ydbd
2025-11-01 16:15:21,442 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18143
2025-11-01 16:15:21,443 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13410
2025-11-01 16:15:21,444 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9744
2025-11-01 16:15:21,444 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21359
2025-11-01 16:15:21,445 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ewjv/002553/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/kikimr_configs"
2025-11-01 16:15:21,446 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/ewjv/002553/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/002553/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1/logfile_ix0q9gri.log --grpc-port=18143 --mon-port=13410 --ic-port=3024
2025-11-01 16:15:21,446 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:18143/1, disk_size 68719476736
2025-11-01 16:15:21,447 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ewjv/002553/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/kikimr_configs"
2025-11-01 16:15:21,448 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/ewjv/002553/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/002553/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1/logfile_ix0q9gri.log --grpc-port=18143 --mon-port=13410 --ic-port=3024
2025-11-01 16:15:21,449 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/ewjv/002553/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/ewjv/002553/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/ewjv/002553/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1/logfile_ix0q9gri.log', '--grpc-port=18143', '--mon-port=13410', '--ic-port=3024')' in '/home/runner/.ya/build/build_root/ewjv/002553/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1' (no wait)
2025-11-01 16:15:21,471 - DEBUG - ya.test - execute: Command pid: 287294
2025-11-01 16:15:21,472 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:18143/1
2025-11-01 16:15:21,474 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-01 16:15:21,475 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-01 16:15:21,475 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ewjv/002553/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:15:21,486 - DEBUG - ya.test - execute: Command pid: 287296
2025-11-01 16:15:22,319 - DEBUG - ya.test - _save_outputs: Command (pid 287296) rc: 0
2025-11-01 16:15:22,320 - DEBUG - ya.test - _save_outputs: Command (pid 287296) elapsed time (sec): 0.8281776905059814
2025-11-01 16:15:22,320 - DEBUG - ya.test - _save_outputs: Command (pid 287296) idrss: 0
2025-11-01 16:15:22,320 - DEBUG - ya.test - _save_outputs: Command (pid 287296) inblock: 0
2025-11-01 16:15:22,320 - DEBUG - ya.test - _save_outputs: Command (pid 287296) isrss: 0
2025-11-01 16:15:22,321 - DEBUG - ya.test - _save_outputs: Command (pid 287296) ixrss: 0
2025-11-01 16:15:22,321 - DEBUG - ya.test - _save_outputs: Command (pid 287296) majflt: 0
2025-11-01 16:15:22,321 - DEBUG - ya.test - _save_outputs: Command (pid 287296) maxrss: 489712
2025-11-01 16:15:22,322 - DEBUG - ya.test - _save_outputs: Command (pid 287296) minflt: 45400
2025-11-01 16:15:22,322 - DEBUG - ya.test - _save_outputs: Command (pid 287296) msgrcv: 0
2025-11-01 16:15:22,323 - DEBUG - ya.test - _save_outputs: Command (pid 287296) msgsnd: 0
2025-11-01 16:15:22,323 - DEBUG - ya.test - _save_outputs: Command (pid 287296) nivcsw: 136
2025-11-01 16:15:22,323 - DEBUG - ya.test - _save_outputs: Command (pid 287296) nsignals: 0
2025-11-01 16:15:22,324 - DEBUG - ya.test - _save_outputs: Command (pid 287296) nswap: 0
2025-11-01 16:15:22,324 - DEBUG - ya.test - _save_outputs: Command (pid 287296) nvcsw: 14
2025-11-01 16:15:22,324 - DEBUG - ya.test - _save_outputs: Command (pid 287296) oublock: 8
2025-11-01 16:15:22,324 - DEBUG - ya.test - _save_outputs: Command (pid 287296) stime: 0.204087
2025-11-01 16:15:22,325 - DEBUG - ya.test - _save_outputs: Command (pid 287296) utime: 0.498409
2025-11-01 16:15:22,325 - DEBUG - ya.test - _save_outputs: Command (pid 287296) wtime: 0.84
2025-11-01 16:15:22,325 - DEBUG - ya.test - _save_outputs: Command (pid 287296) 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:22,326 - DEBUG - ya.test - _save_outputs: Command (pid 287296) errors:

2025-11-01 16:15:22,326 - 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:28,423 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ewjv/002553/ydb/apps/ydbd/ydbd
2025-11-01 16:15:28,424 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/ewjv/002553/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18143', '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: 3024\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 16:15:28,425 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-01 16:15:28,426 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-01 16:15:28,426 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ewjv/002553/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18143', '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: 3024\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 16:15:28,442 - DEBUG - ya.test - execute: Command pid: 290836
2025-11-01 16:15:29,455 - DEBUG - ya.test - _save_outputs: Command (pid 290836) rc: 0
2025-11-01 16:15:29,456 - DEBUG - ya.test - _save_outputs: Command (pid 290836) elapsed time (sec): 1.0081398487091064
2025-11-01 16:15:29,456 - DEBUG - ya.test - _save_outputs: Command (pid 290836) idrss: 0
2025-11-01 16:15:29,456 - DEBUG - ya.test - _save_outputs: Command (pid 290836) inblock: 0
2025-11-01 16:15:29,456 - DEBUG - ya.test - _save_outputs: Command (pid 290836) isrss: 0
2025-11-01 16:15:29,457 - DEBUG - ya.test - _save_outputs: Command (pid 290836) ixrss: 0
2025-11-01 16:15:29,457 - DEBUG - ya.test - _save_outputs: Command (pid 290836) majflt: 0
2025-11-01 16:15:29,457 - DEBUG - ya.test - _save_outputs: Command (pid 290836) maxrss: 593652
2025-11-01 16:15:29,457 - DEBUG - ya.test - _save_outputs: Command (pid 290836) minflt: 51634
2025-11-01 16:15:29,458 - DEBUG - ya.test - _save_outputs: Command (pid 290836) msgrcv: 0
2025-11-01 16:15:29,458 - DEBUG - ya.test - _save_outputs: Command (pid 290836) msgsnd: 0
2025-11-01 16:15:29,458 - DEBUG - ya.test - _save_outputs: Command (pid 290836) nivcsw: 191
2025-11-01 16:15:29,458 - DEBUG - ya.test - _save_outputs: Command (pid 290836) nsignals: 0
2025-11-01 16:15:29,459 - DEBUG - ya.test - _save_outputs: Command (pid 290836) nswap: 0
2025-11-01 16:15:29,459 - DEBUG - ya.test - _save_outputs: Command (pid 290836) nvcsw: 495
2025-11-01 16:15:29,459 - DEBUG - ya.test - _save_outputs: Command (pid 290836) oublock: 8
2025-11-01 16:15:29,459 - DEBUG - ya.test - _save_outputs: Command (pid 290836) stime: 0.258486
2025-11-01 16:15:29,460 - DEBUG - ya.test - _save_outputs: Command (pid 290836) utime: 0.545294
2025-11-01 16:15:29,460 - DEBUG - ya.test - _save_outputs: Command (pid 290836) wtime: 1.024
2025-11-01 16:15:29,460 - DEBUG - ya.test - _save_outputs: Command (pid 290836) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 16:15:29,460 - DEBUG - ya.test - _save_outputs: Command (pid 290836) errors:

2025-11-01 16:15:29,462 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ewjv/002553/ydb/apps/ydbd/ydbd
2025-11-01 16:15:29,462 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/ewjv/002553/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18143', '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:29,465 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-01 16:15:29,465 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-01 16:15:29,466 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ewjv/002553/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18143', '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:29,471 - DEBUG - ya.test - execute: Command pid: 291199
2025-11-01 16:15:30,638 - DEBUG - ya.test - _save_outputs: Command (pid 291199) rc: 0
2025-11-01 16:15:30,638 - DEBUG - ya.test - _save_outputs: Command (pid 291199) elapsed time (sec): 1.1588292121887207
2025-11-01 16:15:30,639 - DEBUG - ya.test - _save_outputs: Command (pid 291199) idrss: 0
2025-11-01 16:15:30,639 - DEBUG - ya.test - _save_outputs: Command (pid 291199) inblock: 0
2025-11-01 16:15:30,640 - DEBUG - ya.test - _save_outputs: Command (pid 291199) isrss: 0
2025-11-01 16:15:30,640 - DEBUG - ya.test - _save_outputs: Command (pid 291199) ixrss: 0
2025-11-01 16:15:30,640 - DEBUG - ya.test - _save_outputs: Command (pid 291199) majflt: 0
2025-11-01 16:15:30,640 - DEBUG - ya.test - _save_outputs: Command (pid 291199) maxrss: 593944
2025-11-01 16:15:30,641 - DEBUG - ya.test - _save_outputs: Command (pid 291199) minflt: 51596
2025-11-01 16:15:30,641 - DEBUG - ya.test - _save_outputs: Command (pid 291199) msgrcv: 0
2025-11-01 16:15:30,641 - DEBUG - ya.test - _save_outputs: Command (pid 291199) msgsnd: 0
2025-11-01 16:15:30,642 - DEBUG - ya.test - _save_outputs: Command (pid 291199) nivcsw: 182
2025-11-01 16:15:30,642 - DEBUG - ya.test - _save_outputs: Command (pid 291199) nsignals: 0
2025-11-01 16:15:30,642 - DEBUG - ya.test - _save_outputs: Command (pid 291199) nswap: 0
2025-11-01 16:15:30,643 - DEBUG - ya.test - _save_outputs: Command (pid 291199) nvcsw: 458
2025-11-01 16:15:30,643 - DEBUG - ya.test - _save_outputs: Command (pid 291199) oublock: 8
2025-11-01 16:15:30,643 - DEBUG - ya.test - _save_outputs: Command (pid 291199) stime: 0.25920299999999996
2025-11-01 16:15:30,647 - DEBUG - ya.test - _save_outputs: Command (pid 291199) utime: 0.5717869999999999
2025-11-01 16:15:30,648 - DEBUG - ya.test - _save_outputs: Command (pid 291199) wtime: 1.165
2025-11-01 16:15:30,648 - DEBUG - ya.test - _save_outputs: Command (pid 291199) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-01 16:15:30,648 - DEBUG - ya.test - _save_outputs: Command (pid 291199) errors:

2025-11-01 16:15:30,649 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 16:15:30,827 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 16:15:30,883 - 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:30,888 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:15:30,891 - 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:30,915 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:15:30,916 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 3c99f854-32ce-4893-a850-f087bb6255c9, localhost:18143): creating call state
2025-11-01 16:15:30,917 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 3c99f854-32ce-4893-a850-f087bb6255c9, localhost:18143): request = { database: "/Root" }
2025-11-01 16:15:30,925 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 3c99f854-32ce-4893-a850-f087bb6255c9, localhost:18143): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jzwpcuvpwq.auto.internal" port: 18143 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:30,926 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jzwpcuvpwq.auto.internal:18143, location 1, ssl: False>]>
2025-11-01 16:15:30,927 - 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:30,979 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:18143
2025-11-01 16:15:30,980 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-01 16:15:30,981 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 8f493637-469c-4ab6-a100-03fa50d992b6, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:30,982 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 8f493637-469c-4ab6-a100-03fa50d992b6, ghrun-jzwpcuvpwq.auto.internal:18143): request = {  }
2025-11-01 16:15:31,000 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 8f493637-469c-4ab6-a100-03fa50d992b6, ghrun-jzwpcuvpwq.auto.internal:18143): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" node_id: 1 }
2025-11-01 16:15:31,002 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 71694bc5-800a-4d53-a560-9e0c06bcb3d2, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:31,002 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 71694bc5-800a-4d53-a560-9e0c06bcb3d2, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" }
2025-11-01 16:15:31,006 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 71694bc5-800a-4d53-a560-9e0c06bcb3d2, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:31,028 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:31,029 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 45c7b61c-407d-44ed-9995-0c14ec492e9d, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:31,030 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 45c7b61c-407d-44ed-9995-0c14ec492e9d, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:31,032 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 45c7b61c-407d-44ed-9995-0c14ec492e9d, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:35,124 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:35,125 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-01 16:15:35,126 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:35,126 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d1cf0b9d-6d0a-4288-a590-15f28eb316a9, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:35,127 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d1cf0b9d-6d0a-4288-a590-15f28eb316a9, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:35,128 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d1cf0b9d-6d0a-4288-a590-15f28eb316a9, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:36,764 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:36,764 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:36,765 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e80b040c-6ca1-4b38-8c83-0ddeb5547a3d, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:36,766 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e80b040c-6ca1-4b38-8c83-0ddeb5547a3d, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:36,767 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e80b040c-6ca1-4b38-8c83-0ddeb5547a3d, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:38,099 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:38,100 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:38,101 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 47c384bf-d41a-4272-aaa2-623f3ee436d4, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:38,102 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 47c384bf-d41a-4272-aaa2-623f3ee436d4, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:38,103 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 47c384bf-d41a-4272-aaa2-623f3ee436d4, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:39,192 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:39,193 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:39,193 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9dc69dcc-c44b-4727-a54c-e9f27cc6a75d, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:39,194 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9dc69dcc-c44b-4727-a54c-e9f27cc6a75d, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:39,199 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9dc69dcc-c44b-4727-a54c-e9f27cc6a75d, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:41,399 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:41,400 - 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:41,401 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:41,401 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c13b1bf2-b266-4cef-8bef-e06679bfca23, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:41,402 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c13b1bf2-b266-4cef-8bef-e06679bfca23, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:41,404 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c13b1bf2-b266-4cef-8bef-e06679bfca23, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:42,349 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:42,350 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:42,351 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cc0cc445-7c89-4cfe-9242-bd9dad5bbb1a, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:42,352 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cc0cc445-7c89-4cfe-9242-bd9dad5bbb1a, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:42,353 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cc0cc445-7c89-4cfe-9242-bd9dad5bbb1a, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:42,659 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:42,660 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:42,661 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 06cbf0d8-fd82-47ea-9700-3cbb814277c3, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:42,662 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 06cbf0d8-fd82-47ea-9700-3cbb814277c3, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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,664 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 06cbf0d8-fd82-47ea-9700-3cbb814277c3, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:43,233 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:43,233 - 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:43,234 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:43,234 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0a0bec72-b9f8-49e6-92b8-e23125bd047b, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:43,235 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0a0bec72-b9f8-49e6-92b8-e23125bd047b, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:43,241 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0a0bec72-b9f8-49e6-92b8-e23125bd047b, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:44,642 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:44,643 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:44,644 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d5d35dc-d672-4660-b06f-7f06af2527ea, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:44,644 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d5d35dc-d672-4660-b06f-7f06af2527ea, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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,646 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d5d35dc-d672-4660-b06f-7f06af2527ea, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:44,973 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:44,973 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:44,974 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 37fccf1c-6dbc-40b5-a1a4-65d1b707d8f9, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:44,975 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 37fccf1c-6dbc-40b5-a1a4-65d1b707d8f9, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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,976 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 37fccf1c-6dbc-40b5-a1a4-65d1b707d8f9, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:45,822 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:45,823 - 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:45,823 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:45,824 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6196714c-dea3-494c-9e25-6aed6fe17c84, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:45,825 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6196714c-dea3-494c-9e25-6aed6fe17c84, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:45,844 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6196714c-dea3-494c-9e25-6aed6fe17c84, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:47,478 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:47,479 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:47,480 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0b22d478-fd5c-47d9-9ef3-d6018b3a951f, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:47,481 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0b22d478-fd5c-47d9-9ef3-d6018b3a951f, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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,482 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0b22d478-fd5c-47d9-9ef3-d6018b3a951f, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:47,970 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:47,971 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:47,972 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 525f04bf-c681-4fe6-9837-8a52fbab4847, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:47,973 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 525f04bf-c681-4fe6-9837-8a52fbab4847, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:47,974 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 525f04bf-c681-4fe6-9837-8a52fbab4847, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:48,574 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:48,575 - 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:48,575 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:48,576 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d446a3c-7272-4eb6-9af5-76405b23d69b, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:48,577 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d446a3c-7272-4eb6-9af5-76405b23d69b, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:48,588 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d446a3c-7272-4eb6-9af5-76405b23d69b, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:49,963 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:49,964 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:49,964 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e0908af8-7f32-4a6e-a2f5-4a74ba69a265, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:49,965 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e0908af8-7f32-4a6e-a2f5-4a74ba69a265, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:49,967 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e0908af8-7f32-4a6e-a2f5-4a74ba69a265, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:50,424 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:50,425 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:50,426 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f88d0128-2c8b-46be-902a-e800dc089714, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:50,435 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f88d0128-2c8b-46be-902a-e800dc089714, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:50,437 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f88d0128-2c8b-46be-902a-e800dc089714, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:51,504 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:51,505 - 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:51,506 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:51,506 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 039bfc66-8cca-4f53-a34d-f79f462820c0, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:51,507 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 039bfc66-8cca-4f53-a34d-f79f462820c0, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:51,509 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 039bfc66-8cca-4f53-a34d-f79f462820c0, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:53,094 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:53,094 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:53,095 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ac515746-7cc7-4c3b-97d1-d211fb726417, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:53,096 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ac515746-7cc7-4c3b-97d1-d211fb726417, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:53,097 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ac515746-7cc7-4c3b-97d1-d211fb726417, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:53,716 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:53,717 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:53,718 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 14d266ec-0a87-49a6-8502-58b8ba0e6cd9, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:53,719 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 14d266ec-0a87-49a6-8502-58b8ba0e6cd9, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:53,727 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 14d266ec-0a87-49a6-8502-58b8ba0e6cd9, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:54,360 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:54,361 - 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:54,364 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:54,365 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4b294b62-37b8-4081-bf2b-0d97da8c77ec, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:54,366 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4b294b62-37b8-4081-bf2b-0d97da8c77ec, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:54,367 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4b294b62-37b8-4081-bf2b-0d97da8c77ec, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:55,652 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:55,653 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:55,654 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a562ff88-bde8-40a8-886d-d180817c5118, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:55,655 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a562ff88-bde8-40a8-886d-d180817c5118, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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,656 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a562ff88-bde8-40a8-886d-d180817c5118, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:55,927 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:55,928 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:55,928 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0ce8ce42-380f-4bf2-be22-6f5c45d052f5, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:55,929 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0ce8ce42-380f-4bf2-be22-6f5c45d052f5, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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,944 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0ce8ce42-380f-4bf2-be22-6f5c45d052f5, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:56,745 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:56,746 - 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,746 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:56,747 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 02b2f8f6-b203-4822-983b-bc963bd88ef9, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:56,748 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 02b2f8f6-b203-4822-983b-bc963bd88ef9, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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,750 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 02b2f8f6-b203-4822-983b-bc963bd88ef9, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:58,035 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:58,035 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:58,036 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 07eba32d-5121-40f2-940c-ee0fbda7e1de, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:58,037 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 07eba32d-5121-40f2-940c-ee0fbda7e1de, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:58,044 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 07eba32d-5121-40f2-940c-ee0fbda7e1de, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:58,533 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:58,533 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:58,534 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1be712e5-1aff-4bd0-aff8-c312040e1211, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:58,535 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1be712e5-1aff-4bd0-aff8-c312040e1211, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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,536 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1be712e5-1aff-4bd0-aff8-c312040e1211, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:59,427 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:59,428 - 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:59,429 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:15:59,429 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 21712f04-a211-4910-8928-0b1d90b2a57a, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:15:59,430 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 21712f04-a211-4910-8928-0b1d90b2a57a, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:59,445 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 21712f04-a211-4910-8928-0b1d90b2a57a, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:01,132 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:01,133 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:01,133 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6cc20838-85df-4e2e-9963-54f13164d0ac, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:16:01,134 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6cc20838-85df-4e2e-9963-54f13164d0ac, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:01,135 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6cc20838-85df-4e2e-9963-54f13164d0ac, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:01,488 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:01,489 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:01,490 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, da77a8cd-cb1e-46d8-b067-4c54996c6c89, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:16:01,491 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, da77a8cd-cb1e-46d8-b067-4c54996c6c89, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:01,494 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, da77a8cd-cb1e-46d8-b067-4c54996c6c89, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:02,648 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:02,649 - 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:02,650 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:02,650 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3e4e682f-3cc3-436f-ab93-1fda7205c77f, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:16:02,651 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3e4e682f-3cc3-436f-ab93-1fda7205c77f, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:02,653 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3e4e682f-3cc3-436f-ab93-1fda7205c77f, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:03,849 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:03,850 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:03,851 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 258d1ac4-7ea7-4a15-b4cc-58330e93f2b8, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:16:03,852 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 258d1ac4-7ea7-4a15-b4cc-58330e93f2b8, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:03,853 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 258d1ac4-7ea7-4a15-b4cc-58330e93f2b8, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:04,400 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:04,401 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:04,402 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bb569031-86e5-4629-a04b-1ef7ab822d8b, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:16:04,402 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bb569031-86e5-4629-a04b-1ef7ab822d8b, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:04,404 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bb569031-86e5-4629-a04b-1ef7ab822d8b, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:05,399 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:05,400 - 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:05,400 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:05,401 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d41f43aa-9287-4d12-a39c-f04aa01a6df7, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:16:05,401 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d41f43aa-9287-4d12-a39c-f04aa01a6df7, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:05,404 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d41f43aa-9287-4d12-a39c-f04aa01a6df7, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:06,962 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:06,963 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:06,964 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 502c317a-36ef-49fe-ad0d-0c5eabf2e7c6, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:16:06,965 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 502c317a-36ef-49fe-ad0d-0c5eabf2e7c6, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:06,967 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 502c317a-36ef-49fe-ad0d-0c5eabf2e7c6, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:07,253 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:07,254 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:07,257 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b4e49c1d-2705-45ca-a60c-e56277549e16, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:16:07,258 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b4e49c1d-2705-45ca-a60c-e56277549e16, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:07,260 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b4e49c1d-2705-45ca-a60c-e56277549e16, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:07,738 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:07,739 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:07,739 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b6ae1775-28f6-4162-a509-e7c26ef5e9b5, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:16:07,740 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b6ae1775-28f6-4162-a509-e7c26ef5e9b5, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:07,741 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b6ae1775-28f6-4162-a509-e7c26ef5e9b5, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:13,916 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:24,003 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:24,004 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, da3ef6a5-7aa7-40ee-9f4e-0355f1fa8ac7, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:16:24,005 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, da3ef6a5-7aa7-40ee-9f4e-0355f1fa8ac7, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:24,006 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, da3ef6a5-7aa7-40ee-9f4e-0355f1fa8ac7, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:30,882 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:30,944 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-01 16:16:30,952 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:30,952 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aded01f5-81f4-4c3d-a8fa-800504177fd6, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:16:30,953 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aded01f5-81f4-4c3d-a8fa-800504177fd6, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:30,954 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aded01f5-81f4-4c3d-a8fa-800504177fd6, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:40,459 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:50,535 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:16:50,536 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a2d87c00-b76f-4cda-801b-206a9ff21fc5, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:16:50,536 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a2d87c00-b76f-4cda-801b-206a9ff21fc5, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:50,538 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a2d87c00-b76f-4cda-801b-206a9ff21fc5, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:52,692 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:02,699 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:02,700 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2f2142f3-d85b-4fa5-b2a3-e36bc142b4c7, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:02,706 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2f2142f3-d85b-4fa5-b2a3-e36bc142b4c7, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:02,708 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2f2142f3-d85b-4fa5-b2a3-e36bc142b4c7, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:03,793 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:03,799 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-11-01 16:17:03,799 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:03,800 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1452bd6f-1e09-40ef-8fb8-296f4fba6c69, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:03,800 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1452bd6f-1e09-40ef-8fb8-296f4fba6c69, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" 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:03,802 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1452bd6f-1e09-40ef-8fb8-296f4fba6c69, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:04,179 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:04,182 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_15_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 15])
2025-11-01 16:17:04,184 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:04,185 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 85cf870b-f4b6-43c8-984b-70ed40ee21fc, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:04,185 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 85cf870b-f4b6-43c8-984b-70ed40ee21fc, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_15_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 15)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:04,204 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 85cf870b-f4b6-43c8-984b-70ed40ee21fc, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:09,987 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:09,988 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_15_compression created
2025-11-01 16:17:09,989 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:09,989 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 65353373-fdf3-4ba1-9330-45b49a7a0ef8, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:09,990 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 65353373-fdf3-4ba1-9330-45b49a7a0ef8, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_15_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:10,000 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 65353373-fdf3-4ba1-9330-45b49a7a0ef8, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:11,563 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:11,563 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:11,564 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7a622add-12c9-4681-89fe-e66769c957b6, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:11,565 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7a622add-12c9-4681-89fe-e66769c957b6, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:11,566 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7a622add-12c9-4681-89fe-e66769c957b6, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:16,945 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:16,946 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:16,947 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9e0c00fb-1a9b-4cfe-b168-173d9969fd61, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:16,947 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9e0c00fb-1a9b-4cfe-b168-173d9969fd61, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:16,949 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9e0c00fb-1a9b-4cfe-b168-173d9969fd61, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:17,842 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:17,843 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:17,843 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd496e9e-5e83-4bb8-959d-a573228b06f2, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:17,844 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd496e9e-5e83-4bb8-959d-a573228b06f2, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:17,845 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd496e9e-5e83-4bb8-959d-a573228b06f2, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:20,864 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:20,865 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318544, 'BlobCount': 128}}
2025-11-01 16:17:20,865 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:20,866 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d8e2265e-e282-4708-bd33-492ff4acacaa, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:20,867 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d8e2265e-e282-4708-bd33-492ff4acacaa, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:20,869 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d8e2265e-e282-4708-bd33-492ff4acacaa, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:24,442 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:24,443 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:24,444 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 73fe630b-3af7-45f6-9be0-11e65753a842, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:24,445 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 73fe630b-3af7-45f6-9be0-11e65753a842, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:24,449 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 73fe630b-3af7-45f6-9be0-11e65753a842, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:24,762 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:24,763 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:24,764 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a769800e-dc5f-4cae-a824-19babcad6288, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:24,765 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a769800e-dc5f-4cae-a824-19babcad6288, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:24,772 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a769800e-dc5f-4cae-a824-19babcad6288, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:25,085 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:25,086 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638064, 'BlobCount': 256}}
2025-11-01 16:17:25,086 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:25,087 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 47b24c52-f92c-4c28-98b0-dc8ae4efa683, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:25,088 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 47b24c52-f92c-4c28-98b0-dc8ae4efa683, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:25,099 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 47b24c52-f92c-4c28-98b0-dc8ae4efa683, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:29,307 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:17:29,308 - 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:17:29,337 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:17:29,338 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 115945c4-e575-4a2f-90ce-5232cbf0680f, localhost:18143): creating call state
2025-11-01 16:17:29,338 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 115945c4-e575-4a2f-90ce-5232cbf0680f, localhost:18143): request = { database: "/Root" }
2025-11-01 16:17:29,348 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 115945c4-e575-4a2f-90ce-5232cbf0680f, localhost:18143): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jzwpcuvpwq.auto.internal" port: 18143 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:17:29,349 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jzwpcuvpwq.auto.internal:18143, location 1, ssl: False>]>
2025-11-01 16:17:29,350 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:18143
2025-11-01 16:17:29,430 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:29,431 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:29,432 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9f7fe217-92ee-486d-9a8f-94e87b29171f, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:29,433 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9f7fe217-92ee-486d-9a8f-94e87b29171f, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:29,434 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9f7fe217-92ee-486d-9a8f-94e87b29171f, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:30,152 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:30,153 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:30,154 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8eddf8d2-cec8-4c39-b984-6438b3cbc7a5, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:30,155 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8eddf8d2-cec8-4c39-b984-6438b3cbc7a5, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:30,156 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8eddf8d2-cec8-4c39-b984-6438b3cbc7a5, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:30,869 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:30,870 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957088, 'BlobCount': 384}}
2025-11-01 16:17:30,915 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:30,916 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 035e5065-a6c9-42a6-b836-d27b9ec569cf, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:30,917 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 035e5065-a6c9-42a6-b836-d27b9ec569cf, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:30,919 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 035e5065-a6c9-42a6-b836-d27b9ec569cf, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:34,555 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:34,556 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:34,557 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b208c5f6-659c-4413-89ae-8e87dfb07425, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:34,557 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b208c5f6-659c-4413-89ae-8e87dfb07425, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:34,559 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b208c5f6-659c-4413-89ae-8e87dfb07425, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:35,181 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:35,182 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:35,182 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3300d34a-32f9-47ad-b548-eb32c29f7b8d, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:35,183 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3300d34a-32f9-47ad-b548-eb32c29f7b8d, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:35,185 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3300d34a-32f9-47ad-b548-eb32c29f7b8d, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:35,748 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:35,748 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276560, 'BlobCount': 512}}
2025-11-01 16:17:35,749 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:35,750 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2f16fa35-9842-4223-8e06-6852f0642ec0, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:35,750 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2f16fa35-9842-4223-8e06-6852f0642ec0, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:35,756 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2f16fa35-9842-4223-8e06-6852f0642ec0, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:40,284 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:40,285 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:40,285 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b50641c5-41f0-4c52-982b-0bf853d81fcb, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:40,286 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b50641c5-41f0-4c52-982b-0bf853d81fcb, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:40,288 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b50641c5-41f0-4c52-982b-0bf853d81fcb, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:40,792 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:40,793 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:40,794 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5e6628ff-55e1-4640-88a6-e8f4701df594, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:40,795 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5e6628ff-55e1-4640-88a6-e8f4701df594, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:40,796 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5e6628ff-55e1-4640-88a6-e8f4701df594, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:41,315 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:41,315 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595584, 'BlobCount': 640}}
2025-11-01 16:17:41,316 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:41,317 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 72a01438-f91e-4386-b603-dc5db95ac535, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:41,318 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 72a01438-f91e-4386-b603-dc5db95ac535, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:41,326 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 72a01438-f91e-4386-b603-dc5db95ac535, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:46,236 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:46,237 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:46,238 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3e7f0f09-30fe-495b-bf01-5dc610431f6d, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:46,238 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3e7f0f09-30fe-495b-bf01-5dc610431f6d, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:46,240 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3e7f0f09-30fe-495b-bf01-5dc610431f6d, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:46,723 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:46,724 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:46,725 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d1506477-f7ea-4233-905e-0a3e32bab847, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:46,726 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d1506477-f7ea-4233-905e-0a3e32bab847, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:46,729 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d1506477-f7ea-4233-905e-0a3e32bab847, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:48,195 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:48,195 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914960, 'BlobCount': 768}}
2025-11-01 16:17:48,196 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:48,197 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 46a10eb8-93ca-4b9c-9e28-41b1f8b0141e, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:48,198 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 46a10eb8-93ca-4b9c-9e28-41b1f8b0141e, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:48,211 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 46a10eb8-93ca-4b9c-9e28-41b1f8b0141e, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:52,980 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:52,981 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:52,981 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 947a905d-e551-4708-bc70-a193c112cec9, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:52,982 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 947a905d-e551-4708-bc70-a193c112cec9, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:52,984 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 947a905d-e551-4708-bc70-a193c112cec9, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:53,648 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:53,649 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:53,650 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f9e34ff9-7c8c-4da9-a7ec-3fdb88de87cb, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:53,651 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f9e34ff9-7c8c-4da9-a7ec-3fdb88de87cb, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:53,654 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f9e34ff9-7c8c-4da9-a7ec-3fdb88de87cb, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:54,901 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:54,902 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234080, 'BlobCount': 896}}
2025-11-01 16:17:54,902 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:54,903 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b6ad1cb3-9428-4561-9696-674096ef7478, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:54,904 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b6ad1cb3-9428-4561-9696-674096ef7478, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:54,915 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b6ad1cb3-9428-4561-9696-674096ef7478, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:59,845 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:59,845 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:17:59,846 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 066cc9b6-ce90-4e7d-ac68-d203a4f0e6e3, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:17:59,847 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 066cc9b6-ce90-4e7d-ac68-d203a4f0e6e3, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:59,848 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 066cc9b6-ce90-4e7d-ac68-d203a4f0e6e3, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:00,715 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:00,716 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:00,717 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e933b610-7fda-4a7f-94e5-a4399541e368, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:18:00,718 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e933b610-7fda-4a7f-94e5-a4399541e368, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:00,721 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e933b610-7fda-4a7f-94e5-a4399541e368, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:01,566 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:01,567 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553696, 'BlobCount': 1024}}
2025-11-01 16:18:01,567 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:01,568 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d41c2fc7-de1c-490b-9f89-9983e259b9f4, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:18:01,569 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d41c2fc7-de1c-490b-9f89-9983e259b9f4, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:01,570 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d41c2fc7-de1c-490b-9f89-9983e259b9f4, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:05,548 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:05,549 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:05,550 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f72a26d9-84a3-44e6-a27e-97425e22553b, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:18:05,551 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f72a26d9-84a3-44e6-a27e-97425e22553b, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:05,552 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f72a26d9-84a3-44e6-a27e-97425e22553b, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:05,863 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:05,864 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:05,865 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3385ab93-4fe8-4754-aaeb-d0d7420fd093, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:18:05,866 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3385ab93-4fe8-4754-aaeb-d0d7420fd093, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:05,872 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3385ab93-4fe8-4754-aaeb-d0d7420fd093, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:06,608 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:06,608 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872720, 'BlobCount': 1152}}
2025-11-01 16:18:06,609 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:06,609 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 69e50f07-6a14-4d11-ba13-4816e234243d, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:18:06,610 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 69e50f07-6a14-4d11-ba13-4816e234243d, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:06,612 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 69e50f07-6a14-4d11-ba13-4816e234243d, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:12,143 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:12,144 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:12,145 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ee65c2e3-d26c-41ae-a3c6-ef4c38729df0, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:18:12,146 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ee65c2e3-d26c-41ae-a3c6-ef4c38729df0, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:12,148 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ee65c2e3-d26c-41ae-a3c6-ef4c38729df0, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:12,955 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:12,956 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:12,957 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a73cf41a-e092-4789-be09-8ee31cc4a37c, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:18:12,958 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a73cf41a-e092-4789-be09-8ee31cc4a37c, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:12,960 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a73cf41a-e092-4789-be09-8ee31cc4a37c, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:15,369 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:15,370 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3192432, 'BlobCount': 1280}}
2025-11-01 16:18:15,370 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:15,371 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ab9e4f79-b69b-4e98-899d-d1ddf12d8541, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:18:15,372 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ab9e4f79-b69b-4e98-899d-d1ddf12d8541, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_15_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:15,373 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ab9e4f79-b69b-4e98-899d-d1ddf12d8541, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:17,357 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:17,358 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:17,359 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9026174e-8dca-44e9-a2de-f2cb0551f9b9, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:18:17,359 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9026174e-8dca-44e9-a2de-f2cb0551f9b9, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:17,361 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9026174e-8dca-44e9-a2de-f2cb0551f9b9, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:18,157 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:18,157 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:18,158 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a57ec2fc-7dba-44e3-8b88-8a114ca0e596, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:18:18,159 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a57ec2fc-7dba-44e3-8b88-8a114ca0e596, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:18,161 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a57ec2fc-7dba-44e3-8b88-8a114ca0e596, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:18,516 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:18,517 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:18,517 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4410835b-578d-409d-9dd2-651d3fc80f56, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:18:18,518 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4410835b-578d-409d-9dd2-651d3fc80f56, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:18,520 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4410835b-578d-409d-9dd2-651d3fc80f56, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:26,909 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:36,963 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:36,964 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d940c999-1a7b-46f2-be7f-bc1e53770d53, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:18:36,965 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d940c999-1a7b-46f2-be7f-bc1e53770d53, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:36,966 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d940c999-1a7b-46f2-be7f-bc1e53770d53, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:40,549 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:40,644 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_15_compression`, volumes `value` (8000000, 1596216)
2025-11-01 16:18:40,645 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:40,646 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb86b1ec-5699-4325-8a66-f8537b1ac227, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:18:40,646 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb86b1ec-5699-4325-8a66-f8537b1ac227, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:40,648 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb86b1ec-5699-4325-8a66-f8537b1ac227, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:41,019 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:41,020 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:41,021 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 06923b2e-2088-4fe8-9a72-723caddd43eb, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:18:41,021 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 06923b2e-2088-4fe8-9a72-723caddd43eb, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:41,030 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 06923b2e-2088-4fe8-9a72-723caddd43eb, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:44,683 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:54,718 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:54,719 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2600bbd2-6641-4cb9-8523-3ad53287d024, ghrun-jzwpcuvpwq.auto.internal:18143): creating call state
2025-11-01 16:18:54,720 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2600bbd2-6641-4cb9-8523-3ad53287d024, ghrun-jzwpcuvpwq.auto.internal:18143): request = { session_id: "ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:54,722 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2600bbd2-6641-4cb9-8523-3ad53287d024, ghrun-jzwpcuvpwq.auto.internal:18143): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:58,066 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzAyODdmODMtYjc5NmI5Yi1lYzk0NWNlMi05MWI1MGI4Mg==
2025-11-01 16:18:58,112 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_15_compression`, volumes `value` (8000000, 1596216)
2025-11-01 16:18:58,113 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_15_compression` 8092160 / 1596216: 5.069589579355175
2025-11-01 16:18:58,128 - INFO - ya.test - pytest_runtest_teardown: Test teardown
