2025-10-15 15:11:54,115 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:11:54,115 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_4_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 4]
2025-10-15 15:11:54,116 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-15 15:11:54,116 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-15 15:11:54,118 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-15 15:11:54,119 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-15 15:11:54,119 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001bf4/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:11:54,129 - DEBUG - ya.test - execute: Command pid: 568372
2025-10-15 15:11:55,089 - DEBUG - ya.test - _save_outputs: Command (pid 568372) rc: 0
2025-10-15 15:11:55,089 - DEBUG - ya.test - _save_outputs: Command (pid 568372) elapsed time (sec): 0.9581515789031982
2025-10-15 15:11:55,090 - DEBUG - ya.test - _save_outputs: Command (pid 568372) idrss: 0
2025-10-15 15:11:55,090 - DEBUG - ya.test - _save_outputs: Command (pid 568372) inblock: 0
2025-10-15 15:11:55,091 - DEBUG - ya.test - _save_outputs: Command (pid 568372) isrss: 0
2025-10-15 15:11:55,091 - DEBUG - ya.test - _save_outputs: Command (pid 568372) ixrss: 0
2025-10-15 15:11:55,091 - DEBUG - ya.test - _save_outputs: Command (pid 568372) majflt: 0
2025-10-15 15:11:55,092 - DEBUG - ya.test - _save_outputs: Command (pid 568372) maxrss: 484220
2025-10-15 15:11:55,092 - DEBUG - ya.test - _save_outputs: Command (pid 568372) minflt: 45070
2025-10-15 15:11:55,092 - DEBUG - ya.test - _save_outputs: Command (pid 568372) msgrcv: 0
2025-10-15 15:11:55,093 - DEBUG - ya.test - _save_outputs: Command (pid 568372) msgsnd: 0
2025-10-15 15:11:55,093 - DEBUG - ya.test - _save_outputs: Command (pid 568372) nivcsw: 213
2025-10-15 15:11:55,093 - DEBUG - ya.test - _save_outputs: Command (pid 568372) nsignals: 0
2025-10-15 15:11:55,093 - DEBUG - ya.test - _save_outputs: Command (pid 568372) nswap: 0
2025-10-15 15:11:55,094 - DEBUG - ya.test - _save_outputs: Command (pid 568372) nvcsw: 14
2025-10-15 15:11:55,094 - DEBUG - ya.test - _save_outputs: Command (pid 568372) oublock: 8
2025-10-15 15:11:55,094 - DEBUG - ya.test - _save_outputs: Command (pid 568372) stime: 0.177371
2025-10-15 15:11:55,095 - DEBUG - ya.test - _save_outputs: Command (pid 568372) utime: 0.58366
2025-10-15 15:11:55,095 - DEBUG - ya.test - _save_outputs: Command (pid 568372) wtime: 0.969
2025-10-15 15:11:55,095 - DEBUG - ya.test - _save_outputs: Command (pid 568372) output:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-lvxbqnxafi
    Host information: 
        Linux ghrun-lvxbqnxafi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-10-15 15:11:55,096 - DEBUG - ya.test - _save_outputs: Command (pid 568372) errors:

2025-10-15 15:11:55,096 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-lvxbqnxafi
    Host information: 
        Linux ghrun-lvxbqnxafi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-10-15 15:11:55,248 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001bf4/ydb/apps/ydbd/ydbd
2025-10-15 15:11:55,251 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7536
2025-10-15 15:11:55,253 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/w2kl/001bf4/r3tmp/kikimr_cluster_rsrki1pg
2025-10-15 15:11:55,318 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001bf4/ydb/apps/ydbd/ydbd
2025-10-15 15:11:55,319 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14078
2025-10-15 15:11:55,320 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23533
2025-10-15 15:11:55,322 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/12594
2025-10-15 15:11:55,322 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4511
2025-10-15 15:11:55,323 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001bf4/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/kikimr_configs"
2025-10-15 15:11:55,324 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001bf4/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/w2kl/001bf4/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/node_1/logfile_d7co05pc.log --grpc-port=14078 --mon-port=23533 --ic-port=7536
2025-10-15 15:11:55,324 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:14078/1, disk_size 68719476736
2025-10-15 15:11:55,325 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/w2kl/001bf4/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/kikimr_configs"
2025-10-15 15:11:55,325 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/w2kl/001bf4/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/w2kl/001bf4/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/node_1/logfile_d7co05pc.log --grpc-port=14078 --mon-port=23533 --ic-port=7536
2025-10-15 15:11:55,327 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/w2kl/001bf4/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/w2kl/001bf4/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/w2kl/001bf4/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/node_1/logfile_d7co05pc.log', '--grpc-port=14078', '--mon-port=23533', '--ic-port=7536')' in '/home/runner/.ya/build/build_root/w2kl/001bf4/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk6/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_4_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.4/cluster/node_1' (no wait)
2025-10-15 15:11:55,349 - DEBUG - ya.test - execute: Command pid: 568540
2025-10-15 15:11:55,350 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:14078/1
2025-10-15 15:11:55,354 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-15 15:11:55,355 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-15 15:11:55,356 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001bf4/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-15 15:11:55,365 - DEBUG - ya.test - execute: Command pid: 568541
2025-10-15 15:11:56,391 - DEBUG - ya.test - _save_outputs: Command (pid 568541) rc: 0
2025-10-15 15:11:56,392 - DEBUG - ya.test - _save_outputs: Command (pid 568541) elapsed time (sec): 1.025381088256836
2025-10-15 15:11:56,392 - DEBUG - ya.test - _save_outputs: Command (pid 568541) idrss: 0
2025-10-15 15:11:56,393 - DEBUG - ya.test - _save_outputs: Command (pid 568541) inblock: 0
2025-10-15 15:11:56,393 - DEBUG - ya.test - _save_outputs: Command (pid 568541) isrss: 0
2025-10-15 15:11:56,393 - DEBUG - ya.test - _save_outputs: Command (pid 568541) ixrss: 0
2025-10-15 15:11:56,394 - DEBUG - ya.test - _save_outputs: Command (pid 568541) majflt: 0
2025-10-15 15:11:56,394 - DEBUG - ya.test - _save_outputs: Command (pid 568541) maxrss: 484220
2025-10-15 15:11:56,394 - DEBUG - ya.test - _save_outputs: Command (pid 568541) minflt: 45080
2025-10-15 15:11:56,395 - DEBUG - ya.test - _save_outputs: Command (pid 568541) msgrcv: 0
2025-10-15 15:11:56,395 - DEBUG - ya.test - _save_outputs: Command (pid 568541) msgsnd: 0
2025-10-15 15:11:56,395 - DEBUG - ya.test - _save_outputs: Command (pid 568541) nivcsw: 173
2025-10-15 15:11:56,396 - DEBUG - ya.test - _save_outputs: Command (pid 568541) nsignals: 0
2025-10-15 15:11:56,396 - DEBUG - ya.test - _save_outputs: Command (pid 568541) nswap: 0
2025-10-15 15:11:56,396 - DEBUG - ya.test - _save_outputs: Command (pid 568541) nvcsw: 14
2025-10-15 15:11:56,403 - DEBUG - ya.test - _save_outputs: Command (pid 568541) oublock: 8
2025-10-15 15:11:56,403 - DEBUG - ya.test - _save_outputs: Command (pid 568541) stime: 0.237843
2025-10-15 15:11:56,404 - DEBUG - ya.test - _save_outputs: Command (pid 568541) utime: 0.528902
2025-10-15 15:11:56,404 - DEBUG - ya.test - _save_outputs: Command (pid 568541) wtime: 1.035
2025-10-15 15:11:56,404 - DEBUG - ya.test - _save_outputs: Command (pid 568541) output:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-lvxbqnxafi
    Host information: 
        Linux ghrun-lvxbqnxafi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -fsanitize=address -Daddress_sanitizer_enabled -fno-omit-frame-pointer -fsanitize-blacklist=/home/runner/actions_runner/_work/ydb/ydb/build/sanitize-blacklist.txt -fno-sanitize-link-runtime -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -DNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-10-15 15:11:56,405 - DEBUG - ya.test - _save_outputs: Command (pid 568541) errors:

2025-10-15 15:11:56,405 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 407626920c115831f1a477a2b30b7078a40af8e5
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 497b3dc27b07cc86494880d309604353f9f511e7 into a477250a0870cd44ffd06d39719a4c90e0753d7b
2025-10-15 15:12:02,424 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001bf4/ydb/apps/ydbd/ydbd
2025-10-15 15:12:02,425 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2kl/001bf4/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:14078', '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: 7536\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-15 15:12:02,438 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-15 15:12:02,439 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-15 15:12:02,439 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001bf4/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:14078', '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: 7536\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-15 15:12:02,448 - DEBUG - ya.test - execute: Command pid: 570227
2025-10-15 15:12:04,145 - DEBUG - ya.test - _save_outputs: Command (pid 570227) rc: 0
2025-10-15 15:12:04,146 - DEBUG - ya.test - _save_outputs: Command (pid 570227) elapsed time (sec): 1.6962850093841553
2025-10-15 15:12:04,146 - DEBUG - ya.test - _save_outputs: Command (pid 570227) idrss: 0
2025-10-15 15:12:04,146 - DEBUG - ya.test - _save_outputs: Command (pid 570227) inblock: 0
2025-10-15 15:12:04,147 - DEBUG - ya.test - _save_outputs: Command (pid 570227) isrss: 0
2025-10-15 15:12:04,147 - DEBUG - ya.test - _save_outputs: Command (pid 570227) ixrss: 0
2025-10-15 15:12:04,147 - DEBUG - ya.test - _save_outputs: Command (pid 570227) majflt: 0
2025-10-15 15:12:04,147 - DEBUG - ya.test - _save_outputs: Command (pid 570227) maxrss: 586432
2025-10-15 15:12:04,148 - DEBUG - ya.test - _save_outputs: Command (pid 570227) minflt: 51445
2025-10-15 15:12:04,148 - DEBUG - ya.test - _save_outputs: Command (pid 570227) msgrcv: 0
2025-10-15 15:12:04,148 - DEBUG - ya.test - _save_outputs: Command (pid 570227) msgsnd: 0
2025-10-15 15:12:04,148 - DEBUG - ya.test - _save_outputs: Command (pid 570227) nivcsw: 296
2025-10-15 15:12:04,149 - DEBUG - ya.test - _save_outputs: Command (pid 570227) nsignals: 0
2025-10-15 15:12:04,151 - DEBUG - ya.test - _save_outputs: Command (pid 570227) nswap: 0
2025-10-15 15:12:04,151 - DEBUG - ya.test - _save_outputs: Command (pid 570227) nvcsw: 625
2025-10-15 15:12:04,151 - DEBUG - ya.test - _save_outputs: Command (pid 570227) oublock: 8
2025-10-15 15:12:04,152 - DEBUG - ya.test - _save_outputs: Command (pid 570227) stime: 0.362105
2025-10-15 15:12:04,152 - DEBUG - ya.test - _save_outputs: Command (pid 570227) utime: 0.573388
2025-10-15 15:12:04,152 - DEBUG - ya.test - _save_outputs: Command (pid 570227) wtime: 1.705
2025-10-15 15:12:04,152 - DEBUG - ya.test - _save_outputs: Command (pid 570227) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-15 15:12:04,153 - DEBUG - ya.test - _save_outputs: Command (pid 570227) errors:

2025-10-15 15:12:04,154 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/w2kl/001bf4/ydb/apps/ydbd/ydbd
2025-10-15 15:12:04,155 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/w2kl/001bf4/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:14078', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-10-15 15:12:04,156 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-15 15:12:04,157 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-15 15:12:04,158 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/w2kl/001bf4/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:14078', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-15 15:12:04,176 - DEBUG - ya.test - execute: Command pid: 570594
2025-10-15 15:12:05,749 - DEBUG - ya.test - _save_outputs: Command (pid 570594) rc: 0
2025-10-15 15:12:05,750 - DEBUG - ya.test - _save_outputs: Command (pid 570594) elapsed time (sec): 1.5724468231201172
2025-10-15 15:12:05,750 - DEBUG - ya.test - _save_outputs: Command (pid 570594) idrss: 0
2025-10-15 15:12:05,750 - DEBUG - ya.test - _save_outputs: Command (pid 570594) inblock: 0
2025-10-15 15:12:05,750 - DEBUG - ya.test - _save_outputs: Command (pid 570594) isrss: 0
2025-10-15 15:12:05,751 - DEBUG - ya.test - _save_outputs: Command (pid 570594) ixrss: 0
2025-10-15 15:12:05,751 - DEBUG - ya.test - _save_outputs: Command (pid 570594) majflt: 0
2025-10-15 15:12:05,751 - DEBUG - ya.test - _save_outputs: Command (pid 570594) maxrss: 586932
2025-10-15 15:12:05,752 - DEBUG - ya.test - _save_outputs: Command (pid 570594) minflt: 51436
2025-10-15 15:12:05,752 - DEBUG - ya.test - _save_outputs: Command (pid 570594) msgrcv: 0
2025-10-15 15:12:05,752 - DEBUG - ya.test - _save_outputs: Command (pid 570594) msgsnd: 0
2025-10-15 15:12:05,752 - DEBUG - ya.test - _save_outputs: Command (pid 570594) nivcsw: 269
2025-10-15 15:12:05,753 - DEBUG - ya.test - _save_outputs: Command (pid 570594) nsignals: 0
2025-10-15 15:12:05,753 - DEBUG - ya.test - _save_outputs: Command (pid 570594) nswap: 0
2025-10-15 15:12:05,754 - DEBUG - ya.test - _save_outputs: Command (pid 570594) nvcsw: 474
2025-10-15 15:12:05,754 - DEBUG - ya.test - _save_outputs: Command (pid 570594) oublock: 8
2025-10-15 15:12:05,754 - DEBUG - ya.test - _save_outputs: Command (pid 570594) stime: 0.27235699999999996
2025-10-15 15:12:05,755 - DEBUG - ya.test - _save_outputs: Command (pid 570594) utime: 0.610193
2025-10-15 15:12:05,755 - DEBUG - ya.test - _save_outputs: Command (pid 570594) wtime: 1.59
2025-10-15 15:12:05,756 - DEBUG - ya.test - _save_outputs: Command (pid 570594) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-15 15:12:05,756 - DEBUG - ya.test - _save_outputs: Command (pid 570594) errors:

2025-10-15 15:12:05,756 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-15 15:12:05,940 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-15 15:12:05,987 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-15 15:12:05,991 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:12:05,992 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-15 15:12:06,065 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:12:06,066 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, ca64000e-ce9f-4953-9f80-6813f654ac83, localhost:14078): creating call state
2025-10-15 15:12:06,067 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, ca64000e-ce9f-4953-9f80-6813f654ac83, localhost:14078): request = { database: "/Root" }
2025-10-15 15:12:06,079 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, ca64000e-ce9f-4953-9f80-6813f654ac83, localhost:14078): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 14078 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-10-15 15:12:06,080 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:14078, location 1, ssl: False>]>
2025-10-15 15:12:06,081 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-15 15:12:06,113 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:14078
2025-10-15 15:12:06,117 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-10-15 15:12:06,118 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a08a780a-d4b9-45bb-bee9-a702b613cd49, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:06,119 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a08a780a-d4b9-45bb-bee9-a702b613cd49, ghrun-lvxbqnxafi.auto.internal:14078): request = {  }
2025-10-15 15:12:06,130 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a08a780a-d4b9-45bb-bee9-a702b613cd49, ghrun-lvxbqnxafi.auto.internal:14078): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" node_id: 1 }
2025-10-15 15:12:06,131 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, bb3d0de1-0be8-4b55-9b97-46ddab5b051a, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:06,132 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, bb3d0de1-0be8-4b55-9b97-46ddab5b051a, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" }
2025-10-15 15:12:06,141 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, bb3d0de1-0be8-4b55-9b97-46ddab5b051a, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:06,155 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:06,156 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7b89cf3f-7c30-44fc-b92f-03822e8a5809, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:06,157 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7b89cf3f-7c30-44fc-b92f-03822e8a5809, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:06,159 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7b89cf3f-7c30-44fc-b92f-03822e8a5809, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:10,305 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:10,306 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-10-15 15:12:10,306 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:10,307 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a1ebbf1b-2ccf-43dc-88c5-4280a7daa8f3, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:10,308 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a1ebbf1b-2ccf-43dc-88c5-4280a7daa8f3, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:10,320 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a1ebbf1b-2ccf-43dc-88c5-4280a7daa8f3, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:11,761 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:11,762 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:11,762 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb570813-dd4a-4d44-8509-fffa23506e7a, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:11,763 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb570813-dd4a-4d44-8509-fffa23506e7a, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:11,765 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb570813-dd4a-4d44-8509-fffa23506e7a, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:13,146 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:13,147 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:13,148 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 920dbdc1-5e35-435e-a80a-dd354cb3912d, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:13,149 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 920dbdc1-5e35-435e-a80a-dd354cb3912d, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:13,150 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 920dbdc1-5e35-435e-a80a-dd354cb3912d, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:14,274 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:14,275 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:14,276 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4f996d02-7aea-44ba-9ec0-70e473e6d642, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:14,277 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4f996d02-7aea-44ba-9ec0-70e473e6d642, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:14,279 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4f996d02-7aea-44ba-9ec0-70e473e6d642, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:17,521 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:17,522 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 1618432, 'BlobCount': 128}}
2025-10-15 15:12:17,523 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:17,523 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 93afb86c-6991-4384-b1d1-bb055aeb3127, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:17,525 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 93afb86c-6991-4384-b1d1-bb055aeb3127, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:17,526 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 93afb86c-6991-4384-b1d1-bb055aeb3127, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:18,651 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:18,652 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:18,653 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8b8ce8d1-ca29-4c06-8393-1dc7238bb458, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:18,654 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8b8ce8d1-ca29-4c06-8393-1dc7238bb458, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:18,662 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8b8ce8d1-ca29-4c06-8393-1dc7238bb458, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:19,217 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:19,218 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:19,219 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 83c966ae-de78-47eb-b554-f5f1342b1f68, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:19,220 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 83c966ae-de78-47eb-b554-f5f1342b1f68, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:19,227 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 83c966ae-de78-47eb-b554-f5f1342b1f68, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:19,737 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:19,738 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 3236864, 'BlobCount': 256}}
2025-10-15 15:12:19,738 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:19,739 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a1f862c5-5bd6-4af9-a651-4f1a35f9f6d9, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:19,742 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a1f862c5-5bd6-4af9-a651-4f1a35f9f6d9, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:19,744 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a1f862c5-5bd6-4af9-a651-4f1a35f9f6d9, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:20,969 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:20,970 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:20,970 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9a0a7538-bfcd-4a26-87d2-66e3f19d16c5, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:20,971 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9a0a7538-bfcd-4a26-87d2-66e3f19d16c5, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:20,973 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9a0a7538-bfcd-4a26-87d2-66e3f19d16c5, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:21,268 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:21,269 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:21,270 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a42f89ee-2e65-497d-a910-7bc109b4a050, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:21,272 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a42f89ee-2e65-497d-a910-7bc109b4a050, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:21,273 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a42f89ee-2e65-497d-a910-7bc109b4a050, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:21,683 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:21,684 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 309374, 'Portions': 189}}, blobs: {'__DEFAULT': {'Portions': 183, 'BlobSize': 4858280, 'BlobCount': 384}}
2025-10-15 15:12:21,684 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:21,685 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6ef73611-7dd7-43c3-8bae-bc1a0021a8fb, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:21,686 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6ef73611-7dd7-43c3-8bae-bc1a0021a8fb, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:21,688 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6ef73611-7dd7-43c3-8bae-bc1a0021a8fb, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:22,791 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:22,792 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:22,793 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bae46c7f-af16-46ba-b48b-1a817f696742, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:22,794 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bae46c7f-af16-46ba-b48b-1a817f696742, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:22,796 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bae46c7f-af16-46ba-b48b-1a817f696742, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:23,094 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:23,094 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:23,095 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 522e768b-d090-4cbe-9f36-7f04df21c46d, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:23,096 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 522e768b-d090-4cbe-9f36-7f04df21c46d, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:23,103 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 522e768b-d090-4cbe-9f36-7f04df21c46d, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:23,476 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:23,477 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 241}}, blobs: {'__DEFAULT': {'Portions': 241, 'BlobSize': 6477184, 'BlobCount': 506}}
2025-10-15 15:12:23,478 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:23,478 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f1df0072-87b7-4ae3-9be6-363bc0e38375, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:23,479 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f1df0072-87b7-4ae3-9be6-363bc0e38375, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:23,489 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f1df0072-87b7-4ae3-9be6-363bc0e38375, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:25,048 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:25,049 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:25,050 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6d0d9243-ac47-42c3-ac11-330dbe3244e9, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:25,051 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6d0d9243-ac47-42c3-ac11-330dbe3244e9, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:25,062 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6d0d9243-ac47-42c3-ac11-330dbe3244e9, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:25,387 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:25,388 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:25,389 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d1b1a04-4662-4bce-b93c-10cbacf8b4d7, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:25,390 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d1b1a04-4662-4bce-b93c-10cbacf8b4d7, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:25,406 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d1b1a04-4662-4bce-b93c-10cbacf8b4d7, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:25,948 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:25,949 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 299}}, blobs: {'__DEFAULT': {'Portions': 299, 'BlobSize': 8095576, 'BlobCount': 626}}
2025-10-15 15:12:25,950 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:25,950 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0e81af05-543c-4627-9c05-0bdf6b41edf2, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:25,952 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0e81af05-543c-4627-9c05-0bdf6b41edf2, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:25,962 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0e81af05-543c-4627-9c05-0bdf6b41edf2, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:27,236 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:27,237 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:27,238 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 023a3e3a-e3c4-421b-8ae1-fe063a53af28, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:27,239 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 023a3e3a-e3c4-421b-8ae1-fe063a53af28, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:27,241 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 023a3e3a-e3c4-421b-8ae1-fe063a53af28, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:27,721 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:27,722 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:27,723 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 28eeb31c-56b7-41b3-926d-723827f1b688, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:27,724 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 28eeb31c-56b7-41b3-926d-723827f1b688, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:27,726 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 28eeb31c-56b7-41b3-926d-723827f1b688, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:28,411 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:28,412 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 351}}, blobs: {'__DEFAULT': {'Portions': 351, 'BlobSize': 9713432, 'BlobCount': 736}}
2025-10-15 15:12:28,413 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:28,413 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0ba019ec-cfef-451a-9ec9-0e1217255774, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:28,414 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0ba019ec-cfef-451a-9ec9-0e1217255774, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:28,416 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0ba019ec-cfef-451a-9ec9-0e1217255774, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:29,614 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:29,614 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:29,615 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f93d1f93-0af0-47a4-a7e1-8eace08a96b1, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:29,616 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f93d1f93-0af0-47a4-a7e1-8eace08a96b1, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:29,623 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f93d1f93-0af0-47a4-a7e1-8eace08a96b1, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:29,970 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:29,971 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:29,971 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5d0cb655-37c8-44a6-a2c6-c8b98181e8d3, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:29,972 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5d0cb655-37c8-44a6-a2c6-c8b98181e8d3, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:29,974 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5d0cb655-37c8-44a6-a2c6-c8b98181e8d3, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:30,604 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:30,605 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 405}}, blobs: {'__DEFAULT': {'Portions': 405, 'BlobSize': 11331192, 'BlobCount': 848}}
2025-10-15 15:12:30,605 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:30,606 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9ac3c343-64a9-4557-bb9d-2600daafa86d, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:30,607 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9ac3c343-64a9-4557-bb9d-2600daafa86d, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:30,611 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9ac3c343-64a9-4557-bb9d-2600daafa86d, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:31,947 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:31,948 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:31,949 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e5369bf0-5d00-4f23-9722-6817402f32b9, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:31,950 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e5369bf0-5d00-4f23-9722-6817402f32b9, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:31,951 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e5369bf0-5d00-4f23-9722-6817402f32b9, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:32,250 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:32,251 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:32,252 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dab4e605-d811-4914-a889-47322e19f727, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:32,253 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dab4e605-d811-4914-a889-47322e19f727, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:32,254 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dab4e605-d811-4914-a889-47322e19f727, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:33,211 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:33,211 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 457}}, blobs: {'__DEFAULT': {'Portions': 457, 'BlobSize': 12948584, 'BlobCount': 956}}
2025-10-15 15:12:33,212 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:33,213 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8fe41070-7fa5-4e6e-850f-891f6f1f392c, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:33,214 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8fe41070-7fa5-4e6e-850f-891f6f1f392c, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:33,217 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8fe41070-7fa5-4e6e-850f-891f6f1f392c, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:34,315 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:34,316 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:34,316 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1cfc5826-d89b-4c8f-a718-5b703abf8259, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:34,318 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1cfc5826-d89b-4c8f-a718-5b703abf8259, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:34,320 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1cfc5826-d89b-4c8f-a718-5b703abf8259, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:34,600 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:34,601 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:34,602 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 549db73d-0983-453d-b801-ded60249f4f9, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:34,603 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 549db73d-0983-453d-b801-ded60249f4f9, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:34,604 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 549db73d-0983-453d-b801-ded60249f4f9, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:35,317 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:35,317 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 514}}, blobs: {'__DEFAULT': {'Portions': 514, 'BlobSize': 14566336, 'BlobCount': 1072}}
2025-10-15 15:12:35,318 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:35,319 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8a880215-cd97-4022-84b1-e06ae6a895ba, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:35,320 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8a880215-cd97-4022-84b1-e06ae6a895ba, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:35,322 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8a880215-cd97-4022-84b1-e06ae6a895ba, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:36,704 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:36,705 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:36,706 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 14fda5f9-389a-4a5a-8c7f-0e5b051f07e9, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:36,706 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 14fda5f9-389a-4a5a-8c7f-0e5b051f07e9, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:36,708 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 14fda5f9-389a-4a5a-8c7f-0e5b051f07e9, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:37,006 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:37,007 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:37,008 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 094204d1-dc71-4a97-bd3e-9925ceb0a265, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:37,009 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 094204d1-dc71-4a97-bd3e-9925ceb0a265, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:37,010 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 094204d1-dc71-4a97-bd3e-9925ceb0a265, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:37,883 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:37,884 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 570}}, blobs: {'__DEFAULT': {'Portions': 570, 'BlobSize': 16183896, 'BlobCount': 1186}}
2025-10-15 15:12:37,884 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:37,885 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 989fd006-44cb-4731-bac4-62986297fa56, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:37,886 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 989fd006-44cb-4731-bac4-62986297fa56, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:37,889 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 989fd006-44cb-4731-bac4-62986297fa56, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:39,064 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:39,065 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:39,066 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0efd504f-6d96-489d-bce1-fbdcc7d80489, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:39,067 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0efd504f-6d96-489d-bce1-fbdcc7d80489, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:39,071 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0efd504f-6d96-489d-bce1-fbdcc7d80489, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:39,426 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:39,427 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:39,428 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6b72453a-e702-4c90-9357-28b8d39e3a95, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:39,429 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6b72453a-e702-4c90-9357-28b8d39e3a95, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:39,431 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6b72453a-e702-4c90-9357-28b8d39e3a95, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:39,635 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:39,636 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:39,637 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cd585657-68db-4d92-82d4-51d1d0f611b7, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:39,638 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cd585657-68db-4d92-82d4-51d1d0f611b7, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:39,639 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cd585657-68db-4d92-82d4-51d1d0f611b7, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:44,464 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:54,505 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:54,507 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d7b1ca3e-412b-4398-a99a-dda674ae6f9d, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:54,507 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d7b1ca3e-412b-4398-a99a-dda674ae6f9d, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:54,510 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d7b1ca3e-412b-4398-a99a-dda674ae6f9d, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:12:58,713 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:58,855 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8082080)
2025-10-15 15:12:58,856 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:12:58,857 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3f66b466-a345-400e-b20f-aa328a4f764e, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:12:58,858 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3f66b466-a345-400e-b20f-aa328a4f764e, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:12:58,859 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3f66b466-a345-400e-b20f-aa328a4f764e, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:04,218 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:14,269 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:14,270 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 882be318-dcb5-4c5d-a861-2b9aa329ebb5, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:14,271 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 882be318-dcb5-4c5d-a861-2b9aa329ebb5, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:14,272 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 882be318-dcb5-4c5d-a861-2b9aa329ebb5, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:16,721 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:26,762 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:26,763 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1469b411-ddad-48c0-8e98-6f025b955aa4, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:26,764 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1469b411-ddad-48c0-8e98-6f025b955aa4, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:26,769 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1469b411-ddad-48c0-8e98-6f025b955aa4, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:28,246 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:38,261 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:38,262 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 050b13d4-516a-426c-a515-5f438acdaaf1, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:38,263 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 050b13d4-516a-426c-a515-5f438acdaaf1, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:38,272 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 050b13d4-516a-426c-a515-5f438acdaaf1, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:39,797 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:39,808 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8028944)
2025-10-15 15:13:39,810 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:39,810 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1d8bbd66-5ad2-4702-a6d0-fa8229102ddb, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:39,811 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1d8bbd66-5ad2-4702-a6d0-fa8229102ddb, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:39,813 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1d8bbd66-5ad2-4702-a6d0-fa8229102ddb, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:40,121 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:40,125 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_4_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 4])
2025-10-15 15:13:40,126 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:40,127 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1432a7da-21a4-4027-8900-1df7c50557d1, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:40,128 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1432a7da-21a4-4027-8900-1df7c50557d1, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_4_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 4)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:40,130 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1432a7da-21a4-4027-8900-1df7c50557d1, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:44,464 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:44,465 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_4_compression created
2025-10-15 15:13:44,466 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:44,466 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 75a56e81-2d36-40c0-9b19-e8008dc777a3, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:44,467 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 75a56e81-2d36-40c0-9b19-e8008dc777a3, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_4_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:44,477 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 75a56e81-2d36-40c0-9b19-e8008dc777a3, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:45,609 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:45,610 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:45,611 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 297d7f17-3bdd-471d-9218-c81bd16bc517, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:45,612 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 297d7f17-3bdd-471d-9218-c81bd16bc517, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:45,613 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 297d7f17-3bdd-471d-9218-c81bd16bc517, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:47,291 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:47,292 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:47,293 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3fbc0e52-6a2b-458c-9fe7-7b39cf4429a5, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:47,294 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3fbc0e52-6a2b-458c-9fe7-7b39cf4429a5, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:47,295 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3fbc0e52-6a2b-458c-9fe7-7b39cf4429a5, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:48,749 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:48,750 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:48,750 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e079b062-8111-4198-ab7b-68f0aa299707, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:48,751 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e079b062-8111-4198-ab7b-68f0aa299707, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:48,761 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e079b062-8111-4198-ab7b-68f0aa299707, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:51,368 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:51,369 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318960, 'BlobCount': 128}}
2025-10-15 15:13:51,369 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:51,370 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3cdf4347-3366-43b1-92d5-3be978837a6b, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:51,371 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3cdf4347-3366-43b1-92d5-3be978837a6b, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:51,381 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3cdf4347-3366-43b1-92d5-3be978837a6b, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:53,057 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:53,058 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:53,059 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8658f97d-68c4-4f93-826e-b4f0adf7d91e, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:53,060 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8658f97d-68c4-4f93-826e-b4f0adf7d91e, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:53,062 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8658f97d-68c4-4f93-826e-b4f0adf7d91e, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:53,529 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:53,530 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:53,531 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd3f3cab-ba7d-46d8-a3d5-29f0d04eeb5e, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:53,532 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd3f3cab-ba7d-46d8-a3d5-29f0d04eeb5e, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:53,533 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd3f3cab-ba7d-46d8-a3d5-29f0d04eeb5e, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:54,142 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:54,143 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638256, 'BlobCount': 256}}
2025-10-15 15:13:54,144 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:54,144 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 22bf0d29-0ae3-42e8-b943-793ee483ba25, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:54,145 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 22bf0d29-0ae3-42e8-b943-793ee483ba25, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:54,147 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 22bf0d29-0ae3-42e8-b943-793ee483ba25, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:55,670 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:55,671 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:55,672 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9bbde68d-2ddc-456c-a0c3-6f4587888b99, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:55,673 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9bbde68d-2ddc-456c-a0c3-6f4587888b99, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:55,674 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9bbde68d-2ddc-456c-a0c3-6f4587888b99, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:56,137 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:56,138 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:56,139 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e914aa8c-9bde-492e-87b6-223d537fad54, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:56,140 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e914aa8c-9bde-492e-87b6-223d537fad54, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:56,141 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e914aa8c-9bde-492e-87b6-223d537fad54, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:56,661 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:56,661 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 182}}, blobs: {'__DEFAULT': {'Portions': 182, 'BlobSize': 958960, 'BlobCount': 384}}
2025-10-15 15:13:56,662 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:56,663 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0ce30196-e9a7-4ce1-aa70-d0802f4936dc, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:56,664 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0ce30196-e9a7-4ce1-aa70-d0802f4936dc, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:56,670 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0ce30196-e9a7-4ce1-aa70-d0802f4936dc, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:58,073 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:58,074 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:58,075 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 85fd55cc-3ae7-459e-897a-cf975326a75d, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:58,076 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 85fd55cc-3ae7-459e-897a-cf975326a75d, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:58,077 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 85fd55cc-3ae7-459e-897a-cf975326a75d, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:58,430 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:58,431 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:58,431 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dfb40031-0927-4fc7-886c-5ae201c91733, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:58,432 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dfb40031-0927-4fc7-886c-5ae201c91733, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:58,435 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dfb40031-0927-4fc7-886c-5ae201c91733, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:13:58,914 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:58,915 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 238}}, blobs: {'__DEFAULT': {'Portions': 238, 'BlobSize': 1277624, 'BlobCount': 504}}
2025-10-15 15:13:58,915 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:13:58,916 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bb3db067-9242-445e-8727-591e4869de4c, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:13:58,917 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bb3db067-9242-445e-8727-591e4869de4c, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:13:58,918 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bb3db067-9242-445e-8727-591e4869de4c, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:00,457 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:00,458 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:00,458 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4d449721-2f4a-4d10-a4ed-361bf04f47bf, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:00,459 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4d449721-2f4a-4d10-a4ed-361bf04f47bf, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:00,461 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4d449721-2f4a-4d10-a4ed-361bf04f47bf, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:00,869 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:00,870 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:00,871 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 39cf5959-56c3-4a11-93f8-8e61d6872459, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:00,872 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 39cf5959-56c3-4a11-93f8-8e61d6872459, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:00,874 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 39cf5959-56c3-4a11-93f8-8e61d6872459, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:01,300 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-15 15:14:01,301 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-15 15:14:01,334 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-15 15:14:01,334 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 5ccddd5f-a714-438d-abb3-a2f70e6d9045, localhost:14078): creating call state
2025-10-15 15:14:01,335 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 5ccddd5f-a714-438d-abb3-a2f70e6d9045, localhost:14078): request = { database: "/Root" }
2025-10-15 15:14:01,353 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 5ccddd5f-a714-438d-abb3-a2f70e6d9045, localhost:14078): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lvxbqnxafi.auto.internal" port: 14078 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-10-15 15:14:01,354 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lvxbqnxafi.auto.internal:14078, location 1, ssl: False>]>
2025-10-15 15:14:01,355 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:14078
2025-10-15 15:14:01,485 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:01,486 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 287}}, blobs: {'__DEFAULT': {'Portions': 287, 'BlobSize': 1594120, 'BlobCount': 612}}
2025-10-15 15:14:01,487 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:01,487 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c28c2fad-0385-4c0b-a5c5-3ddc3725ee4a, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:01,488 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c28c2fad-0385-4c0b-a5c5-3ddc3725ee4a, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:01,505 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c28c2fad-0385-4c0b-a5c5-3ddc3725ee4a, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:03,054 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:03,055 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:03,056 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 48089a78-93d1-4d23-90f2-bd2c5199fdbf, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:03,056 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 48089a78-93d1-4d23-90f2-bd2c5199fdbf, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:03,058 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 48089a78-93d1-4d23-90f2-bd2c5199fdbf, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:03,582 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:03,583 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:03,584 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 45c6aaab-7ae2-406a-9c0e-14b5f86cf73f, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:03,585 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 45c6aaab-7ae2-406a-9c0e-14b5f86cf73f, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:03,586 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 45c6aaab-7ae2-406a-9c0e-14b5f86cf73f, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:04,495 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:04,496 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 347}}, blobs: {'__DEFAULT': {'Portions': 347, 'BlobSize': 1912448, 'BlobCount': 734}}
2025-10-15 15:14:04,496 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:04,497 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0a059bcc-aeb8-4a15-8e04-10f824643189, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:04,498 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0a059bcc-aeb8-4a15-8e04-10f824643189, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:04,499 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0a059bcc-aeb8-4a15-8e04-10f824643189, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:06,011 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:06,012 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:06,013 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8add346c-e6a3-406a-be62-f6d7a5582783, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:06,014 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8add346c-e6a3-406a-be62-f6d7a5582783, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:06,015 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8add346c-e6a3-406a-be62-f6d7a5582783, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:06,712 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:06,713 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:06,713 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb23e005-5fb4-4968-8ea9-6db1f415829a, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:06,714 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb23e005-5fb4-4968-8ea9-6db1f415829a, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:06,723 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb23e005-5fb4-4968-8ea9-6db1f415829a, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:07,629 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:07,629 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 401}}, blobs: {'__DEFAULT': {'Portions': 401, 'BlobSize': 2229408, 'BlobCount': 846}}
2025-10-15 15:14:07,630 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:07,631 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 14ad1c3a-d688-42b0-be56-e3aab1392d57, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:07,632 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 14ad1c3a-d688-42b0-be56-e3aab1392d57, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:07,633 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 14ad1c3a-d688-42b0-be56-e3aab1392d57, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:09,309 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:09,310 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:09,310 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 88ceeecb-c72f-4764-baf9-636a03162100, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:09,311 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 88ceeecb-c72f-4764-baf9-636a03162100, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:09,314 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 88ceeecb-c72f-4764-baf9-636a03162100, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:10,179 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:10,180 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:10,180 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 89e48260-eab2-4fa0-8640-c0d89779404c, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:10,182 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 89e48260-eab2-4fa0-8640-c0d89779404c, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:10,184 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 89e48260-eab2-4fa0-8640-c0d89779404c, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:10,870 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:10,871 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 832857, 'Portions': 450}}, blobs: {'__DEFAULT': {'Portions': 418, 'BlobSize': 2570696, 'BlobCount': 898}}
2025-10-15 15:14:10,872 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:10,872 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 37ff8522-f598-4068-be6c-d730ef58ae3d, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:10,873 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 37ff8522-f598-4068-be6c-d730ef58ae3d, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:10,887 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 37ff8522-f598-4068-be6c-d730ef58ae3d, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:12,513 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:12,514 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:12,515 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 221f8b9f-dbee-4474-be33-eb0e771f8933, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:12,516 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 221f8b9f-dbee-4474-be33-eb0e771f8933, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:12,520 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 221f8b9f-dbee-4474-be33-eb0e771f8933, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:13,421 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:13,422 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:13,423 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7b3a71fa-4d14-40c5-9c8f-0f5893131b26, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:13,424 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7b3a71fa-4d14-40c5-9c8f-0f5893131b26, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:13,433 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7b3a71fa-4d14-40c5-9c8f-0f5893131b26, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:14,567 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:14,568 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 475}}, blobs: {'__DEFAULT': {'Portions': 475, 'BlobSize': 2854568, 'BlobCount': 1012}}
2025-10-15 15:14:14,568 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:14,570 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 86a51721-c6c8-4bb7-a295-34d684376064, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:14,571 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 86a51721-c6c8-4bb7-a295-34d684376064, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:14,572 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 86a51721-c6c8-4bb7-a295-34d684376064, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:16,037 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:16,038 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:16,039 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9afda096-f882-4041-a064-ca9523840cba, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:16,039 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9afda096-f882-4041-a064-ca9523840cba, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:16,042 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9afda096-f882-4041-a064-ca9523840cba, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:16,382 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:16,383 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:16,384 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cad3d254-1550-4ea6-9701-343084b41650, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:16,385 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cad3d254-1550-4ea6-9701-343084b41650, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:16,387 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cad3d254-1550-4ea6-9701-343084b41650, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:17,087 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:17,088 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_4_compression. portions: {'__DEFAULT': {'Rows': 1014033, 'Portions': 510}}, blobs: {'__DEFAULT': {'Portions': 493, 'BlobSize': 3161768, 'BlobCount': 1060}}
2025-10-15 15:14:17,089 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:17,089 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d3dd4b4c-1313-4943-a941-325096704877, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:17,090 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d3dd4b4c-1313-4943-a941-325096704877, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_4_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:17,093 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d3dd4b4c-1313-4943-a941-325096704877, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:18,333 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:18,334 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:18,335 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3e240418-5daa-44d9-a157-2ed327fee42b, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:18,336 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3e240418-5daa-44d9-a157-2ed327fee42b, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:18,338 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3e240418-5daa-44d9-a157-2ed327fee42b, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:18,679 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:18,680 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:18,681 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 28888f8e-0dd1-4595-8732-c0755e483fd9, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:18,682 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 28888f8e-0dd1-4595-8732-c0755e483fd9, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:18,685 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 28888f8e-0dd1-4595-8732-c0755e483fd9, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:19,149 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:19,150 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:19,150 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ef908f52-d9b0-4b62-af89-163e837a9c66, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:19,151 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ef908f52-d9b0-4b62-af89-163e837a9c66, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:19,152 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ef908f52-d9b0-4b62-af89-163e837a9c66, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:22,903 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:32,949 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:32,950 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, adf4b46f-41b7-4b68-846b-7d66ecbc7540, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:32,951 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, adf4b46f-41b7-4b68-846b-7d66ecbc7540, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:32,952 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, adf4b46f-41b7-4b68-846b-7d66ecbc7540, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:36,555 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:36,584 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_4_compression`, volumes `value` (8000000, 1562912)
2025-10-15 15:14:36,585 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:36,586 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1e9ef2a9-21a7-4be7-9ab2-b1bfe80b37c5, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:36,587 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1e9ef2a9-21a7-4be7-9ab2-b1bfe80b37c5, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" 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_4_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:36,589 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1e9ef2a9-21a7-4be7-9ab2-b1bfe80b37c5, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:36,970 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:36,971 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:36,972 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b181ae29-9499-459f-a86a-592eab1e9a49, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:36,972 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b181ae29-9499-459f-a86a-592eab1e9a49, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:36,975 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b181ae29-9499-459f-a86a-592eab1e9a49, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:40,279 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:50,310 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:50,311 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2e40ec90-2a24-4930-80a9-7458756e08a6, ghrun-lvxbqnxafi.auto.internal:14078): creating call state
2025-10-15 15:14:50,312 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2e40ec90-2a24-4930-80a9-7458756e08a6, ghrun-lvxbqnxafi.auto.internal:14078): request = { session_id: "ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_4_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-15 15:14:50,313 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2e40ec90-2a24-4930-80a9-7458756e08a6, ghrun-lvxbqnxafi.auto.internal:14078): response = { <_MultiThreadedRendezvous object> }
2025-10-15 15:14:52,107 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2EzMDVjYTktZjM2ZTA2ZDQtOWUxNWQ1YjgtYTI0ZWZiMzA=
2025-10-15 15:14:52,124 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_4_compression`, volumes `value` (8000000, 1514184)
2025-10-15 15:14:52,124 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_4_compression` 8082080 / 1514184: 5.337581165829252
2025-10-15 15:14:52,127 - INFO - ya.test - pytest_runtest_teardown: Test teardown
