2025-11-01 16:19:07,286 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:19:07,287 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_18_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 18]
2025-11-01 16:19:07,287 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:19:07,288 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 16:19:07,290 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-01 16:19:07,290 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-01 16:19:07,291 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/0024f7/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:19:07,296 - DEBUG - ya.test - execute: Command pid: 517370
2025-11-01 16:19:08,077 - DEBUG - ya.test - _save_outputs: Command (pid 517370) rc: 0
2025-11-01 16:19:08,077 - DEBUG - ya.test - _save_outputs: Command (pid 517370) elapsed time (sec): 0.779982328414917
2025-11-01 16:19:08,078 - DEBUG - ya.test - _save_outputs: Command (pid 517370) idrss: 0
2025-11-01 16:19:08,078 - DEBUG - ya.test - _save_outputs: Command (pid 517370) inblock: 0
2025-11-01 16:19:08,078 - DEBUG - ya.test - _save_outputs: Command (pid 517370) isrss: 0
2025-11-01 16:19:08,079 - DEBUG - ya.test - _save_outputs: Command (pid 517370) ixrss: 0
2025-11-01 16:19:08,079 - DEBUG - ya.test - _save_outputs: Command (pid 517370) majflt: 0
2025-11-01 16:19:08,079 - DEBUG - ya.test - _save_outputs: Command (pid 517370) maxrss: 491476
2025-11-01 16:19:08,079 - DEBUG - ya.test - _save_outputs: Command (pid 517370) minflt: 45398
2025-11-01 16:19:08,080 - DEBUG - ya.test - _save_outputs: Command (pid 517370) msgrcv: 0
2025-11-01 16:19:08,080 - DEBUG - ya.test - _save_outputs: Command (pid 517370) msgsnd: 0
2025-11-01 16:19:08,080 - DEBUG - ya.test - _save_outputs: Command (pid 517370) nivcsw: 178
2025-11-01 16:19:08,081 - DEBUG - ya.test - _save_outputs: Command (pid 517370) nsignals: 0
2025-11-01 16:19:08,081 - DEBUG - ya.test - _save_outputs: Command (pid 517370) nswap: 0
2025-11-01 16:19:08,081 - DEBUG - ya.test - _save_outputs: Command (pid 517370) nvcsw: 13
2025-11-01 16:19:08,081 - DEBUG - ya.test - _save_outputs: Command (pid 517370) oublock: 8
2025-11-01 16:19:08,082 - DEBUG - ya.test - _save_outputs: Command (pid 517370) stime: 0.188962
2025-11-01 16:19:08,082 - DEBUG - ya.test - _save_outputs: Command (pid 517370) utime: 0.49099099999999996
2025-11-01 16:19:08,082 - DEBUG - ya.test - _save_outputs: Command (pid 517370) wtime: 0.786
2025-11-01 16:19:08,082 - DEBUG - ya.test - _save_outputs: Command (pid 517370) output:
Git info:
    Commit: b759454d1e40b275ba8b2f5964202fc9f08a1c3c
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge b3001557f326a9ac3df9852d3eea5c83a6651a93 into 88f362bbf1115d2653ecf70400035099c5165741

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

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

2025-11-01 16:19:08,083 - DEBUG - ya.test - _save_outputs: Command (pid 517370) errors:

2025-11-01 16:19:08,083 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: b759454d1e40b275ba8b2f5964202fc9f08a1c3c
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge b3001557f326a9ac3df9852d3eea5c83a6651a93 into 88f362bbf1115d2653ecf70400035099c5165741

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

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

2025-11-01 16:19:08,197 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/y72z/0024f7/ydb/apps/ydbd/ydbd
2025-11-01 16:19:08,199 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6749
2025-11-01 16:19:08,201 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/y72z/0024f7/r3tmp/kikimr_cluster_89n1ilar
2025-11-01 16:19:08,239 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/y72z/0024f7/ydb/apps/ydbd/ydbd
2025-11-01 16:19:08,240 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2297
2025-11-01 16:19:08,240 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8361
2025-11-01 16:19:08,241 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/31761
2025-11-01 16:19:08,241 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17573
2025-11-01 16:19:08,242 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/y72z/0024f7/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs"
2025-11-01 16:19:08,243 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/y72z/0024f7/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/y72z/0024f7/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_zjz4qdvo.log --grpc-port=2297 --mon-port=8361 --ic-port=6749
2025-11-01 16:19:08,243 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:2297/1, disk_size 68719476736
2025-11-01 16:19:08,244 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/y72z/0024f7/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs"
2025-11-01 16:19:08,244 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/y72z/0024f7/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/y72z/0024f7/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_zjz4qdvo.log --grpc-port=2297 --mon-port=8361 --ic-port=6749
2025-11-01 16:19:08,245 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/y72z/0024f7/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/y72z/0024f7/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/y72z/0024f7/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_zjz4qdvo.log', '--grpc-port=2297', '--mon-port=8361', '--ic-port=6749')' in '/home/runner/.ya/build/build_root/y72z/0024f7/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1' (no wait)
2025-11-01 16:19:08,249 - DEBUG - ya.test - execute: Command pid: 517886
2025-11-01 16:19:08,249 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:2297/1
2025-11-01 16:19:08,251 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-01 16:19:08,252 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-01 16:19:08,252 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/0024f7/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:19:08,269 - DEBUG - ya.test - execute: Command pid: 517889
2025-11-01 16:19:08,963 - DEBUG - ya.test - _save_outputs: Command (pid 517889) rc: 0
2025-11-01 16:19:08,964 - DEBUG - ya.test - _save_outputs: Command (pid 517889) elapsed time (sec): 0.6932296752929688
2025-11-01 16:19:08,964 - DEBUG - ya.test - _save_outputs: Command (pid 517889) idrss: 0
2025-11-01 16:19:08,964 - DEBUG - ya.test - _save_outputs: Command (pid 517889) inblock: 0
2025-11-01 16:19:08,965 - DEBUG - ya.test - _save_outputs: Command (pid 517889) isrss: 0
2025-11-01 16:19:08,965 - DEBUG - ya.test - _save_outputs: Command (pid 517889) ixrss: 0
2025-11-01 16:19:08,965 - DEBUG - ya.test - _save_outputs: Command (pid 517889) majflt: 0
2025-11-01 16:19:08,965 - DEBUG - ya.test - _save_outputs: Command (pid 517889) maxrss: 500588
2025-11-01 16:19:08,966 - DEBUG - ya.test - _save_outputs: Command (pid 517889) minflt: 45362
2025-11-01 16:19:08,966 - DEBUG - ya.test - _save_outputs: Command (pid 517889) msgrcv: 0
2025-11-01 16:19:08,966 - DEBUG - ya.test - _save_outputs: Command (pid 517889) msgsnd: 0
2025-11-01 16:19:08,966 - DEBUG - ya.test - _save_outputs: Command (pid 517889) nivcsw: 56
2025-11-01 16:19:08,966 - DEBUG - ya.test - _save_outputs: Command (pid 517889) nsignals: 0
2025-11-01 16:19:08,966 - DEBUG - ya.test - _save_outputs: Command (pid 517889) nswap: 0
2025-11-01 16:19:08,967 - DEBUG - ya.test - _save_outputs: Command (pid 517889) nvcsw: 14
2025-11-01 16:19:08,967 - DEBUG - ya.test - _save_outputs: Command (pid 517889) oublock: 8
2025-11-01 16:19:08,967 - DEBUG - ya.test - _save_outputs: Command (pid 517889) stime: 0.197317
2025-11-01 16:19:08,967 - DEBUG - ya.test - _save_outputs: Command (pid 517889) utime: 0.42657599999999996
2025-11-01 16:19:08,967 - DEBUG - ya.test - _save_outputs: Command (pid 517889) wtime: 0.71
2025-11-01 16:19:08,968 - DEBUG - ya.test - _save_outputs: Command (pid 517889) output:
Git info:
    Commit: b759454d1e40b275ba8b2f5964202fc9f08a1c3c
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge b3001557f326a9ac3df9852d3eea5c83a6651a93 into 88f362bbf1115d2653ecf70400035099c5165741

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

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

2025-11-01 16:19:08,968 - DEBUG - ya.test - _save_outputs: Command (pid 517889) errors:

2025-11-01 16:19:08,969 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: b759454d1e40b275ba8b2f5964202fc9f08a1c3c
    Branch: 
    Author: Denis Khalikov <dennis.khalikov@gmail.com>
    Summary: Merge b3001557f326a9ac3df9852d3eea5c83a6651a93 into 88f362bbf1115d2653ecf70400035099c5165741
2025-11-01 16:19:14,014 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/y72z/0024f7/ydb/apps/ydbd/ydbd
2025-11-01 16:19:14,015 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/y72z/0024f7/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:2297', '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: 6749\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 16:19:14,016 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-01 16:19:14,017 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-01 16:19:14,017 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/0024f7/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:2297', '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: 6749\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 16:19:14,023 - DEBUG - ya.test - execute: Command pid: 520827
2025-11-01 16:19:15,135 - DEBUG - ya.test - _save_outputs: Command (pid 520827) rc: 0
2025-11-01 16:19:15,136 - DEBUG - ya.test - _save_outputs: Command (pid 520827) elapsed time (sec): 1.1111688613891602
2025-11-01 16:19:15,137 - DEBUG - ya.test - _save_outputs: Command (pid 520827) idrss: 0
2025-11-01 16:19:15,137 - DEBUG - ya.test - _save_outputs: Command (pid 520827) inblock: 0
2025-11-01 16:19:15,137 - DEBUG - ya.test - _save_outputs: Command (pid 520827) isrss: 0
2025-11-01 16:19:15,137 - DEBUG - ya.test - _save_outputs: Command (pid 520827) ixrss: 0
2025-11-01 16:19:15,138 - DEBUG - ya.test - _save_outputs: Command (pid 520827) majflt: 0
2025-11-01 16:19:15,138 - DEBUG - ya.test - _save_outputs: Command (pid 520827) maxrss: 592912
2025-11-01 16:19:15,138 - DEBUG - ya.test - _save_outputs: Command (pid 520827) minflt: 51672
2025-11-01 16:19:15,139 - DEBUG - ya.test - _save_outputs: Command (pid 520827) msgrcv: 0
2025-11-01 16:19:15,139 - DEBUG - ya.test - _save_outputs: Command (pid 520827) msgsnd: 0
2025-11-01 16:19:15,139 - DEBUG - ya.test - _save_outputs: Command (pid 520827) nivcsw: 164
2025-11-01 16:19:15,139 - DEBUG - ya.test - _save_outputs: Command (pid 520827) nsignals: 0
2025-11-01 16:19:15,140 - DEBUG - ya.test - _save_outputs: Command (pid 520827) nswap: 0
2025-11-01 16:19:15,140 - DEBUG - ya.test - _save_outputs: Command (pid 520827) nvcsw: 742
2025-11-01 16:19:15,140 - DEBUG - ya.test - _save_outputs: Command (pid 520827) oublock: 8
2025-11-01 16:19:15,145 - DEBUG - ya.test - _save_outputs: Command (pid 520827) stime: 0.22980399999999998
2025-11-01 16:19:15,145 - DEBUG - ya.test - _save_outputs: Command (pid 520827) utime: 0.499704
2025-11-01 16:19:15,146 - DEBUG - ya.test - _save_outputs: Command (pid 520827) wtime: 1.117
2025-11-01 16:19:15,146 - DEBUG - ya.test - _save_outputs: Command (pid 520827) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 16:19:15,146 - DEBUG - ya.test - _save_outputs: Command (pid 520827) errors:

2025-11-01 16:19:15,148 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/y72z/0024f7/ydb/apps/ydbd/ydbd
2025-11-01 16:19:15,153 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/y72z/0024f7/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:2297', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-11-01 16:19:15,154 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-01 16:19:15,155 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-01 16:19:15,156 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/y72z/0024f7/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:2297', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 16:19:15,165 - DEBUG - ya.test - execute: Command pid: 521472
2025-11-01 16:19:15,986 - DEBUG - ya.test - _save_outputs: Command (pid 521472) rc: 0
2025-11-01 16:19:15,987 - DEBUG - ya.test - _save_outputs: Command (pid 521472) elapsed time (sec): 0.8205175399780273
2025-11-01 16:19:15,987 - DEBUG - ya.test - _save_outputs: Command (pid 521472) idrss: 0
2025-11-01 16:19:15,988 - DEBUG - ya.test - _save_outputs: Command (pid 521472) inblock: 0
2025-11-01 16:19:15,988 - DEBUG - ya.test - _save_outputs: Command (pid 521472) isrss: 0
2025-11-01 16:19:15,988 - DEBUG - ya.test - _save_outputs: Command (pid 521472) ixrss: 0
2025-11-01 16:19:15,988 - DEBUG - ya.test - _save_outputs: Command (pid 521472) majflt: 0
2025-11-01 16:19:15,989 - DEBUG - ya.test - _save_outputs: Command (pid 521472) maxrss: 592996
2025-11-01 16:19:15,989 - DEBUG - ya.test - _save_outputs: Command (pid 521472) minflt: 51496
2025-11-01 16:19:15,989 - DEBUG - ya.test - _save_outputs: Command (pid 521472) msgrcv: 0
2025-11-01 16:19:15,990 - DEBUG - ya.test - _save_outputs: Command (pid 521472) msgsnd: 0
2025-11-01 16:19:15,990 - DEBUG - ya.test - _save_outputs: Command (pid 521472) nivcsw: 179
2025-11-01 16:19:15,990 - DEBUG - ya.test - _save_outputs: Command (pid 521472) nsignals: 0
2025-11-01 16:19:15,990 - DEBUG - ya.test - _save_outputs: Command (pid 521472) nswap: 0
2025-11-01 16:19:15,991 - DEBUG - ya.test - _save_outputs: Command (pid 521472) nvcsw: 431
2025-11-01 16:19:15,991 - DEBUG - ya.test - _save_outputs: Command (pid 521472) oublock: 8
2025-11-01 16:19:15,991 - DEBUG - ya.test - _save_outputs: Command (pid 521472) stime: 0.195525
2025-11-01 16:19:15,992 - DEBUG - ya.test - _save_outputs: Command (pid 521472) utime: 0.51044
2025-11-01 16:19:15,992 - DEBUG - ya.test - _save_outputs: Command (pid 521472) wtime: 0.83
2025-11-01 16:19:15,992 - DEBUG - ya.test - _save_outputs: Command (pid 521472) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-01 16:19:15,993 - DEBUG - ya.test - _save_outputs: Command (pid 521472) errors:

2025-11-01 16:19:15,993 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 16:19:16,105 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 16:19:16,146 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:19:16,161 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:19:16,162 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:19:16,208 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:19:16,209 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, a15963c6-dee5-4d1f-a871-2f9dac2e45fa, localhost:2297): creating call state
2025-11-01 16:19:16,210 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, a15963c6-dee5-4d1f-a871-2f9dac2e45fa, localhost:2297): request = { database: "/Root" }
2025-11-01 16:19:16,222 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, a15963c6-dee5-4d1f-a871-2f9dac2e45fa, localhost:2297): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-cjocww3of4.auto.internal" port: 2297 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-01 16:19:16,223 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-cjocww3of4.auto.internal:2297, location 1, ssl: False>]>
2025-11-01 16:19:16,224 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:19:16,261 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:2297
2025-11-01 16:19:16,262 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-01 16:19:16,263 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 1e444697-9a0a-4de0-926e-8f50d0de7648, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:16,263 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 1e444697-9a0a-4de0-926e-8f50d0de7648, ghrun-cjocww3of4.auto.internal:2297): request = {  }
2025-11-01 16:19:16,274 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 1e444697-9a0a-4de0-926e-8f50d0de7648, ghrun-cjocww3of4.auto.internal:2297): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" node_id: 1 }
2025-11-01 16:19:16,275 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, fcf53b81-4a1e-4d89-bd6f-1328ebd9d81a, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:16,275 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, fcf53b81-4a1e-4d89-bd6f-1328ebd9d81a, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" }
2025-11-01 16:19:16,279 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, fcf53b81-4a1e-4d89-bd6f-1328ebd9d81a, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:16,289 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:16,290 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be3c6919-547c-42dc-8248-072feda048aa, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:16,291 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be3c6919-547c-42dc-8248-072feda048aa, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:16,293 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be3c6919-547c-42dc-8248-072feda048aa, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:19,001 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:19,001 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-01 16:19:19,002 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:19,003 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 17298c91-8bdf-45d6-bd7c-369aab76e710, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:19,003 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 17298c91-8bdf-45d6-bd7c-369aab76e710, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:19,006 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 17298c91-8bdf-45d6-bd7c-369aab76e710, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:19,922 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:19,922 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:19,923 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 047d81cf-d484-45b2-a679-24c82897bed7, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:19,924 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 047d81cf-d484-45b2-a679-24c82897bed7, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:19,926 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 047d81cf-d484-45b2-a679-24c82897bed7, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:20,862 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:20,863 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:20,863 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 087f2a61-cef8-48d2-a847-41674d86b4a3, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:20,864 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 087f2a61-cef8-48d2-a847-41674d86b4a3, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:20,865 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 087f2a61-cef8-48d2-a847-41674d86b4a3, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:21,530 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:21,531 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:21,531 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0a1cabcd-f73d-41ce-8cad-812ade5515fb, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:21,532 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0a1cabcd-f73d-41ce-8cad-812ade5515fb, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:21,533 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0a1cabcd-f73d-41ce-8cad-812ade5515fb, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:22,954 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:22,955 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 1618432, 'BlobCount': 128}}
2025-11-01 16:19:22,955 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:22,956 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be04dc04-a5ba-4e17-88aa-9aaa4258ef72, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:22,956 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be04dc04-a5ba-4e17-88aa-9aaa4258ef72, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:22,960 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be04dc04-a5ba-4e17-88aa-9aaa4258ef72, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:23,778 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:23,778 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:23,779 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f05267f1-ecac-4729-9686-0c728779769b, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:23,780 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f05267f1-ecac-4729-9686-0c728779769b, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:23,781 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f05267f1-ecac-4729-9686-0c728779769b, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:24,031 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:24,032 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:24,033 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 91bdaadb-df15-4362-9b6a-953e232ea57d, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:24,034 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 91bdaadb-df15-4362-9b6a-953e232ea57d, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:24,035 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 91bdaadb-df15-4362-9b6a-953e232ea57d, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:24,303 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:24,304 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 3236864, 'BlobCount': 256}}
2025-11-01 16:19:24,304 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:24,305 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e5fa4440-9a15-4f8b-976f-5f90f184e5d9, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:24,305 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e5fa4440-9a15-4f8b-976f-5f90f184e5d9, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:24,306 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e5fa4440-9a15-4f8b-976f-5f90f184e5d9, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:25,162 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:25,163 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:25,164 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dac2579f-0ee4-4e68-b156-335796f26742, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:25,165 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dac2579f-0ee4-4e68-b156-335796f26742, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:25,166 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dac2579f-0ee4-4e68-b156-335796f26742, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:25,378 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:25,379 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:25,380 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ba4d273d-695f-41df-881d-df5e68f63da1, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:25,381 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ba4d273d-695f-41df-881d-df5e68f63da1, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:25,382 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ba4d273d-695f-41df-881d-df5e68f63da1, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:25,713 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:25,714 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 4855296, 'BlobCount': 384}}
2025-11-01 16:19:25,715 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:25,716 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0205c226-780c-4023-ac44-769e46671a49, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:25,717 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0205c226-780c-4023-ac44-769e46671a49, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:25,718 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0205c226-780c-4023-ac44-769e46671a49, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:26,762 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:26,763 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:26,764 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ffbb9f32-f3a0-4f18-a559-043059ece853, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:26,765 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ffbb9f32-f3a0-4f18-a559-043059ece853, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:26,766 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ffbb9f32-f3a0-4f18-a559-043059ece853, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:26,985 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:26,986 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:26,986 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eeaae2f5-bed2-454f-885e-95e26f890f5a, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:26,987 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eeaae2f5-bed2-454f-885e-95e26f890f5a, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:26,996 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eeaae2f5-bed2-454f-885e-95e26f890f5a, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:27,425 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:27,426 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 6473728, 'BlobCount': 512}}
2025-11-01 16:19:27,426 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:27,427 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cbf4f8c6-8873-45ea-ade6-9727e53da961, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:27,428 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cbf4f8c6-8873-45ea-ade6-9727e53da961, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:27,429 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cbf4f8c6-8873-45ea-ade6-9727e53da961, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:28,595 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:28,596 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:28,596 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4a7c3ff9-1916-43f5-a769-6f64400f0c25, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:28,597 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4a7c3ff9-1916-43f5-a769-6f64400f0c25, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:28,599 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4a7c3ff9-1916-43f5-a769-6f64400f0c25, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:28,918 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:28,919 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:28,920 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bbd92618-7b83-44e8-bc70-21e702f6a6b1, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:28,921 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bbd92618-7b83-44e8-bc70-21e702f6a6b1, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:28,922 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bbd92618-7b83-44e8-bc70-21e702f6a6b1, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:29,599 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:29,600 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 8092160, 'BlobCount': 640}}
2025-11-01 16:19:29,600 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:29,601 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2d436393-48c0-4ecf-bdea-9a3f871a6a0f, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:29,602 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2d436393-48c0-4ecf-bdea-9a3f871a6a0f, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:29,604 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2d436393-48c0-4ecf-bdea-9a3f871a6a0f, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:30,728 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:30,729 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:30,729 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3c2b4911-761a-4f6c-8591-e3d06e132ecb, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:30,730 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3c2b4911-761a-4f6c-8591-e3d06e132ecb, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:30,732 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3c2b4911-761a-4f6c-8591-e3d06e132ecb, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:31,024 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:31,025 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:31,026 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a15cfaad-9e7d-47ad-ad55-45bdc168f942, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:31,027 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a15cfaad-9e7d-47ad-ad55-45bdc168f942, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:31,029 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a15cfaad-9e7d-47ad-ad55-45bdc168f942, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:31,549 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:31,550 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 9710592, 'BlobCount': 768}}
2025-11-01 16:19:31,550 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:31,551 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a0f66197-bd12-496c-b3b4-a19313454a70, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:31,552 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a0f66197-bd12-496c-b3b4-a19313454a70, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:31,553 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a0f66197-bd12-496c-b3b4-a19313454a70, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:32,590 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:32,590 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:32,591 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 616eb9df-4b7f-429b-bb01-f085fbccdf40, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:32,592 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 616eb9df-4b7f-429b-bb01-f085fbccdf40, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:32,593 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 616eb9df-4b7f-429b-bb01-f085fbccdf40, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:32,884 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:32,885 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:32,886 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bbc3bb0e-75a6-474f-8658-7ae01ace125d, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:32,887 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bbc3bb0e-75a6-474f-8658-7ae01ace125d, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:32,888 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bbc3bb0e-75a6-474f-8658-7ae01ace125d, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:33,625 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:33,626 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 11329024, 'BlobCount': 896}}
2025-11-01 16:19:33,627 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:33,628 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 59a4f59c-e295-4e74-a199-6afbf00b8c5a, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:33,629 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 59a4f59c-e295-4e74-a199-6afbf00b8c5a, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:33,630 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 59a4f59c-e295-4e74-a199-6afbf00b8c5a, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:34,642 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:34,643 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:34,643 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 35686221-a17f-492e-b8d5-cd25b2623c32, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:34,644 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 35686221-a17f-492e-b8d5-cd25b2623c32, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:34,646 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 35686221-a17f-492e-b8d5-cd25b2623c32, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:34,890 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:34,891 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:34,892 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 32da0b71-f83b-4e65-a654-f53ecff4a9d9, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:34,892 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 32da0b71-f83b-4e65-a654-f53ecff4a9d9, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:34,894 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 32da0b71-f83b-4e65-a654-f53ecff4a9d9, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:35,420 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:35,421 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 12947456, 'BlobCount': 1024}}
2025-11-01 16:19:35,421 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:35,422 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 794a84a9-05ba-4ea2-973c-bbc921b18f3e, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:35,422 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 794a84a9-05ba-4ea2-973c-bbc921b18f3e, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:35,424 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 794a84a9-05ba-4ea2-973c-bbc921b18f3e, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:36,525 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:36,526 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:36,527 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 13509027-10da-4761-ad97-aab13ece1fb3, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:36,527 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 13509027-10da-4761-ad97-aab13ece1fb3, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:36,533 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 13509027-10da-4761-ad97-aab13ece1fb3, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:36,781 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:36,782 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:36,783 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 184d5687-d4b0-4177-a501-be26a4b2a0c6, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:36,784 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 184d5687-d4b0-4177-a501-be26a4b2a0c6, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:36,785 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 184d5687-d4b0-4177-a501-be26a4b2a0c6, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:37,382 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:37,383 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 14565888, 'BlobCount': 1152}}
2025-11-01 16:19:37,383 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:37,384 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c7d36f75-8267-492c-a400-32f21b3071c4, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:37,385 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c7d36f75-8267-492c-a400-32f21b3071c4, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:37,387 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c7d36f75-8267-492c-a400-32f21b3071c4, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:38,453 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:38,454 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:38,454 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2cd8aec6-263c-450a-b894-c10538b20bda, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:38,455 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2cd8aec6-263c-450a-b894-c10538b20bda, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:38,458 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2cd8aec6-263c-450a-b894-c10538b20bda, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:38,783 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:38,784 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:38,784 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d54e28f9-dfca-40bc-8f81-5b5ef47d5ebf, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:38,785 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d54e28f9-dfca-40bc-8f81-5b5ef47d5ebf, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:38,786 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d54e28f9-dfca-40bc-8f81-5b5ef47d5ebf, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:39,432 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:39,433 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 16184320, 'BlobCount': 1280}}
2025-11-01 16:19:39,433 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:39,434 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8b637b53-3572-4b4e-9066-b9b9bfeac64a, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:39,434 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8b637b53-3572-4b4e-9066-b9b9bfeac64a, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:39,435 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8b637b53-3572-4b4e-9066-b9b9bfeac64a, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:40,335 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:40,336 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:40,336 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ffe88b49-c877-47ec-9f83-05b7f4661e51, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:40,337 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ffe88b49-c877-47ec-9f83-05b7f4661e51, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:40,338 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ffe88b49-c877-47ec-9f83-05b7f4661e51, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:40,601 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:40,601 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:40,602 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 13c87cc9-14ce-433f-92b0-d5cd49ce9d59, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:40,603 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 13c87cc9-14ce-433f-92b0-d5cd49ce9d59, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:40,611 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 13c87cc9-14ce-433f-92b0-d5cd49ce9d59, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:40,836 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:40,837 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:40,837 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9ad99b86-ee4c-45ee-bee1-7af5752d21e5, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:40,838 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9ad99b86-ee4c-45ee-bee1-7af5752d21e5, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:40,839 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9ad99b86-ee4c-45ee-bee1-7af5752d21e5, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:43,940 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:53,976 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:53,977 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 72fbe091-b292-430a-910c-bd2057a991a7, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:53,978 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 72fbe091-b292-430a-910c-bd2057a991a7, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:53,979 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 72fbe091-b292-430a-910c-bd2057a991a7, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:58,086 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:58,122 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-01 16:19:58,123 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:19:58,124 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1152a445-8ebe-44e0-bd28-1ee7b1a3da49, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:19:58,125 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1152a445-8ebe-44e0-bd28-1ee7b1a3da49, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:58,126 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1152a445-8ebe-44e0-bd28-1ee7b1a3da49, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:01,050 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:11,086 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:11,086 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 801af386-e6c3-4654-a005-3a50b26715b9, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:11,087 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 801af386-e6c3-4654-a005-3a50b26715b9, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:11,089 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 801af386-e6c3-4654-a005-3a50b26715b9, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:15,067 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:15,105 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-01 16:20:15,105 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:15,106 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bf8ea3df-d3db-4cbb-b869-94e0c8702f98, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:15,107 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bf8ea3df-d3db-4cbb-b869-94e0c8702f98, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:15,112 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bf8ea3df-d3db-4cbb-b869-94e0c8702f98, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:15,413 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:15,416 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_18_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 18])
2025-11-01 16:20:15,418 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:15,419 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d6c242d3-464c-465b-a759-713d7b89264a, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:15,420 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d6c242d3-464c-465b-a759-713d7b89264a, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_18_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 18)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:15,422 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d6c242d3-464c-465b-a759-713d7b89264a, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:20,010 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:20,011 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_18_compression created
2025-11-01 16:20:20,011 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:20,012 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3485764a-ba05-4b58-84b9-a8c8acb65dff, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:20,013 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3485764a-ba05-4b58-84b9-a8c8acb65dff, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_18_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:20,019 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3485764a-ba05-4b58-84b9-a8c8acb65dff, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:20,954 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:20,955 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:20,956 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 527e4693-02c1-4a86-92cd-a6a516ec01f8, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:20,956 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 527e4693-02c1-4a86-92cd-a6a516ec01f8, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:20,959 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 527e4693-02c1-4a86-92cd-a6a516ec01f8, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:25,519 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:25,520 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:25,521 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7a522762-4707-4803-8596-75a4a5cdcfd1, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:25,522 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7a522762-4707-4803-8596-75a4a5cdcfd1, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:25,523 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7a522762-4707-4803-8596-75a4a5cdcfd1, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:26,514 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:26,515 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:26,515 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, af0cc45e-8d06-4420-8668-2f664a36f35c, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:26,516 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, af0cc45e-8d06-4420-8668-2f664a36f35c, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:26,517 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, af0cc45e-8d06-4420-8668-2f664a36f35c, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:28,574 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:28,575 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-11-01 16:20:28,575 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:28,576 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2da0fde1-73a0-4dab-a09a-bd84e54cfeb1, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:28,577 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2da0fde1-73a0-4dab-a09a-bd84e54cfeb1, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:28,578 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2da0fde1-73a0-4dab-a09a-bd84e54cfeb1, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:32,890 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:32,891 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:32,891 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, deb4cfcd-2e8d-4038-8db4-352919b8f1cf, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:32,892 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, deb4cfcd-2e8d-4038-8db4-352919b8f1cf, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:32,897 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, deb4cfcd-2e8d-4038-8db4-352919b8f1cf, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:33,213 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:33,214 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:33,215 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7dbe3f72-aa07-4437-a959-d9da78ec07c2, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:33,216 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7dbe3f72-aa07-4437-a959-d9da78ec07c2, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:33,217 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7dbe3f72-aa07-4437-a959-d9da78ec07c2, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:33,589 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:33,590 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-11-01 16:20:33,591 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:33,592 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 96549882-2cf5-4f90-aee8-8b4a6d303b5c, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:33,592 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 96549882-2cf5-4f90-aee8-8b4a6d303b5c, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:33,594 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 96549882-2cf5-4f90-aee8-8b4a6d303b5c, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:37,657 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:37,658 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:37,659 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 13b4c161-1678-4b5f-8ab2-e30875ae9076, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:37,660 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 13b4c161-1678-4b5f-8ab2-e30875ae9076, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:37,666 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 13b4c161-1678-4b5f-8ab2-e30875ae9076, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:38,061 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:38,062 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:38,063 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9379ba9e-3af5-4349-b7e7-1a821e06bd7a, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:38,064 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9379ba9e-3af5-4349-b7e7-1a821e06bd7a, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:38,065 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9379ba9e-3af5-4349-b7e7-1a821e06bd7a, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:38,476 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:38,477 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-11-01 16:20:38,477 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:38,478 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a3cf2ab5-b63c-4789-adfc-18bb08e587a1, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:38,479 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a3cf2ab5-b63c-4789-adfc-18bb08e587a1, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:38,480 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a3cf2ab5-b63c-4789-adfc-18bb08e587a1, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:43,174 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:43,175 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:43,176 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 89e38e2d-d3db-4d28-85a8-d71de53325a0, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:43,176 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 89e38e2d-d3db-4d28-85a8-d71de53325a0, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:43,178 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 89e38e2d-d3db-4d28-85a8-d71de53325a0, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:43,435 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:43,435 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:43,436 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 09b21cbf-49d2-4fb4-9eec-045980db024f, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:43,437 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 09b21cbf-49d2-4fb4-9eec-045980db024f, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:43,439 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 09b21cbf-49d2-4fb4-9eec-045980db024f, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:43,954 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:43,955 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-11-01 16:20:43,956 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:43,956 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 855f8d69-abeb-4eed-a12e-1c5690060b16, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:43,957 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 855f8d69-abeb-4eed-a12e-1c5690060b16, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:43,959 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 855f8d69-abeb-4eed-a12e-1c5690060b16, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:48,389 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:48,390 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:48,390 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fef31509-b5ab-4dc6-af5b-0d03b79c175b, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:48,391 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fef31509-b5ab-4dc6-af5b-0d03b79c175b, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:48,397 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fef31509-b5ab-4dc6-af5b-0d03b79c175b, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:48,754 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:48,755 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:48,756 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3d384cf1-9059-4da2-95e2-6dc10cc39d39, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:48,757 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3d384cf1-9059-4da2-95e2-6dc10cc39d39, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:48,769 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3d384cf1-9059-4da2-95e2-6dc10cc39d39, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:49,320 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:49,321 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-11-01 16:20:49,322 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:49,323 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 923810e2-3fdd-4420-91c8-0a5e47e12f81, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:49,324 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 923810e2-3fdd-4420-91c8-0a5e47e12f81, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:49,325 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 923810e2-3fdd-4420-91c8-0a5e47e12f81, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:53,149 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:53,150 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:53,151 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9cc482c5-facf-4402-bf97-1b1274ea41bf, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:53,152 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9cc482c5-facf-4402-bf97-1b1274ea41bf, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:53,153 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9cc482c5-facf-4402-bf97-1b1274ea41bf, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:53,474 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:53,475 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:53,476 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 464779aa-51f1-431d-9854-315e9135625a, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:53,476 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 464779aa-51f1-431d-9854-315e9135625a, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:53,517 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 464779aa-51f1-431d-9854-315e9135625a, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:54,187 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:54,188 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-11-01 16:20:54,188 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:54,189 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 469ea054-8825-4013-a501-77554bab5fc0, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:54,190 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 469ea054-8825-4013-a501-77554bab5fc0, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:54,191 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 469ea054-8825-4013-a501-77554bab5fc0, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:58,126 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:58,126 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:58,127 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 72ce7dd8-4d3b-494f-a87a-44c89ce57a69, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:58,128 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 72ce7dd8-4d3b-494f-a87a-44c89ce57a69, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:58,129 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 72ce7dd8-4d3b-494f-a87a-44c89ce57a69, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:58,442 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:58,442 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:58,443 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 37b90b1f-455e-43a7-91b5-2b1ec82a0698, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:58,444 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 37b90b1f-455e-43a7-91b5-2b1ec82a0698, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:58,445 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 37b90b1f-455e-43a7-91b5-2b1ec82a0698, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:20:59,127 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:59,128 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-11-01 16:20:59,128 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:20:59,129 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4712ef68-7db0-4652-a02d-fdd14ab44c4d, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:20:59,130 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4712ef68-7db0-4652-a02d-fdd14ab44c4d, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:20:59,131 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4712ef68-7db0-4652-a02d-fdd14ab44c4d, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:03,581 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:03,582 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:03,583 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 72bdea40-53c6-4691-96c4-469787aeeed4, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:03,584 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 72bdea40-53c6-4691-96c4-469787aeeed4, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:03,589 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 72bdea40-53c6-4691-96c4-469787aeeed4, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:03,942 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:03,943 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:03,943 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c90c6265-6ab6-448b-afbc-0c2965ff0f6f, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:03,944 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c90c6265-6ab6-448b-afbc-0c2965ff0f6f, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:03,945 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c90c6265-6ab6-448b-afbc-0c2965ff0f6f, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:04,545 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:04,545 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-11-01 16:21:04,546 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:04,546 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0baaa43b-7c4c-4c9a-ba42-172d743c5ac6, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:04,547 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0baaa43b-7c4c-4c9a-ba42-172d743c5ac6, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:04,549 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0baaa43b-7c4c-4c9a-ba42-172d743c5ac6, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:08,705 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:08,705 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:08,706 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cfe071ef-deff-47ad-b25d-20977646aec9, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:08,706 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cfe071ef-deff-47ad-b25d-20977646aec9, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:08,707 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cfe071ef-deff-47ad-b25d-20977646aec9, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:09,024 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:09,025 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:09,025 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2040caa5-4c57-4a03-b6df-50920d598e0b, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:09,026 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2040caa5-4c57-4a03-b6df-50920d598e0b, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:09,027 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2040caa5-4c57-4a03-b6df-50920d598e0b, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:09,365 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:21:09,365 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-01 16:21:09,385 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:21:09,386 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, ba4eccb8-6326-470e-af26-54b2153f386e, localhost:2297): creating call state
2025-11-01 16:21:09,387 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, ba4eccb8-6326-470e-af26-54b2153f386e, localhost:2297): request = { database: "/Root" }
2025-11-01 16:21:09,403 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, ba4eccb8-6326-470e-af26-54b2153f386e, localhost:2297): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-cjocww3of4.auto.internal" port: 2297 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-01 16:21:09,404 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-cjocww3of4.auto.internal:2297, location 1, ssl: False>]>
2025-11-01 16:21:09,404 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:2297
2025-11-01 16:21:09,671 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:09,672 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-11-01 16:21:09,672 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:09,673 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cf7aab50-fcad-4db5-8047-f25ebde3e1b9, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:09,674 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cf7aab50-fcad-4db5-8047-f25ebde3e1b9, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:09,675 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cf7aab50-fcad-4db5-8047-f25ebde3e1b9, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:13,503 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:13,504 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:13,504 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 67794e19-d71a-4159-97f9-63234b43f2e7, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:13,505 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 67794e19-d71a-4159-97f9-63234b43f2e7, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:13,507 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 67794e19-d71a-4159-97f9-63234b43f2e7, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:13,926 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:13,927 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:13,928 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3e20b2e1-b11d-484e-b800-eef83949af15, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:13,929 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3e20b2e1-b11d-484e-b800-eef83949af15, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:13,931 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3e20b2e1-b11d-484e-b800-eef83949af15, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:14,630 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:14,631 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-11-01 16:21:14,631 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:14,632 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2a0ee618-ade0-485d-a207-e0644c563037, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:14,633 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2a0ee618-ade0-485d-a207-e0644c563037, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_18_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:14,634 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2a0ee618-ade0-485d-a207-e0644c563037, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:15,864 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:15,865 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:15,866 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 93ef1747-99dd-4d21-9279-b91fabebec72, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:15,867 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 93ef1747-99dd-4d21-9279-b91fabebec72, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:15,868 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 93ef1747-99dd-4d21-9279-b91fabebec72, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:16,196 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:16,197 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:16,198 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2bf9e895-0ec4-449c-ae5e-439f34a1539f, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:16,206 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2bf9e895-0ec4-449c-ae5e-439f34a1539f, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:16,207 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2bf9e895-0ec4-449c-ae5e-439f34a1539f, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:16,533 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:16,534 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:16,535 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1d82679d-0098-4bc3-b2af-cdff52d352fd, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:16,536 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1d82679d-0098-4bc3-b2af-cdff52d352fd, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:16,537 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1d82679d-0098-4bc3-b2af-cdff52d352fd, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:21,401 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:31,442 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:31,443 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fd6c1ea8-2fcd-403e-9c9b-a568793fcf76, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:31,444 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fd6c1ea8-2fcd-403e-9c9b-a568793fcf76, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:31,445 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fd6c1ea8-2fcd-403e-9c9b-a568793fcf76, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:33,451 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:33,495 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_18_compression`, volumes `value` (8000000, 1540864)
2025-11-01 16:21:33,496 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:33,497 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, db73b2a9-13ce-4a6f-9216-58c98b3c5d63, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:33,498 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, db73b2a9-13ce-4a6f-9216-58c98b3c5d63, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:33,499 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, db73b2a9-13ce-4a6f-9216-58c98b3c5d63, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:33,994 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:33,995 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:33,995 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b83a5d1c-b25f-42c6-9a04-ab6322bbd533, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:33,996 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b83a5d1c-b25f-42c6-9a04-ab6322bbd533, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:33,998 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b83a5d1c-b25f-42c6-9a04-ab6322bbd533, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:35,639 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:45,653 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:45,653 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c81cd64f-0149-4d07-b84b-24dd676b569e, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:45,654 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c81cd64f-0149-4d07-b84b-24dd676b569e, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:45,655 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c81cd64f-0149-4d07-b84b-24dd676b569e, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:46,319 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:56,325 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:56,326 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b6af1ff6-d196-4262-a5e9-c5743fc27321, ghrun-cjocww3of4.auto.internal:2297): creating call state
2025-11-01 16:21:56,326 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b6af1ff6-d196-4262-a5e9-c5743fc27321, ghrun-cjocww3of4.auto.internal:2297): request = { session_id: "ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:21:56,328 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b6af1ff6-d196-4262-a5e9-c5743fc27321, ghrun-cjocww3of4.auto.internal:2297): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:21:57,076 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YWU4ZDA2YzctNWZmOTkxNzUtMzdjMDA3OS1jZDE3YzRhNw==
2025-11-01 16:21:57,081 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_18_compression`, volumes `value` (8000000, 1497984)
2025-11-01 16:21:57,081 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_18_compression` 8092160 / 1497984: 5.402033666581218
2025-11-01 16:21:57,083 - INFO - ya.test - pytest_runtest_teardown: Test teardown
