2025-11-01 16:15:21,406 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:15:21,406 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_20_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 20]
2025-11-01 16:15:21,407 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:15:21,407 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 16:15:21,409 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-01 16:15:21,410 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-01 16:15:21,410 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ewjv/002542/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:15:21,415 - DEBUG - ya.test - execute: Command pid: 287255
2025-11-01 16:15:22,411 - DEBUG - ya.test - _save_outputs: Command (pid 287255) rc: 0
2025-11-01 16:15:22,412 - DEBUG - ya.test - _save_outputs: Command (pid 287255) elapsed time (sec): 0.9898107051849365
2025-11-01 16:15:22,412 - DEBUG - ya.test - _save_outputs: Command (pid 287255) idrss: 0
2025-11-01 16:15:22,412 - DEBUG - ya.test - _save_outputs: Command (pid 287255) inblock: 0
2025-11-01 16:15:22,413 - DEBUG - ya.test - _save_outputs: Command (pid 287255) isrss: 0
2025-11-01 16:15:22,413 - DEBUG - ya.test - _save_outputs: Command (pid 287255) ixrss: 0
2025-11-01 16:15:22,413 - DEBUG - ya.test - _save_outputs: Command (pid 287255) majflt: 0
2025-11-01 16:15:22,413 - DEBUG - ya.test - _save_outputs: Command (pid 287255) maxrss: 482444
2025-11-01 16:15:22,414 - DEBUG - ya.test - _save_outputs: Command (pid 287255) minflt: 45426
2025-11-01 16:15:22,414 - DEBUG - ya.test - _save_outputs: Command (pid 287255) msgrcv: 0
2025-11-01 16:15:22,414 - DEBUG - ya.test - _save_outputs: Command (pid 287255) msgsnd: 0
2025-11-01 16:15:22,415 - DEBUG - ya.test - _save_outputs: Command (pid 287255) nivcsw: 128
2025-11-01 16:15:22,420 - DEBUG - ya.test - _save_outputs: Command (pid 287255) nsignals: 0
2025-11-01 16:15:22,421 - DEBUG - ya.test - _save_outputs: Command (pid 287255) nswap: 0
2025-11-01 16:15:22,421 - DEBUG - ya.test - _save_outputs: Command (pid 287255) nvcsw: 14
2025-11-01 16:15:22,421 - DEBUG - ya.test - _save_outputs: Command (pid 287255) oublock: 8
2025-11-01 16:15:22,422 - DEBUG - ya.test - _save_outputs: Command (pid 287255) stime: 0.180061
2025-11-01 16:15:22,422 - DEBUG - ya.test - _save_outputs: Command (pid 287255) utime: 0.53571
2025-11-01 16:15:22,422 - DEBUG - ya.test - _save_outputs: Command (pid 287255) wtime: 0.995
2025-11-01 16:15:22,423 - DEBUG - ya.test - _save_outputs: Command (pid 287255) 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,423 - DEBUG - ya.test - _save_outputs: Command (pid 287255) errors:

2025-11-01 16:15:22,424 - 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:22,612 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ewjv/002542/ydb/apps/ydbd/ydbd
2025-11-01 16:15:22,613 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14188
2025-11-01 16:15:22,615 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/ewjv/002542/r3tmp/kikimr_cluster_5i4jvn2f
2025-11-01 16:15:22,646 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ewjv/002542/ydb/apps/ydbd/ydbd
2025-11-01 16:15:22,647 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28629
2025-11-01 16:15:22,647 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63768
2025-11-01 16:15:22,647 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29912
2025-11-01 16:15:22,648 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29601
2025-11-01 16:15:22,648 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ewjv/002542/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/kikimr_configs"
2025-11-01 16:15:22,649 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/ewjv/002542/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/002542/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/node_1/logfile_mrepnd2b.log --grpc-port=28629 --mon-port=63768 --ic-port=14188
2025-11-01 16:15:22,649 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:28629/1, disk_size 68719476736
2025-11-01 16:15:22,649 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ewjv/002542/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/kikimr_configs"
2025-11-01 16:15:22,649 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/ewjv/002542/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/002542/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/node_1/logfile_mrepnd2b.log --grpc-port=28629 --mon-port=63768 --ic-port=14188
2025-11-01 16:15:22,650 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/ewjv/002542/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/ewjv/002542/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/ewjv/002542/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/node_1/logfile_mrepnd2b.log', '--grpc-port=28629', '--mon-port=63768', '--ic-port=14188')' in '/home/runner/.ya/build/build_root/ewjv/002542/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/node_1' (no wait)
2025-11-01 16:15:22,654 - DEBUG - ya.test - execute: Command pid: 287974
2025-11-01 16:15:22,654 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:28629/1
2025-11-01 16:15:22,656 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-01 16:15:22,657 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-01 16:15:22,657 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ewjv/002542/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:15:22,658 - DEBUG - ya.test - execute: Command pid: 287975
2025-11-01 16:15:23,687 - DEBUG - ya.test - _save_outputs: Command (pid 287975) rc: 0
2025-11-01 16:15:23,688 - DEBUG - ya.test - _save_outputs: Command (pid 287975) elapsed time (sec): 1.0280041694641113
2025-11-01 16:15:23,688 - DEBUG - ya.test - _save_outputs: Command (pid 287975) idrss: 0
2025-11-01 16:15:23,688 - DEBUG - ya.test - _save_outputs: Command (pid 287975) inblock: 0
2025-11-01 16:15:23,689 - DEBUG - ya.test - _save_outputs: Command (pid 287975) isrss: 0
2025-11-01 16:15:23,689 - DEBUG - ya.test - _save_outputs: Command (pid 287975) ixrss: 0
2025-11-01 16:15:23,689 - DEBUG - ya.test - _save_outputs: Command (pid 287975) majflt: 0
2025-11-01 16:15:23,689 - DEBUG - ya.test - _save_outputs: Command (pid 287975) maxrss: 484232
2025-11-01 16:15:23,690 - DEBUG - ya.test - _save_outputs: Command (pid 287975) minflt: 45353
2025-11-01 16:15:23,690 - DEBUG - ya.test - _save_outputs: Command (pid 287975) msgrcv: 0
2025-11-01 16:15:23,690 - DEBUG - ya.test - _save_outputs: Command (pid 287975) msgsnd: 0
2025-11-01 16:15:23,690 - DEBUG - ya.test - _save_outputs: Command (pid 287975) nivcsw: 136
2025-11-01 16:15:23,691 - DEBUG - ya.test - _save_outputs: Command (pid 287975) nsignals: 0
2025-11-01 16:15:23,700 - DEBUG - ya.test - _save_outputs: Command (pid 287975) nswap: 0
2025-11-01 16:15:23,700 - DEBUG - ya.test - _save_outputs: Command (pid 287975) nvcsw: 14
2025-11-01 16:15:23,701 - DEBUG - ya.test - _save_outputs: Command (pid 287975) oublock: 8
2025-11-01 16:15:23,701 - DEBUG - ya.test - _save_outputs: Command (pid 287975) stime: 0.22049
2025-11-01 16:15:23,701 - DEBUG - ya.test - _save_outputs: Command (pid 287975) utime: 0.5044799999999999
2025-11-01 16:15:23,701 - DEBUG - ya.test - _save_outputs: Command (pid 287975) wtime: 1.03
2025-11-01 16:15:23,702 - DEBUG - ya.test - _save_outputs: Command (pid 287975) 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:23,702 - DEBUG - ya.test - _save_outputs: Command (pid 287975) errors:

2025-11-01 16:15:23,703 - 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:29,630 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ewjv/002542/ydb/apps/ydbd/ydbd
2025-11-01 16:15:29,631 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/ewjv/002542/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:28629', '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: 14188\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 16:15:29,632 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-01 16:15:29,633 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-01 16:15:29,633 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ewjv/002542/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:28629', '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: 14188\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 16:15:29,642 - DEBUG - ya.test - execute: Command pid: 291214
2025-11-01 16:15:31,201 - DEBUG - ya.test - _save_outputs: Command (pid 291214) rc: 0
2025-11-01 16:15:31,201 - DEBUG - ya.test - _save_outputs: Command (pid 291214) elapsed time (sec): 1.5561378002166748
2025-11-01 16:15:31,202 - DEBUG - ya.test - _save_outputs: Command (pid 291214) idrss: 0
2025-11-01 16:15:31,202 - DEBUG - ya.test - _save_outputs: Command (pid 291214) inblock: 0
2025-11-01 16:15:31,202 - DEBUG - ya.test - _save_outputs: Command (pid 291214) isrss: 0
2025-11-01 16:15:31,202 - DEBUG - ya.test - _save_outputs: Command (pid 291214) ixrss: 0
2025-11-01 16:15:31,203 - DEBUG - ya.test - _save_outputs: Command (pid 291214) majflt: 0
2025-11-01 16:15:31,203 - DEBUG - ya.test - _save_outputs: Command (pid 291214) maxrss: 589816
2025-11-01 16:15:31,203 - DEBUG - ya.test - _save_outputs: Command (pid 291214) minflt: 51641
2025-11-01 16:15:31,203 - DEBUG - ya.test - _save_outputs: Command (pid 291214) msgrcv: 0
2025-11-01 16:15:31,204 - DEBUG - ya.test - _save_outputs: Command (pid 291214) msgsnd: 0
2025-11-01 16:15:31,204 - DEBUG - ya.test - _save_outputs: Command (pid 291214) nivcsw: 209
2025-11-01 16:15:31,204 - DEBUG - ya.test - _save_outputs: Command (pid 291214) nsignals: 0
2025-11-01 16:15:31,204 - DEBUG - ya.test - _save_outputs: Command (pid 291214) nswap: 0
2025-11-01 16:15:31,204 - DEBUG - ya.test - _save_outputs: Command (pid 291214) nvcsw: 471
2025-11-01 16:15:31,205 - DEBUG - ya.test - _save_outputs: Command (pid 291214) oublock: 8
2025-11-01 16:15:31,205 - DEBUG - ya.test - _save_outputs: Command (pid 291214) stime: 0.303579
2025-11-01 16:15:31,205 - DEBUG - ya.test - _save_outputs: Command (pid 291214) utime: 0.5734319999999999
2025-11-01 16:15:31,205 - DEBUG - ya.test - _save_outputs: Command (pid 291214) wtime: 1.565
2025-11-01 16:15:31,206 - DEBUG - ya.test - _save_outputs: Command (pid 291214) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 16:15:31,206 - DEBUG - ya.test - _save_outputs: Command (pid 291214) errors:

2025-11-01 16:15:31,214 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ewjv/002542/ydb/apps/ydbd/ydbd
2025-11-01 16:15:31,214 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/ewjv/002542/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:28629', '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:31,216 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-01 16:15:31,216 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-01 16:15:31,217 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ewjv/002542/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:28629', '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:31,231 - DEBUG - ya.test - execute: Command pid: 292171
2025-11-01 16:15:32,630 - DEBUG - ya.test - _save_outputs: Command (pid 292171) rc: 0
2025-11-01 16:15:32,630 - DEBUG - ya.test - _save_outputs: Command (pid 292171) elapsed time (sec): 1.3976385593414307
2025-11-01 16:15:32,631 - DEBUG - ya.test - _save_outputs: Command (pid 292171) idrss: 0
2025-11-01 16:15:32,631 - DEBUG - ya.test - _save_outputs: Command (pid 292171) inblock: 0
2025-11-01 16:15:32,631 - DEBUG - ya.test - _save_outputs: Command (pid 292171) isrss: 0
2025-11-01 16:15:32,631 - DEBUG - ya.test - _save_outputs: Command (pid 292171) ixrss: 0
2025-11-01 16:15:32,632 - DEBUG - ya.test - _save_outputs: Command (pid 292171) majflt: 0
2025-11-01 16:15:32,632 - DEBUG - ya.test - _save_outputs: Command (pid 292171) maxrss: 589816
2025-11-01 16:15:32,632 - DEBUG - ya.test - _save_outputs: Command (pid 292171) minflt: 51626
2025-11-01 16:15:32,632 - DEBUG - ya.test - _save_outputs: Command (pid 292171) msgrcv: 0
2025-11-01 16:15:32,632 - DEBUG - ya.test - _save_outputs: Command (pid 292171) msgsnd: 0
2025-11-01 16:15:32,633 - DEBUG - ya.test - _save_outputs: Command (pid 292171) nivcsw: 235
2025-11-01 16:15:32,633 - DEBUG - ya.test - _save_outputs: Command (pid 292171) nsignals: 0
2025-11-01 16:15:32,633 - DEBUG - ya.test - _save_outputs: Command (pid 292171) nswap: 0
2025-11-01 16:15:32,633 - DEBUG - ya.test - _save_outputs: Command (pid 292171) nvcsw: 670
2025-11-01 16:15:32,634 - DEBUG - ya.test - _save_outputs: Command (pid 292171) oublock: 8
2025-11-01 16:15:32,634 - DEBUG - ya.test - _save_outputs: Command (pid 292171) stime: 0.274594
2025-11-01 16:15:32,634 - DEBUG - ya.test - _save_outputs: Command (pid 292171) utime: 0.615746
2025-11-01 16:15:32,634 - DEBUG - ya.test - _save_outputs: Command (pid 292171) wtime: 1.412
2025-11-01 16:15:32,635 - DEBUG - ya.test - _save_outputs: Command (pid 292171) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-01 16:15:32,635 - DEBUG - ya.test - _save_outputs: Command (pid 292171) errors:

2025-11-01 16:15:32,636 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 16:15:32,816 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 16:15:32,864 - 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:32,880 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:15:32,880 - 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:32,929 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:15:32,930 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 15ee56a1-c5e5-41f1-b5d2-2c647bb47d4a, localhost:28629): creating call state
2025-11-01 16:15:32,931 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 15ee56a1-c5e5-41f1-b5d2-2c647bb47d4a, localhost:28629): request = { database: "/Root" }
2025-11-01 16:15:33,097 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 15ee56a1-c5e5-41f1-b5d2-2c647bb47d4a, localhost:28629): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jzwpcuvpwq.auto.internal" port: 28629 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:33,098 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jzwpcuvpwq.auto.internal:28629, location 1, ssl: False>]>
2025-11-01 16:15:33,099 - 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:33,131 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:28629
2025-11-01 16:15:33,139 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-01 16:15:33,140 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a48cdf4a-d375-4e7c-b605-69c346775a9a, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:33,141 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a48cdf4a-d375-4e7c-b605-69c346775a9a, ghrun-jzwpcuvpwq.auto.internal:28629): request = {  }
2025-11-01 16:15:33,148 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a48cdf4a-d375-4e7c-b605-69c346775a9a, ghrun-jzwpcuvpwq.auto.internal:28629): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" node_id: 1 }
2025-11-01 16:15:33,149 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 58ac1b15-0595-497a-88b3-b10bd2532090, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:33,149 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 58ac1b15-0595-497a-88b3-b10bd2532090, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" }
2025-11-01 16:15:33,152 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 58ac1b15-0595-497a-88b3-b10bd2532090, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:33,176 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:33,177 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, da2a941b-0005-4eb4-92d5-06aa803b5a7b, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:33,178 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, da2a941b-0005-4eb4-92d5-06aa803b5a7b, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:33,179 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, da2a941b-0005-4eb4-92d5-06aa803b5a7b, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:37,509 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:37,510 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-01 16:15:37,510 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:37,511 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4bd55fbf-7bbf-4b1b-832b-417f03520bb4, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:37,512 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4bd55fbf-7bbf-4b1b-832b-417f03520bb4, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:37,514 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4bd55fbf-7bbf-4b1b-832b-417f03520bb4, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:38,688 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:38,689 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:38,689 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ee995c35-0e52-47f4-b0af-5f6d415bea37, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:38,690 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ee995c35-0e52-47f4-b0af-5f6d415bea37, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:38,692 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ee995c35-0e52-47f4-b0af-5f6d415bea37, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:40,085 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:40,086 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:40,086 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 55c1a9bb-37c1-4e6b-b72c-56336d30167d, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:40,087 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 55c1a9bb-37c1-4e6b-b72c-56336d30167d, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:15:40,088 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 55c1a9bb-37c1-4e6b-b72c-56336d30167d, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:41,005 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:41,006 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:41,007 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c6be331e-11e2-47e0-9d4e-b37c8513edb2, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:41,008 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c6be331e-11e2-47e0-9d4e-b37c8513edb2, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:41,027 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c6be331e-11e2-47e0-9d4e-b37c8513edb2, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:42,870 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:42,871 - 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:42,871 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:42,872 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 31fc9567-9056-410f-b270-05fe08ad1b37, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:42,873 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 31fc9567-9056-410f-b270-05fe08ad1b37, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:42,875 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 31fc9567-9056-410f-b270-05fe08ad1b37, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:44,153 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:44,154 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:44,154 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4feb43ee-e5ea-4aee-81a4-e695f8c5431b, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:44,155 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4feb43ee-e5ea-4aee-81a4-e695f8c5431b, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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,156 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4feb43ee-e5ea-4aee-81a4-e695f8c5431b, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:44,499 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:44,500 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:44,501 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, adf2a1f5-b2ee-4b7e-931a-16c87f8523b8, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:44,502 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, adf2a1f5-b2ee-4b7e-931a-16c87f8523b8, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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,503 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, adf2a1f5-b2ee-4b7e-931a-16c87f8523b8, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:44,895 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:44,896 - 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:44,897 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:44,897 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5547f848-12bf-4168-acf7-1fb35cd8041f, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:44,898 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5547f848-12bf-4168-acf7-1fb35cd8041f, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:44,900 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5547f848-12bf-4168-acf7-1fb35cd8041f, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:46,129 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:46,130 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:46,131 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e91490cc-0736-4c72-8f84-ccc2af4ae934, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:46,132 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e91490cc-0736-4c72-8f84-ccc2af4ae934, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:46,134 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e91490cc-0736-4c72-8f84-ccc2af4ae934, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:46,628 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:46,629 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:46,631 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 09be4dc6-c407-4704-87d8-4a9483f220d0, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:46,632 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 09be4dc6-c407-4704-87d8-4a9483f220d0, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:46,635 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 09be4dc6-c407-4704-87d8-4a9483f220d0, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:47,030 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:47,032 - 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:47,032 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:47,033 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c8b11d96-35af-47fd-b03d-b38a871b32bd, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:47,034 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c8b11d96-35af-47fd-b03d-b38a871b32bd, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:47,038 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c8b11d96-35af-47fd-b03d-b38a871b32bd, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:48,575 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:48,576 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:48,577 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8015d141-e814-4f42-bd9f-4162b0c74e94, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:48,578 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8015d141-e814-4f42-bd9f-4162b0c74e94, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:48,579 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8015d141-e814-4f42-bd9f-4162b0c74e94, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:49,089 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:49,090 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:49,090 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a27075dc-0a53-4c35-b2b7-da748dbead29, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:49,091 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a27075dc-0a53-4c35-b2b7-da748dbead29, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:49,093 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a27075dc-0a53-4c35-b2b7-da748dbead29, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:49,549 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:49,550 - 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:49,550 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:49,551 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3714de1d-c515-47df-b0fc-7f3b817164d4, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:49,552 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3714de1d-c515-47df-b0fc-7f3b817164d4, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:49,555 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3714de1d-c515-47df-b0fc-7f3b817164d4, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:51,129 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:51,130 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:51,130 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a43bde52-200f-4ac8-b26e-f768d195f701, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:51,131 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a43bde52-200f-4ac8-b26e-f768d195f701, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:15:51,132 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a43bde52-200f-4ac8-b26e-f768d195f701, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:51,628 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:51,629 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:51,630 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e0a1255f-0477-47d6-be3a-96a7263f9c60, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:51,631 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e0a1255f-0477-47d6-be3a-96a7263f9c60, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:51,632 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e0a1255f-0477-47d6-be3a-96a7263f9c60, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:52,278 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:52,279 - 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:52,280 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:52,281 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9fa24eb4-ebf5-4dab-be39-25394e7f29d0, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:52,281 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9fa24eb4-ebf5-4dab-be39-25394e7f29d0, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:52,283 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9fa24eb4-ebf5-4dab-be39-25394e7f29d0, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:53,940 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:53,941 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:53,942 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2f64402d-0c77-4aca-9d74-22f6bfed0c8c, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:53,943 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2f64402d-0c77-4aca-9d74-22f6bfed0c8c, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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,944 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2f64402d-0c77-4aca-9d74-22f6bfed0c8c, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:54,750 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:54,751 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:54,751 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, de25344c-cb07-4676-b8ed-e83a04124b82, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:54,752 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, de25344c-cb07-4676-b8ed-e83a04124b82, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:54,754 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, de25344c-cb07-4676-b8ed-e83a04124b82, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:55,661 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:55,661 - 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:55,662 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:55,662 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 463b569e-6615-4d5b-a90a-96ace54b9bf1, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:55,663 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 463b569e-6615-4d5b-a90a-96ace54b9bf1, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:55,671 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 463b569e-6615-4d5b-a90a-96ace54b9bf1, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:57,020 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:57,021 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:57,022 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 82a0ec43-d99e-45a3-a90c-bcfe3178bf02, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:57,023 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 82a0ec43-d99e-45a3-a90c-bcfe3178bf02, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:15:57,024 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 82a0ec43-d99e-45a3-a90c-bcfe3178bf02, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:57,622 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:57,622 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:57,623 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0c2b611a-f42c-4734-bd60-bea9a332e2f0, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:57,624 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0c2b611a-f42c-4734-bd60-bea9a332e2f0, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:57,625 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0c2b611a-f42c-4734-bd60-bea9a332e2f0, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:58,503 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:58,504 - 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:58,505 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:58,505 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e571ae9d-dd8a-4001-af36-81f2aad21003, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:58,506 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e571ae9d-dd8a-4001-af36-81f2aad21003, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:58,511 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e571ae9d-dd8a-4001-af36-81f2aad21003, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:59,896 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:59,897 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:15:59,897 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7cfd5dd6-092b-470c-bf7f-4bbfac686702, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:15:59,898 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7cfd5dd6-092b-470c-bf7f-4bbfac686702, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:59,899 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7cfd5dd6-092b-470c-bf7f-4bbfac686702, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:00,459 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:00,460 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:00,460 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2d269c76-73a4-4b90-a872-87f8de8e03e5, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:16:00,461 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2d269c76-73a4-4b90-a872-87f8de8e03e5, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:16:00,463 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2d269c76-73a4-4b90-a872-87f8de8e03e5, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:01,391 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:01,392 - 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:16:01,393 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:01,393 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9576a988-b270-44b6-a1dd-a758b3a8d0fc, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:16:01,394 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9576a988-b270-44b6-a1dd-a758b3a8d0fc, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:16:01,395 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9576a988-b270-44b6-a1dd-a758b3a8d0fc, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:02,471 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:02,472 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:02,472 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e5174d13-8393-4136-b6f9-9c82ac046d95, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:16:02,473 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e5174d13-8393-4136-b6f9-9c82ac046d95, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:16:02,475 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e5174d13-8393-4136-b6f9-9c82ac046d95, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:02,856 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:02,857 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:02,858 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0c9d3864-4c2d-49bb-a64d-e9bddfe75bde, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:16:02,859 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0c9d3864-4c2d-49bb-a64d-e9bddfe75bde, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:16:02,860 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0c9d3864-4c2d-49bb-a64d-e9bddfe75bde, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:03,609 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:03,609 - 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:03,610 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:03,610 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 63cd79b1-01c7-4243-8139-486672e517ce, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:16:03,611 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 63cd79b1-01c7-4243-8139-486672e517ce, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:03,618 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 63cd79b1-01c7-4243-8139-486672e517ce, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:04,772 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:04,773 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:04,774 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 63578883-e5d8-4654-9887-85cf768cfde4, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:16:04,775 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 63578883-e5d8-4654-9887-85cf768cfde4, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:04,776 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 63578883-e5d8-4654-9887-85cf768cfde4, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:05,078 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:05,079 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:05,079 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eef44093-eec1-4459-aa79-016c951a4a74, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:16:05,080 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eef44093-eec1-4459-aa79-016c951a4a74, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:05,082 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eef44093-eec1-4459-aa79-016c951a4a74, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:06,112 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:06,112 - 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:06,113 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:06,113 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, af045d12-9ae5-420b-9a34-e39015af8582, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:16:06,114 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, af045d12-9ae5-420b-9a34-e39015af8582, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:06,115 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, af045d12-9ae5-420b-9a34-e39015af8582, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:07,381 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:07,382 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:07,383 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 64903621-322a-4e91-ba59-f190db1ac12c, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:16:07,384 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 64903621-322a-4e91-ba59-f190db1ac12c, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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,385 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 64903621-322a-4e91-ba59-f190db1ac12c, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:07,752 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:07,753 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:07,753 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dda40672-6d96-46a8-bc32-e9f3650491f2, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:16:07,754 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dda40672-6d96-46a8-bc32-e9f3650491f2, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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,760 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dda40672-6d96-46a8-bc32-e9f3650491f2, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:08,160 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:08,161 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:08,162 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7738458a-417c-4cdb-a46d-b3f538ca218d, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:16:08,163 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7738458a-417c-4cdb-a46d-b3f538ca218d, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:08,164 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7738458a-417c-4cdb-a46d-b3f538ca218d, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:13,746 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:23,783 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:23,784 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5b73904b-1ac1-458c-8db8-4c11994cf366, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:16:23,785 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5b73904b-1ac1-458c-8db8-4c11994cf366, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:16:23,786 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5b73904b-1ac1-458c-8db8-4c11994cf366, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:29,848 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:29,939 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-01 16:16:29,939 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:29,940 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1df696ff-5be9-4ae7-bc8e-96050a6fd00b, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:16:29,941 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1df696ff-5be9-4ae7-bc8e-96050a6fd00b, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:29,943 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1df696ff-5be9-4ae7-bc8e-96050a6fd00b, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:36,197 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:46,235 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:46,236 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 745b4113-1a29-447d-8208-c141840b4d70, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:16:46,237 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 745b4113-1a29-447d-8208-c141840b4d70, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:46,238 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 745b4113-1a29-447d-8208-c141840b4d70, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:48,898 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:59,007 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:16:59,008 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 66c37220-7d19-478b-aad4-48d89108907f, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:16:59,009 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 66c37220-7d19-478b-aad4-48d89108907f, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:59,010 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 66c37220-7d19-478b-aad4-48d89108907f, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:01,170 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:07,967 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:17:07,968 - 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:08,024 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:17:08,025 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, c5075293-e460-4841-b0fe-1b764db296cd, localhost:28629): creating call state
2025-11-01 16:17:08,026 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, c5075293-e460-4841-b0fe-1b764db296cd, localhost:28629): request = { database: "/Root" }
2025-11-01 16:17:08,031 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, c5075293-e460-4841-b0fe-1b764db296cd, localhost:28629): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jzwpcuvpwq.auto.internal" port: 28629 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:08,032 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jzwpcuvpwq.auto.internal:28629, location 1, ssl: False>]>
2025-11-01 16:17:08,033 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:28629
2025-11-01 16:17:11,175 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:11,176 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 856482a6-36b0-4a26-a1ff-25bd0b0554f6, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:11,177 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 856482a6-36b0-4a26-a1ff-25bd0b0554f6, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:11,178 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 856482a6-36b0-4a26-a1ff-25bd0b0554f6, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:12,764 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:12,768 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-11-01 16:17:12,770 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:12,770 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 80a9c979-466f-4e3a-a40e-ce2b672a1983, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:12,771 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 80a9c979-466f-4e3a-a40e-ce2b672a1983, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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:12,778 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 80a9c979-466f-4e3a-a40e-ce2b672a1983, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:13,147 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:13,150 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_20_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 20])
2025-11-01 16:17:13,150 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:13,152 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7edf0a75-86f7-4a37-8989-8ccb2492580a, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:13,153 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7edf0a75-86f7-4a37-8989-8ccb2492580a, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_20_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 20)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:13,154 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7edf0a75-86f7-4a37-8989-8ccb2492580a, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:17,379 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:17,380 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_20_compression created
2025-11-01 16:17:17,381 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:17,381 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b6132e4f-6e21-4cb1-bb7f-e0eb8c01dd56, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:17,382 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b6132e4f-6e21-4cb1-bb7f-e0eb8c01dd56, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_20_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:17,396 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b6132e4f-6e21-4cb1-bb7f-e0eb8c01dd56, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:18,583 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:18,585 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:18,586 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b4fc0700-8b01-48bc-a37f-906153adbd53, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:18,586 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b4fc0700-8b01-48bc-a37f-906153adbd53, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:18,588 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b4fc0700-8b01-48bc-a37f-906153adbd53, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:25,384 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:25,385 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:25,385 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a22b7f75-8910-48e7-9f4d-fa7298e3474e, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:25,386 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a22b7f75-8910-48e7-9f4d-fa7298e3474e, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:25,387 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a22b7f75-8910-48e7-9f4d-fa7298e3474e, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:26,702 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:26,702 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:26,703 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2272971e-ea49-4af0-9caa-5eb1876c0709, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:26,704 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2272971e-ea49-4af0-9caa-5eb1876c0709, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_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:26,705 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2272971e-ea49-4af0-9caa-5eb1876c0709, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:29,179 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:29,180 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-11-01 16:17:29,181 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:29,181 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3bf6a361-3fd7-4afe-8f6a-bfd8dd0fddfb, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:29,182 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3bf6a361-3fd7-4afe-8f6a-bfd8dd0fddfb, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:29,184 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3bf6a361-3fd7-4afe-8f6a-bfd8dd0fddfb, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:34,761 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:34,762 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:34,762 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 264e4a04-3d9e-45e3-adf0-c55ec9acd888, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:34,763 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 264e4a04-3d9e-45e3-adf0-c55ec9acd888, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:34,766 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 264e4a04-3d9e-45e3-adf0-c55ec9acd888, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:34,997 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:34,998 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:34,998 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b77db281-d1e0-4a9b-b498-9e8df7eae5f4, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:34,999 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b77db281-d1e0-4a9b-b498-9e8df7eae5f4, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_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,001 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b77db281-d1e0-4a9b-b498-9e8df7eae5f4, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:35,396 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:35,396 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-11-01 16:17:35,397 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:35,398 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 75d71ee8-b268-4b94-9183-5d9b4ce883f1, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:35,399 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 75d71ee8-b268-4b94-9183-5d9b4ce883f1, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:35,400 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 75d71ee8-b268-4b94-9183-5d9b4ce883f1, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:40,759 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:40,760 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:40,761 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 787c040e-dc57-46bf-9b43-2dbc73690cd7, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:40,762 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 787c040e-dc57-46bf-9b43-2dbc73690cd7, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:40,764 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 787c040e-dc57-46bf-9b43-2dbc73690cd7, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:41,295 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:41,296 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:41,297 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 64bfbfbd-f731-4e34-aefa-8f186e35faeb, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:41,298 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 64bfbfbd-f731-4e34-aefa-8f186e35faeb, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_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:41,300 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 64bfbfbd-f731-4e34-aefa-8f186e35faeb, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:41,722 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:41,723 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-11-01 16:17:41,724 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:41,724 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 96c90325-61f2-43c0-a170-0498894682d6, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:41,725 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 96c90325-61f2-43c0-a170-0498894682d6, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:41,728 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 96c90325-61f2-43c0-a170-0498894682d6, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:47,972 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:47,973 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:47,974 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 03ebc8a7-9384-43e9-89b2-33c732d256b2, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:47,975 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 03ebc8a7-9384-43e9-89b2-33c732d256b2, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:47,980 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 03ebc8a7-9384-43e9-89b2-33c732d256b2, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:48,479 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:48,480 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:48,481 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7527cea0-71da-4e01-8d7b-88e32db0b0e2, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:48,482 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7527cea0-71da-4e01-8d7b-88e32db0b0e2, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:48,508 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7527cea0-71da-4e01-8d7b-88e32db0b0e2, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:49,384 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:49,385 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-11-01 16:17:49,386 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:49,386 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ac2ab7d3-1071-4dd6-93f1-c4cf7ff7d9e3, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:49,387 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ac2ab7d3-1071-4dd6-93f1-c4cf7ff7d9e3, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:49,392 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ac2ab7d3-1071-4dd6-93f1-c4cf7ff7d9e3, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:57,031 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:57,032 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:57,033 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1ba16ec8-c0ed-4fe8-b7b9-4b4478cb758c, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:57,034 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1ba16ec8-c0ed-4fe8-b7b9-4b4478cb758c, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:57,040 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1ba16ec8-c0ed-4fe8-b7b9-4b4478cb758c, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:57,545 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:57,546 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:57,547 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb741b16-0c80-430c-a4fe-fc0ca9529cbe, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:57,547 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb741b16-0c80-430c-a4fe-fc0ca9529cbe, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_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:57,549 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb741b16-0c80-430c-a4fe-fc0ca9529cbe, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:58,226 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:58,226 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-11-01 16:17:58,227 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:17:58,228 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, adb4053b-9f9e-4d5a-8cbf-8d307bd71625, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:17:58,229 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, adb4053b-9f9e-4d5a-8cbf-8d307bd71625, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:58,230 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, adb4053b-9f9e-4d5a-8cbf-8d307bd71625, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:05,887 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:05,888 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:05,889 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fa4a13f7-3a8a-47d5-9507-0f4f342d7776, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:05,889 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fa4a13f7-3a8a-47d5-9507-0f4f342d7776, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:05,899 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fa4a13f7-3a8a-47d5-9507-0f4f342d7776, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:06,510 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:06,511 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:06,512 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a9218ec7-09ba-49c2-94de-132cdf3be127, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:06,513 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a9218ec7-09ba-49c2-94de-132cdf3be127, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_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:06,516 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a9218ec7-09ba-49c2-94de-132cdf3be127, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:07,437 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:07,438 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-11-01 16:18:07,438 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:07,439 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 65d4d314-af44-442a-b9e2-ff21f2c18871, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:07,440 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 65d4d314-af44-442a-b9e2-ff21f2c18871, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:07,442 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 65d4d314-af44-442a-b9e2-ff21f2c18871, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:14,156 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:14,157 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:14,158 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b2bc93a4-8664-4c69-b49c-71e353c34f00, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:14,159 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b2bc93a4-8664-4c69-b49c-71e353c34f00, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:14,160 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b2bc93a4-8664-4c69-b49c-71e353c34f00, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:14,531 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:14,532 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:14,533 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 819d484c-db22-4d80-b988-d8bc3557f95a, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:14,534 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 819d484c-db22-4d80-b988-d8bc3557f95a, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_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:14,536 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 819d484c-db22-4d80-b988-d8bc3557f95a, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:15,483 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:15,484 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-11-01 16:18:15,484 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:15,485 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5fcb4cc9-4318-4259-b899-191f467e07dc, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:15,486 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5fcb4cc9-4318-4259-b899-191f467e07dc, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:15,489 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5fcb4cc9-4318-4259-b899-191f467e07dc, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:24,322 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:24,323 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:24,323 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0d7e9050-70ae-452c-8766-026eed284fdf, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:24,324 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0d7e9050-70ae-452c-8766-026eed284fdf, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:24,333 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0d7e9050-70ae-452c-8766-026eed284fdf, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:24,804 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:24,805 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:24,805 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b2281f10-cf10-4991-9023-780079f781c7, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:24,806 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b2281f10-cf10-4991-9023-780079f781c7, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_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:24,808 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b2281f10-cf10-4991-9023-780079f781c7, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:25,610 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:25,611 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-11-01 16:18:25,611 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:25,612 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1360c906-35ec-4291-a76e-7967d50cbf6f, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:25,613 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1360c906-35ec-4291-a76e-7967d50cbf6f, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:25,615 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1360c906-35ec-4291-a76e-7967d50cbf6f, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:31,137 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:31,138 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:31,138 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 52043ed8-a743-49d5-8f43-3d2461c103c9, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:31,139 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 52043ed8-a743-49d5-8f43-3d2461c103c9, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:31,141 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 52043ed8-a743-49d5-8f43-3d2461c103c9, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:31,493 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:31,493 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:31,494 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7f9815ba-a3ff-43b9-8de2-65593e8f4c00, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:31,495 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7f9815ba-a3ff-43b9-8de2-65593e8f4c00, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_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:31,497 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7f9815ba-a3ff-43b9-8de2-65593e8f4c00, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:32,417 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:32,418 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-11-01 16:18:32,418 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:32,419 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4ad5767c-2b34-498d-8ada-6bbe2fbab798, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:32,420 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4ad5767c-2b34-498d-8ada-6bbe2fbab798, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:32,422 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4ad5767c-2b34-498d-8ada-6bbe2fbab798, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:37,859 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:37,860 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:37,861 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8135f055-a69d-461f-b9e3-a3bf36f189cc, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:37,862 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8135f055-a69d-461f-b9e3-a3bf36f189cc, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:37,864 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8135f055-a69d-461f-b9e3-a3bf36f189cc, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:38,222 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:38,222 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:38,223 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8465d336-72c0-424a-8a05-3eb4dde6f617, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:38,224 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8465d336-72c0-424a-8a05-3eb4dde6f617, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_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:38,226 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8465d336-72c0-424a-8a05-3eb4dde6f617, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:38,824 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:38,825 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-11-01 16:18:38,826 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:38,826 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 52121764-ba21-4b64-8807-5f43ea39c2e1, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:38,827 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 52121764-ba21-4b64-8807-5f43ea39c2e1, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_20_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:38,829 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 52121764-ba21-4b64-8807-5f43ea39c2e1, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:39,982 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:39,983 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:39,983 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, db2a234d-e6e1-49ad-b32d-1da0df775e35, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:39,984 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, db2a234d-e6e1-49ad-b32d-1da0df775e35, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:39,992 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, db2a234d-e6e1-49ad-b32d-1da0df775e35, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:40,241 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:40,242 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:40,243 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, def7817d-ef7b-4505-8392-7f636b8883bc, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:40,244 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, def7817d-ef7b-4505-8392-7f636b8883bc, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:40,247 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, def7817d-ef7b-4505-8392-7f636b8883bc, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:40,588 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:40,588 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:40,589 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 869804c5-f033-4c12-a5fd-34cebf7a65e9, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:40,589 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 869804c5-f033-4c12-a5fd-34cebf7a65e9, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:40,590 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 869804c5-f033-4c12-a5fd-34cebf7a65e9, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:45,071 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:51,439 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:18:51,444 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:18:51,456 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:18:51,457 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 02e86b5d-5e54-4c12-ba31-0be776914afa, localhost:28629): creating call state
2025-11-01 16:18:51,458 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 02e86b5d-5e54-4c12-ba31-0be776914afa, localhost:28629): request = { database: "/Root" }
2025-11-01 16:18:51,471 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 02e86b5d-5e54-4c12-ba31-0be776914afa, localhost:28629): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jzwpcuvpwq.auto.internal" port: 28629 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-01 16:18:51,475 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jzwpcuvpwq.auto.internal:28629, location 1, ssl: False>]>
2025-11-01 16:18:51,476 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:28629
2025-11-01 16:18:55,107 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:55,108 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c669eddb-25e7-4ae5-b35c-ccd3651fdd6f, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:55,109 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c669eddb-25e7-4ae5-b35c-ccd3651fdd6f, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:55,111 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c669eddb-25e7-4ae5-b35c-ccd3651fdd6f, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:58,633 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:58,673 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_20_compression`, volumes `value` (8000000, 1595704)
2025-11-01 16:18:58,674 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:58,675 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ec634fe1-6aeb-4d2e-a656-04601b318c23, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:58,676 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ec634fe1-6aeb-4d2e-a656-04601b318c23, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" 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_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:58,677 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ec634fe1-6aeb-4d2e-a656-04601b318c23, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:58,992 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:58,993 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:18:58,994 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aca83a6f-18c9-454b-b612-4c95807534a5, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:18:58,995 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aca83a6f-18c9-454b-b612-4c95807534a5, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:58,996 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aca83a6f-18c9-454b-b612-4c95807534a5, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:02,658 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:19:12,723 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:19:12,736 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ada42845-34c9-4bd4-9e0d-d774a8105e11, ghrun-jzwpcuvpwq.auto.internal:28629): creating call state
2025-11-01 16:19:12,737 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ada42845-34c9-4bd4-9e0d-d774a8105e11, ghrun-jzwpcuvpwq.auto.internal:28629): request = { session_id: "ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:12,772 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ada42845-34c9-4bd4-9e0d-d774a8105e11, ghrun-jzwpcuvpwq.auto.internal:28629): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:15,957 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2I3N2I1ZDYtMTUyZWRkYS0xNTA4MjViNS03MWQ2Y2Zl
2025-11-01 16:19:16,001 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_20_compression`, volumes `value` (8000000, 1595704)
2025-11-01 16:19:16,002 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_20_compression` 8092160 / 1595704: 5.071216215538722
2025-11-01 16:19:16,014 - INFO - ya.test - pytest_runtest_teardown: Test teardown
