2025-12-05 16:18:03,367 - INFO - ya.test.23341  - pytest_runtest_setup: ####################################################################################################
2025-12-05 16:18:03,367 - INFO - ya.test.23341  - pytest_runtest_setup: test_all_supported_compression[zstd_20_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 20]
2025-12-05 16:18:03,367 - INFO - ya.test.23341  - pytest_runtest_setup: ####################################################################################################
2025-12-05 16:18:03,368 - INFO - ya.test.23341  - pytest_runtest_setup: Test setup
2025-12-05 16:18:03,387 - DEBUG - ya.test.23341  - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-05 16:18:03,393 - DEBUG - ya.test.23341  - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-05 16:18:03,397 - DEBUG - ya.test.23341  - execute: Executing '['/home/runner/.ya/build/build_root/nihu/001704/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-05 16:18:03,425 - DEBUG - ya.test.23341  - execute: Command pid: 24737
2025-12-05 16:18:14,457 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) rc: 0
2025-12-05 16:18:14,458 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) elapsed time (sec): 11.026182174682617
2025-12-05 16:18:14,458 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) idrss: 0
2025-12-05 16:18:14,458 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) inblock: 0
2025-12-05 16:18:14,459 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) isrss: 0
2025-12-05 16:18:14,459 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) ixrss: 0
2025-12-05 16:18:14,459 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) majflt: 0
2025-12-05 16:18:14,459 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) maxrss: 487664
2025-12-05 16:18:14,460 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) minflt: 45712
2025-12-05 16:18:14,460 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) msgrcv: 0
2025-12-05 16:18:14,460 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) msgsnd: 0
2025-12-05 16:18:14,461 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) nivcsw: 988
2025-12-05 16:18:14,461 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) nsignals: 0
2025-12-05 16:18:14,461 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) nswap: 0
2025-12-05 16:18:14,461 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) nvcsw: 14
2025-12-05 16:18:14,461 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) oublock: 8
2025-12-05 16:18:14,462 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) stime: 8.036396
2025-12-05 16:18:14,462 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) utime: 0.468975
2025-12-05 16:18:14,465 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) wtime: 11.057
2025-12-05 16:18:14,470 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) output:
Git info:
    Commit: 7e4a069eed48cd0542e43cfcd8d71021826caed7
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge a01fb38f4f5fc409f3a42967229b198aa52519b3 into 3022ce6da3be33dce544958fe431efb7eb8af83a
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-a66hhqwkt4    Host information: 
        Linux ghrun-a66hhqwkt4 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/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-12-05 16:18:14,475 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 24737) errors:

2025-12-05 16:18:14,486 - INFO - __tests__.base.23341  - _setup_ydb: Git info:
    Commit: 7e4a069eed48cd0542e43cfcd8d71021826caed7
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge a01fb38f4f5fc409f3a42967229b198aa52519b3 into 3022ce6da3be33dce544958fe431efb7eb8af83a
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-a66hhqwkt4    Host information: 
        Linux ghrun-a66hhqwkt4 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/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-12-05 16:18:16,415 - DEBUG - ya.test.23341  - get_binary: Binary was found by /home/runner/.ya/build/build_root/nihu/001704/ydb/apps/ydbd/ydbd
2025-12-05 16:18:16,428 - DEBUG - library.python.filelock.23341  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27369
2025-12-05 16:18:16,443 - DEBUG - ydb.tests.library.harness.kikimr_runner.23341  - start: Working directory: /home/runner/.ya/build/build_root/nihu/001704/r3tmp/kikimr_cluster_a7x1g_4e
2025-12-05 16:18:16,657 - DEBUG - ya.test.23341  - get_binary: Binary was found by /home/runner/.ya/build/build_root/nihu/001704/ydb/apps/ydbd/ydbd
2025-12-05 16:18:16,661 - DEBUG - library.python.filelock.23341  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3870
2025-12-05 16:18:16,668 - DEBUG - library.python.filelock.23341  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/16018
2025-12-05 16:18:16,673 - DEBUG - library.python.filelock.23341  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23650
2025-12-05 16:18:16,675 - DEBUG - library.python.filelock.23341  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24191
2025-12-05 16:18:16,683 - INFO - ydb.tests.library.harness.kikimr_runner.23341  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/nihu/001704/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/kikimr_configs"
2025-12-05 16:18:16,687 - INFO - ydb.tests.library.harness.kikimr_runner.23341  - __make_run_command: Final command: /home/runner/.ya/build/build_root/nihu/001704/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/nihu/001704/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/node_1/logfile_0o78780b.log --grpc-port=3870 --mon-port=16018 --ic-port=27369
2025-12-05 16:18:16,691 - DEBUG - ydb.tests.library.harness.kikimr_runner.23341  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:3870/1, disk_size 68719476736
2025-12-05 16:18:16,697 - INFO - ydb.tests.library.harness.kikimr_runner.23341  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/nihu/001704/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/kikimr_configs"
2025-12-05 16:18:16,701 - INFO - ydb.tests.library.harness.kikimr_runner.23341  - __make_run_command: Final command: /home/runner/.ya/build/build_root/nihu/001704/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/nihu/001704/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/node_1/logfile_0o78780b.log --grpc-port=3870 --mon-port=16018 --ic-port=27369
2025-12-05 16:18:16,716 - DEBUG - ya.test.23341  - execute: Executing '('/home/runner/.ya/build/build_root/nihu/001704/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/nihu/001704/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/nihu/001704/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/node_1/logfile_0o78780b.log', '--grpc-port=3870', '--mon-port=16018', '--ic-port=27369')' in '/home/runner/.ya/build/build_root/nihu/001704/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/node_1' (no wait)
2025-12-05 16:18:16,755 - DEBUG - ya.test.23341  - execute: Command pid: 25314
2025-12-05 16:18:16,762 - INFO - ydb.tests.library.harness.kikimr_runner.23341  - start: Started node localhost:3870/1
2025-12-05 16:18:16,775 - DEBUG - ya.test.23341  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-05 16:18:16,779 - DEBUG - ya.test.23341  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-05 16:18:16,782 - DEBUG - ya.test.23341  - execute: Executing '['/home/runner/.ya/build/build_root/nihu/001704/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-05 16:18:16,809 - DEBUG - ya.test.23341  - execute: Command pid: 25315
2025-12-05 16:18:22,273 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) rc: 0
2025-12-05 16:18:22,274 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) elapsed time (sec): 5.452073097229004
2025-12-05 16:18:22,274 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) idrss: 0
2025-12-05 16:18:22,274 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) inblock: 0
2025-12-05 16:18:22,275 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) isrss: 0
2025-12-05 16:18:22,275 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) ixrss: 0
2025-12-05 16:18:22,275 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) majflt: 0
2025-12-05 16:18:22,276 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) maxrss: 499048
2025-12-05 16:18:22,276 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) minflt: 45893
2025-12-05 16:18:22,276 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) msgrcv: 0
2025-12-05 16:18:22,277 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) msgsnd: 0
2025-12-05 16:18:22,277 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) nivcsw: 510
2025-12-05 16:18:22,283 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) nsignals: 0
2025-12-05 16:18:22,283 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) nswap: 0
2025-12-05 16:18:22,284 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) nvcsw: 14
2025-12-05 16:18:22,284 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) oublock: 8
2025-12-05 16:18:22,284 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) stime: 2.995257
2025-12-05 16:18:22,284 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) utime: 0.503483
2025-12-05 16:18:22,285 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) wtime: 5.478
2025-12-05 16:18:22,285 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) output:
Git info:
    Commit: 7e4a069eed48cd0542e43cfcd8d71021826caed7
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge a01fb38f4f5fc409f3a42967229b198aa52519b3 into 3022ce6da3be33dce544958fe431efb7eb8af83a
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-a66hhqwkt4    Host information: 
        Linux ghrun-a66hhqwkt4 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/10302026024/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10302026024/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-12-05 16:18:22,286 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 25315) errors:

2025-12-05 16:18:22,286 - INFO - ydb.tests.library.harness.kikimr_runner.23341  - start: Node 1 version:
Git info:
    Commit: 7e4a069eed48cd0542e43cfcd8d71021826caed7
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge a01fb38f4f5fc409f3a42967229b198aa52519b3 into 3022ce6da3be33dce544958fe431efb7eb8af83a
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-a66hhqwkt4    Host information: 
        Linux ghrun-a66hhqwkt4 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-05 16:18:28,959 - DEBUG - ya.test.23341  - get_binary: Binary was found by /home/runner/.ya/build/build_root/nihu/001704/ydb/apps/ydbd/ydbd
2025-12-05 16:18:28,967 - DEBUG - ydb.tests.library.harness.kikimr_runner.23341  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/nihu/001704/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:3870', '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: 27369\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-05 16:18:28,968 - DEBUG - ya.test.23341  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-05 16:18:28,969 - DEBUG - ya.test.23341  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-05 16:18:28,969 - DEBUG - ya.test.23341  - execute: Executing '['/home/runner/.ya/build/build_root/nihu/001704/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:3870', '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: 27369\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-05 16:18:28,977 - DEBUG - ya.test.23341  - execute: Command pid: 29996
2025-12-05 16:18:30,357 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) rc: 0
2025-12-05 16:18:30,358 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) elapsed time (sec): 1.3789913654327393
2025-12-05 16:18:30,358 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) idrss: 0
2025-12-05 16:18:30,358 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) inblock: 0
2025-12-05 16:18:30,359 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) isrss: 0
2025-12-05 16:18:30,359 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) ixrss: 0
2025-12-05 16:18:30,359 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) majflt: 0
2025-12-05 16:18:30,360 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) maxrss: 599024
2025-12-05 16:18:30,360 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) minflt: 52069
2025-12-05 16:18:30,360 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) msgrcv: 0
2025-12-05 16:18:30,360 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) msgsnd: 0
2025-12-05 16:18:30,361 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) nivcsw: 213
2025-12-05 16:18:30,361 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) nsignals: 0
2025-12-05 16:18:30,361 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) nswap: 0
2025-12-05 16:18:30,362 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) nvcsw: 537
2025-12-05 16:18:30,362 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) oublock: 8
2025-12-05 16:18:30,362 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) stime: 0.34995699999999996
2025-12-05 16:18:30,362 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) utime: 0.569016
2025-12-05 16:18:30,363 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) wtime: 1.387
2025-12-05 16:18:30,363 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-05 16:18:30,363 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 29996) errors:

2025-12-05 16:18:30,365 - DEBUG - ya.test.23341  - get_binary: Binary was found by /home/runner/.ya/build/build_root/nihu/001704/ydb/apps/ydbd/ydbd
2025-12-05 16:18:30,411 - DEBUG - ydb.tests.library.harness.kikimr_runner.23341  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/nihu/001704/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:3870', '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-12-05 16:18:30,412 - DEBUG - ya.test.23341  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-05 16:18:30,425 - DEBUG - ya.test.23341  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-05 16:18:30,457 - DEBUG - ya.test.23341  - execute: Executing '['/home/runner/.ya/build/build_root/nihu/001704/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:3870', '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-12-05 16:18:30,469 - DEBUG - ya.test.23341  - execute: Command pid: 30973
2025-12-05 16:18:31,845 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) rc: 0
2025-12-05 16:18:31,845 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) elapsed time (sec): 1.372950792312622
2025-12-05 16:18:31,846 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) idrss: 0
2025-12-05 16:18:31,846 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) inblock: 0
2025-12-05 16:18:31,846 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) isrss: 0
2025-12-05 16:18:31,847 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) ixrss: 0
2025-12-05 16:18:31,847 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) majflt: 0
2025-12-05 16:18:31,847 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) maxrss: 599024
2025-12-05 16:18:31,847 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) minflt: 52136
2025-12-05 16:18:31,848 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) msgrcv: 0
2025-12-05 16:18:31,848 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) msgsnd: 0
2025-12-05 16:18:31,848 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) nivcsw: 231
2025-12-05 16:18:31,848 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) nsignals: 0
2025-12-05 16:18:31,849 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) nswap: 0
2025-12-05 16:18:31,866 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) nvcsw: 603
2025-12-05 16:18:31,867 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) oublock: 8
2025-12-05 16:18:31,867 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) stime: 0.24406
2025-12-05 16:18:31,867 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) utime: 0.576682
2025-12-05 16:18:31,868 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) wtime: 1.385
2025-12-05 16:18:31,868 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-05 16:18:31,868 - DEBUG - ya.test.23341  - _save_outputs: Command (pid 30973) errors:

2025-12-05 16:18:31,873 - INFO - ydb.tests.library.harness.kikimr_runner.23341  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-05 16:18:32,219 - INFO - ydb.tests.library.harness.kikimr_runner.23341  - __run: Cluster started and initialized
2025-12-05 16:18:32,461 - DEBUG - ydb.connection.23341  - 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-12-05 16:18:32,516 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.23341  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-05 16:18:32,517 - DEBUG - ydb.connection.23341  - 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-12-05 16:18:32,585 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.23341  - context_resolve: Resolving endpoints for database /Root
2025-12-05 16:18:32,586 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ListEndpoints, 80fb5b47-8837-4f6d-86db-4044c4e65196, localhost:3870): creating call state
2025-12-05 16:18:32,587 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ListEndpoints, 80fb5b47-8837-4f6d-86db-4044c4e65196, localhost:3870): request = { database: "/Root" }
2025-12-05 16:18:32,604 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ListEndpoints, 80fb5b47-8837-4f6d-86db-4044c4e65196, localhost:3870): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-a66hhqwkt4.auto.internal" port: 3870 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-12-05 16:18:32,611 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.23341  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-a66hhqwkt4.auto.internal:3870, location 1, ssl: False>]>
2025-12-05 16:18:32,611 - DEBUG - ydb.connection.23341  - 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-12-05 16:18:32,669 - DEBUG - ydb.connection.23341  - close: Closing channel for endpoint localhost:3870
2025-12-05 16:18:32,670 - DEBUG - ydb.query.pool.23341  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-05 16:18:32,677 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(CreateSession, efcd04fa-173b-43b4-a983-219482139798, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:32,677 - DEBUG - ydb.connection.23341  - _log_request: RpcState(CreateSession, efcd04fa-173b-43b4-a983-219482139798, ghrun-a66hhqwkt4.auto.internal:3870): request = {  }
2025-12-05 16:18:32,684 - DEBUG - ydb.connection.23341  - _log_response: RpcState(CreateSession, efcd04fa-173b-43b4-a983-219482139798, ghrun-a66hhqwkt4.auto.internal:3870): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" node_id: 1 }
2025-12-05 16:18:32,691 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(AttachSession, 3ea465c3-c111-4992-ad88-83ad279e2c32, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:32,706 - DEBUG - ydb.connection.23341  - _log_request: RpcState(AttachSession, 3ea465c3-c111-4992-ad88-83ad279e2c32, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" }
2025-12-05 16:18:32,722 - DEBUG - ydb.connection.23341  - _log_response: RpcState(AttachSession, 3ea465c3-c111-4992-ad88-83ad279e2c32, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:32,733 - DEBUG - ydb.query.pool.23341  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:32,749 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 0557f5d0-70a8-483b-b75f-311eab47e82e, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:32,794 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 0557f5d0-70a8-483b-b75f-311eab47e82e, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:32,795 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 0557f5d0-70a8-483b-b75f-311eab47e82e, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:38,281 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:38,282 - INFO - __tests__.alter_compression.23341  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-12-05 16:18:38,283 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:38,283 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, f8205132-9ed4-4a64-a15f-49101686f970, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:38,284 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, f8205132-9ed4-4a64-a15f-49101686f970, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:38,286 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, f8205132-9ed4-4a64-a15f-49101686f970, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:39,673 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:39,674 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:39,703 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 542c35ab-c0d6-4d0a-a090-0c18ef949fb5, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:39,704 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 542c35ab-c0d6-4d0a-a090-0c18ef949fb5, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:39,713 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 542c35ab-c0d6-4d0a-a090-0c18ef949fb5, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:41,037 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:41,038 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:41,039 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, c89556bc-8f40-4242-8c38-55bb9d7b5d79, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:41,039 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, c89556bc-8f40-4242-8c38-55bb9d7b5d79, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:41,041 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, c89556bc-8f40-4242-8c38-55bb9d7b5d79, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:42,306 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:42,307 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:42,307 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 5e0d15b0-025d-48a2-97bf-3ca9f58bf2fe, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:42,308 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 5e0d15b0-025d-48a2-97bf-3ca9f58bf2fe, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:42,317 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 5e0d15b0-025d-48a2-97bf-3ca9f58bf2fe, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:44,338 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:44,361 - INFO - __tests__.alter_compression.23341  - 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-12-05 16:18:44,362 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:44,363 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, ec961eb5-5a8c-4ad5-bdd5-69342a11ed7e, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:44,364 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, ec961eb5-5a8c-4ad5-bdd5-69342a11ed7e, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:44,368 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, ec961eb5-5a8c-4ad5-bdd5-69342a11ed7e, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:45,372 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:45,373 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:45,374 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 31f01814-8585-4d96-875b-fd6483f4c88d, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:45,375 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 31f01814-8585-4d96-875b-fd6483f4c88d, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:45,376 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 31f01814-8585-4d96-875b-fd6483f4c88d, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:45,690 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:45,716 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:45,717 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 4038fb0d-0b2f-4e21-a4a2-aaeaf0da33b5, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:45,726 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 4038fb0d-0b2f-4e21-a4a2-aaeaf0da33b5, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:45,727 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 4038fb0d-0b2f-4e21-a4a2-aaeaf0da33b5, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:46,117 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:46,118 - INFO - __tests__.alter_compression.23341  - 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-12-05 16:18:46,118 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:46,146 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 207f01b6-cc53-474f-8496-cb40fee4e8f3, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:46,172 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 207f01b6-cc53-474f-8496-cb40fee4e8f3, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:46,173 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 207f01b6-cc53-474f-8496-cb40fee4e8f3, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:47,409 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:47,410 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:47,411 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 51d62fba-560b-493f-b579-c2fe09925d2e, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:47,411 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 51d62fba-560b-493f-b579-c2fe09925d2e, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:47,493 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 51d62fba-560b-493f-b579-c2fe09925d2e, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:47,747 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:47,814 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:47,815 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 12a255ed-2671-49de-b349-3e694f24a54d, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:47,815 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 12a255ed-2671-49de-b349-3e694f24a54d, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:47,817 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 12a255ed-2671-49de-b349-3e694f24a54d, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:48,267 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:48,268 - INFO - __tests__.alter_compression.23341  - 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-12-05 16:18:48,269 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:48,270 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 34303023-fb7b-4bd1-bc6b-5b2d6d54ccaa, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:48,279 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 34303023-fb7b-4bd1-bc6b-5b2d6d54ccaa, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:48,312 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 34303023-fb7b-4bd1-bc6b-5b2d6d54ccaa, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:49,507 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:49,570 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:49,571 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, d3ce37f1-09fa-4fe5-9692-cc64c934c19b, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:49,572 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, d3ce37f1-09fa-4fe5-9692-cc64c934c19b, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:49,577 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, d3ce37f1-09fa-4fe5-9692-cc64c934c19b, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:49,874 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:49,894 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:49,895 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, b954ede8-e93f-42f9-b200-e1de86c9346b, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:49,896 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, b954ede8-e93f-42f9-b200-e1de86c9346b, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:49,901 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, b954ede8-e93f-42f9-b200-e1de86c9346b, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:50,681 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:50,738 - INFO - __tests__.alter_compression.23341  - 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-12-05 16:18:50,738 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:50,739 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, b9d43cf8-48ab-4ab3-a004-7391358bfdc2, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:50,740 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, b9d43cf8-48ab-4ab3-a004-7391358bfdc2, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:50,814 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, b9d43cf8-48ab-4ab3-a004-7391358bfdc2, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:52,029 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:52,030 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:52,031 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 2dfaa479-a69a-47a5-98d7-3053099dcc70, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:52,032 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 2dfaa479-a69a-47a5-98d7-3053099dcc70, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:52,038 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 2dfaa479-a69a-47a5-98d7-3053099dcc70, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:52,460 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:52,461 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:52,461 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, f58ad14f-cb67-46c7-9008-0fa0f9c57ffc, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:52,462 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, f58ad14f-cb67-46c7-9008-0fa0f9c57ffc, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:52,471 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, f58ad14f-cb67-46c7-9008-0fa0f9c57ffc, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:53,061 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:53,062 - INFO - __tests__.alter_compression.23341  - 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-12-05 16:18:53,063 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:53,063 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 1cafbe9c-822b-4e9d-9c24-03d52a607118, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:53,064 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 1cafbe9c-822b-4e9d-9c24-03d52a607118, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:53,065 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 1cafbe9c-822b-4e9d-9c24-03d52a607118, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:54,501 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:54,502 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:54,503 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 301a7b09-f327-4293-b255-57f4d311df35, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:54,504 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 301a7b09-f327-4293-b255-57f4d311df35, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:54,507 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 301a7b09-f327-4293-b255-57f4d311df35, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:54,844 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:54,878 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:54,879 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, b7727e33-e928-462d-af34-9b952e4a0989, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:54,880 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, b7727e33-e928-462d-af34-9b952e4a0989, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:54,901 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, b7727e33-e928-462d-af34-9b952e4a0989, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:55,649 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:55,704 - INFO - __tests__.alter_compression.23341  - 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-12-05 16:18:55,726 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:55,727 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, c7d64674-b8b1-410b-9aa1-7c888f76ff65, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:55,728 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, c7d64674-b8b1-410b-9aa1-7c888f76ff65, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:55,730 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, c7d64674-b8b1-410b-9aa1-7c888f76ff65, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:56,908 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:56,908 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:56,909 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 8cdc294c-567a-4a8f-8dda-208cc2e2704d, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:56,910 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 8cdc294c-567a-4a8f-8dda-208cc2e2704d, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:56,911 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 8cdc294c-567a-4a8f-8dda-208cc2e2704d, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:57,405 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:57,406 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:57,426 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 99f4604b-f716-4cba-b6b2-516211027607, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:57,427 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 99f4604b-f716-4cba-b6b2-516211027607, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:57,428 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 99f4604b-f716-4cba-b6b2-516211027607, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:58,058 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:58,059 - INFO - __tests__.alter_compression.23341  - 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-12-05 16:18:58,059 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:58,060 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, a227e2ed-6aa0-459b-91d3-465d3e4dfe78, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:58,061 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, a227e2ed-6aa0-459b-91d3-465d3e4dfe78, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:58,062 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, a227e2ed-6aa0-459b-91d3-465d3e4dfe78, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:59,206 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:59,212 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:59,213 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, ef5d8f7f-4992-4116-8f88-290183c98839, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:59,214 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, ef5d8f7f-4992-4116-8f88-290183c98839, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:59,215 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, ef5d8f7f-4992-4116-8f88-290183c98839, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:18:59,523 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:59,559 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:18:59,584 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, ac93b27f-7ef1-4d0c-a139-fad2e91c772d, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:18:59,585 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, ac93b27f-7ef1-4d0c-a139-fad2e91c772d, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:18:59,588 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, ac93b27f-7ef1-4d0c-a139-fad2e91c772d, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:00,289 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:00,290 - INFO - __tests__.alter_compression.23341  - 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-12-05 16:19:00,290 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:00,291 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, d53d1765-f517-4048-9844-2fed87b1def2, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:19:00,292 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, d53d1765-f517-4048-9844-2fed87b1def2, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:00,294 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, d53d1765-f517-4048-9844-2fed87b1def2, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:01,359 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:01,359 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:01,360 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 939bc4be-1196-4874-a5cf-ca5fb33a6fb0, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:19:01,360 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 939bc4be-1196-4874-a5cf-ca5fb33a6fb0, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:01,361 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 939bc4be-1196-4874-a5cf-ca5fb33a6fb0, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:01,641 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:01,642 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:01,643 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 29902e06-c71d-479a-b494-77f8ce8de71f, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:19:01,653 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 29902e06-c71d-479a-b494-77f8ce8de71f, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:01,706 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 29902e06-c71d-479a-b494-77f8ce8de71f, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:02,500 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:02,500 - INFO - __tests__.alter_compression.23341  - 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-12-05 16:19:02,501 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:02,501 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, a53f5ad0-3abb-4e30-a17e-ffba1af5daa9, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:19:02,502 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, a53f5ad0-3abb-4e30-a17e-ffba1af5daa9, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:02,508 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, a53f5ad0-3abb-4e30-a17e-ffba1af5daa9, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:03,740 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:03,741 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:03,741 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 24f948e4-6c2d-4eef-8ba9-f03fa67cfbd2, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:19:03,742 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 24f948e4-6c2d-4eef-8ba9-f03fa67cfbd2, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:03,743 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 24f948e4-6c2d-4eef-8ba9-f03fa67cfbd2, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:04,143 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:04,143 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:04,144 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, e27dbc95-31a4-4f6c-8823-559e3726e744, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:19:04,145 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, e27dbc95-31a4-4f6c-8823-559e3726e744, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:04,146 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, e27dbc95-31a4-4f6c-8823-559e3726e744, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:04,990 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:04,991 - INFO - __tests__.alter_compression.23341  - 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-12-05 16:19:04,991 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:04,992 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 8ec1f6db-c21e-4d39-8f27-53c3c2d941b1, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:19:04,993 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 8ec1f6db-c21e-4d39-8f27-53c3c2d941b1, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:05,008 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 8ec1f6db-c21e-4d39-8f27-53c3c2d941b1, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:06,310 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:06,346 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:06,347 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 6e9ed67d-e015-42fd-ab36-f72409b29bb2, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:19:06,347 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 6e9ed67d-e015-42fd-ab36-f72409b29bb2, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:06,349 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 6e9ed67d-e015-42fd-ab36-f72409b29bb2, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:06,653 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:06,654 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:06,655 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, a41ba3ff-1fa5-4a65-80a8-3e0ac3c95604, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:19:06,670 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, a41ba3ff-1fa5-4a65-80a8-3e0ac3c95604, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:06,689 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, a41ba3ff-1fa5-4a65-80a8-3e0ac3c95604, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:07,022 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:07,022 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:07,023 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 385fa344-ceb8-46f1-ab75-61855373116e, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:19:07,106 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 385fa344-ceb8-46f1-ab75-61855373116e, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:07,107 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 385fa344-ceb8-46f1-ab75-61855373116e, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:12,259 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:22,326 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:22,327 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, b6cc2c88-18ca-417b-b24c-b19afc686c83, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:19:22,327 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, b6cc2c88-18ca-417b-b24c-b19afc686c83, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:22,329 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, b6cc2c88-18ca-417b-b24c-b19afc686c83, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:26,162 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:26,213 - INFO - root.23341  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-12-05 16:19:26,214 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:26,215 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, f31aef8b-b3bf-43ed-842d-7425897d4f80, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:19:26,215 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, f31aef8b-b3bf-43ed-842d-7425897d4f80, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:26,217 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, f31aef8b-b3bf-43ed-842d-7425897d4f80, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:29,678 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:39,741 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:39,742 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, a0c18ffc-edcb-49d4-95e1-976c22ff503c, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:19:39,743 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, a0c18ffc-edcb-49d4-95e1-976c22ff503c, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:39,769 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, a0c18ffc-edcb-49d4-95e1-976c22ff503c, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:41,669 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:51,697 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:19:51,698 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, a9db160c-faae-4e61-84b7-3c107602370e, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:19:51,699 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, a9db160c-faae-4e61-84b7-3c107602370e, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:19:51,700 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, a9db160c-faae-4e61-84b7-3c107602370e, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:19:52,313 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:02,318 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:02,333 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 75f58882-46e4-401e-8132-d6b74ce13d70, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:02,334 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 75f58882-46e4-401e-8132-d6b74ce13d70, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:02,335 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 75f58882-46e4-401e-8132-d6b74ce13d70, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:03,166 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:03,170 - INFO - root.23341  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-12-05 16:20:03,172 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:03,173 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 8b175a71-a898-41db-b2b6-aae64f8133bc, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:03,178 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 8b175a71-a898-41db-b2b6-aae64f8133bc, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" 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 schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:03,179 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 8b175a71-a898-41db-b2b6-aae64f8133bc, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:03,439 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:03,449 - INFO - ya.test.23341  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_20_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 20])
2025-12-05 16:20:03,471 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:03,472 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 1d9eda95-a58d-4dea-b603-3ed1faafd1e6, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:03,473 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 1d9eda95-a58d-4dea-b603-3ed1faafd1e6, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_20_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 20)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:03,474 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 1d9eda95-a58d-4dea-b603-3ed1faafd1e6, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:05,595 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.23341  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-05 16:20:05,596 - DEBUG - ydb.connection.23341  - 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-12-05 16:20:05,604 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.23341  - context_resolve: Resolving endpoints for database /Root
2025-12-05 16:20:05,625 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ListEndpoints, 88c18952-b3f2-4e97-83b6-ce5c5f1857c8, localhost:3870): creating call state
2025-12-05 16:20:05,626 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ListEndpoints, 88c18952-b3f2-4e97-83b6-ce5c5f1857c8, localhost:3870): request = { database: "/Root" }
2025-12-05 16:20:05,631 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ListEndpoints, 88c18952-b3f2-4e97-83b6-ce5c5f1857c8, localhost:3870): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-a66hhqwkt4.auto.internal" port: 3870 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-12-05 16:20:05,643 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.23341  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-a66hhqwkt4.auto.internal:3870, location 1, ssl: False>]>
2025-12-05 16:20:05,663 - DEBUG - ydb.connection.23341  - close: Closing channel for endpoint localhost:3870
2025-12-05 16:20:05,884 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:05,899 - INFO - __tests__.alter_compression.23341  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_20_compression created
2025-12-05 16:20:05,899 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:05,900 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, bd43e6c8-7d8d-4212-a3ea-2df68f4eb137, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:05,901 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, bd43e6c8-7d8d-4212-a3ea-2df68f4eb137, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_20_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:05,913 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, bd43e6c8-7d8d-4212-a3ea-2df68f4eb137, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:06,763 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:06,774 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:06,783 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 67397314-ada1-4878-aa13-d469ef96ed74, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:06,793 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 67397314-ada1-4878-aa13-d469ef96ed74, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:06,804 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 67397314-ada1-4878-aa13-d469ef96ed74, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:10,455 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:10,455 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:10,456 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, d69970ff-914c-4681-8160-a0c9abf7fb32, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:10,457 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, d69970ff-914c-4681-8160-a0c9abf7fb32, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:10,459 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, d69970ff-914c-4681-8160-a0c9abf7fb32, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:11,292 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:11,295 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:11,295 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 629b76f9-5f43-497c-8dc1-a0ec441f465c, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:11,296 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 629b76f9-5f43-497c-8dc1-a0ec441f465c, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:11,298 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 629b76f9-5f43-497c-8dc1-a0ec441f465c, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:12,913 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:12,914 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-12-05 16:20:12,915 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:12,925 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 82632272-7209-4c6f-9f64-c3457f460a88, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:12,935 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 82632272-7209-4c6f-9f64-c3457f460a88, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:12,960 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 82632272-7209-4c6f-9f64-c3457f460a88, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:16,666 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:16,693 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:16,694 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, a4418835-4d61-41d8-ab98-d50e03627d07, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:16,733 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, a4418835-4d61-41d8-ab98-d50e03627d07, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:16,798 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, a4418835-4d61-41d8-ab98-d50e03627d07, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:17,078 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:17,086 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:17,087 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 2da25a97-4574-4f4f-8d6c-edaa7a54fb86, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:17,098 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 2da25a97-4574-4f4f-8d6c-edaa7a54fb86, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:17,117 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 2da25a97-4574-4f4f-8d6c-edaa7a54fb86, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:17,408 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:17,409 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-12-05 16:20:17,410 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:17,421 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, aeb0dcfe-a641-4872-931b-ba177330f8e8, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:17,434 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, aeb0dcfe-a641-4872-931b-ba177330f8e8, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:17,440 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, aeb0dcfe-a641-4872-931b-ba177330f8e8, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:21,174 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:21,201 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:21,211 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, e9a49cfc-c433-4b37-b53d-6846be4555f8, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:21,225 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, e9a49cfc-c433-4b37-b53d-6846be4555f8, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:21,236 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, e9a49cfc-c433-4b37-b53d-6846be4555f8, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:21,557 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:21,558 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:21,570 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, dc67756f-8ef6-4f37-a761-c62c6dd43b26, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:21,581 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, dc67756f-8ef6-4f37-a761-c62c6dd43b26, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:21,604 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, dc67756f-8ef6-4f37-a761-c62c6dd43b26, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:21,945 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:21,963 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-12-05 16:20:21,964 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:21,964 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 7f92b74f-4461-43c7-850f-1b05f70cc862, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:21,965 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 7f92b74f-4461-43c7-850f-1b05f70cc862, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:21,967 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 7f92b74f-4461-43c7-850f-1b05f70cc862, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:25,368 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:25,386 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:25,421 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 506f8bec-5221-4606-aa3c-3cf59d76aedc, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:25,453 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 506f8bec-5221-4606-aa3c-3cf59d76aedc, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:25,470 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 506f8bec-5221-4606-aa3c-3cf59d76aedc, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:25,725 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:25,729 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:25,739 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, cde7c2cf-4ed3-4775-9585-ef39e10ae025, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:25,752 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, cde7c2cf-4ed3-4775-9585-ef39e10ae025, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:25,793 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, cde7c2cf-4ed3-4775-9585-ef39e10ae025, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:26,178 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:26,178 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-12-05 16:20:26,178 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:26,179 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, f98fd811-9803-40bf-b3bf-b628d1934d37, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:26,180 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, f98fd811-9803-40bf-b3bf-b628d1934d37, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:26,197 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, f98fd811-9803-40bf-b3bf-b628d1934d37, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:30,567 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:30,567 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:30,568 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, e9c88d20-3f5e-49b4-b84b-8783d799678e, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:30,569 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, e9c88d20-3f5e-49b4-b84b-8783d799678e, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:30,570 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, e9c88d20-3f5e-49b4-b84b-8783d799678e, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:30,894 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:30,895 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:30,896 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, f1948101-cfe7-4456-a0ef-d6792f513680, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:30,897 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, f1948101-cfe7-4456-a0ef-d6792f513680, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:30,898 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, f1948101-cfe7-4456-a0ef-d6792f513680, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:31,385 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:31,386 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-12-05 16:20:31,386 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:31,400 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, c843cc42-0f59-496f-be6e-e1bd19840777, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:31,426 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, c843cc42-0f59-496f-be6e-e1bd19840777, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:31,444 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, c843cc42-0f59-496f-be6e-e1bd19840777, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:35,994 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:35,994 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:35,995 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 31fbf64a-7a0a-45e9-ad8a-440296936494, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:35,996 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 31fbf64a-7a0a-45e9-ad8a-440296936494, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:35,998 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 31fbf64a-7a0a-45e9-ad8a-440296936494, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:36,681 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:36,682 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:36,683 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 66a2e8bd-8747-4b53-a950-d393cb627d3a, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:36,684 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 66a2e8bd-8747-4b53-a950-d393cb627d3a, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:36,687 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 66a2e8bd-8747-4b53-a950-d393cb627d3a, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:37,714 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:37,782 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-12-05 16:20:37,783 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:37,795 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, f2708993-1a8b-4a63-b3c9-69b4bde56064, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:37,796 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, f2708993-1a8b-4a63-b3c9-69b4bde56064, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:37,806 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, f2708993-1a8b-4a63-b3c9-69b4bde56064, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:43,841 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:43,914 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:43,914 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, fdef79d1-839c-4a93-9418-a2246bd5a8fe, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:43,915 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, fdef79d1-839c-4a93-9418-a2246bd5a8fe, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:43,916 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, fdef79d1-839c-4a93-9418-a2246bd5a8fe, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:44,215 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:44,216 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:44,216 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, fcfd0716-934e-441d-88e1-fd487ebf8e0f, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:44,217 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, fcfd0716-934e-441d-88e1-fd487ebf8e0f, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:44,220 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, fcfd0716-934e-441d-88e1-fd487ebf8e0f, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:44,801 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:44,818 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-12-05 16:20:44,819 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:44,819 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 892092b4-cde3-4066-a173-5285f8abdfc5, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:44,821 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 892092b4-cde3-4066-a173-5285f8abdfc5, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:44,858 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 892092b4-cde3-4066-a173-5285f8abdfc5, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:48,825 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:48,882 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:48,884 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 4373593e-5d60-4b33-8504-38c49ff9da24, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:48,885 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 4373593e-5d60-4b33-8504-38c49ff9da24, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:48,889 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 4373593e-5d60-4b33-8504-38c49ff9da24, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:49,255 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:49,294 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:49,295 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 1f7f31cd-26b8-4965-a6a1-738570944b81, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:49,296 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 1f7f31cd-26b8-4965-a6a1-738570944b81, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:49,300 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 1f7f31cd-26b8-4965-a6a1-738570944b81, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:49,963 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:49,967 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-12-05 16:20:49,969 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:49,970 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 58e328e5-4cb6-4dd8-a4f5-a98add98f2d1, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:49,971 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 58e328e5-4cb6-4dd8-a4f5-a98add98f2d1, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:50,079 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 58e328e5-4cb6-4dd8-a4f5-a98add98f2d1, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:54,405 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:54,409 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:54,409 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 8db9ebd7-03c3-4ac5-bed0-69313da8989e, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:54,410 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 8db9ebd7-03c3-4ac5-bed0-69313da8989e, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:54,412 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 8db9ebd7-03c3-4ac5-bed0-69313da8989e, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:54,801 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:54,802 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:54,803 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, ea1ec753-0637-4f9d-9b2b-552365e7731a, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:54,804 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, ea1ec753-0637-4f9d-9b2b-552365e7731a, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:54,805 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, ea1ec753-0637-4f9d-9b2b-552365e7731a, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:20:55,493 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:55,494 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-12-05 16:20:55,495 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:20:55,495 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, d647daae-64bd-4ec6-bdfe-e4c9a996e2d3, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:20:55,496 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, d647daae-64bd-4ec6-bdfe-e4c9a996e2d3, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:20:55,498 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, d647daae-64bd-4ec6-bdfe-e4c9a996e2d3, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:00,001 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:00,008 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:00,009 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, e11773e9-ecc5-409f-a18f-3a5727369e91, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:21:00,010 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, e11773e9-ecc5-409f-a18f-3a5727369e91, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:00,011 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, e11773e9-ecc5-409f-a18f-3a5727369e91, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:00,345 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:00,373 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:00,374 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, b1955d46-28e7-4366-a1c0-5c7a795561d1, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:21:00,375 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, b1955d46-28e7-4366-a1c0-5c7a795561d1, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:00,387 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, b1955d46-28e7-4366-a1c0-5c7a795561d1, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:01,170 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:01,221 - INFO - __tests__.alter_compression.23341  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-12-05 16:21:01,225 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:01,242 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 801ecb59-f453-44c5-9d83-7084745743f3, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:21:01,246 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 801ecb59-f453-44c5-9d83-7084745743f3, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_20_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:01,248 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 801ecb59-f453-44c5-9d83-7084745743f3, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:02,591 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:02,613 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:02,624 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 54ebd8d3-7b16-4183-98d3-046da72ac776, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:21:02,636 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 54ebd8d3-7b16-4183-98d3-046da72ac776, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:02,637 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 54ebd8d3-7b16-4183-98d3-046da72ac776, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:02,998 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:03,010 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:03,010 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 46db5043-fe1c-452e-8f8b-74c00ded087b, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:21:03,011 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 46db5043-fe1c-452e-8f8b-74c00ded087b, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:03,013 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 46db5043-fe1c-452e-8f8b-74c00ded087b, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:03,356 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:03,373 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:03,412 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 127d168c-e627-4f82-8236-a04542fb36d7, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:21:03,413 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 127d168c-e627-4f82-8236-a04542fb36d7, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:03,414 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 127d168c-e627-4f82-8236-a04542fb36d7, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:07,368 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:17,416 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:17,417 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 804e0adb-1b3c-4c4e-889a-dc41cb665100, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:21:17,418 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 804e0adb-1b3c-4c4e-889a-dc41cb665100, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:17,419 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 804e0adb-1b3c-4c4e-889a-dc41cb665100, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:19,236 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:20,833 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.23341  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-05 16:21:20,834 - DEBUG - ydb.connection.23341  - 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-12-05 16:21:20,843 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.23341  - context_resolve: Resolving endpoints for database /Root
2025-12-05 16:21:20,843 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ListEndpoints, e780105f-233c-4fac-9ce7-8e821096d4ad, localhost:3870): creating call state
2025-12-05 16:21:20,844 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ListEndpoints, e780105f-233c-4fac-9ce7-8e821096d4ad, localhost:3870): request = { database: "/Root" }
2025-12-05 16:21:20,848 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ListEndpoints, e780105f-233c-4fac-9ce7-8e821096d4ad, localhost:3870): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-a66hhqwkt4.auto.internal" port: 3870 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-12-05 16:21:20,852 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.23341  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-a66hhqwkt4.auto.internal:3870, location 1, ssl: False>]>
2025-12-05 16:21:20,877 - DEBUG - ydb.connection.23341  - close: Closing channel for endpoint localhost:3870
2025-12-05 16:21:29,251 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:29,253 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 8525a62c-287f-416a-8d93-42835a2d53b6, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:21:29,254 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 8525a62c-287f-416a-8d93-42835a2d53b6, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:29,255 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 8525a62c-287f-416a-8d93-42835a2d53b6, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:29,782 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:39,786 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:39,787 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 489efaa9-bc66-4879-a2cd-14a8ef5da211, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:21:39,788 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 489efaa9-bc66-4879-a2cd-14a8ef5da211, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:39,789 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 489efaa9-bc66-4879-a2cd-14a8ef5da211, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:40,446 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:40,500 - INFO - root.23341  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_20_compression`, volumes `value` (8000000, 1465928)
2025-12-05 16:21:40,501 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:40,502 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, 771090db-b668-466a-bd1d-903494788c4b, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:21:40,503 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, 771090db-b668-466a-bd1d-903494788c4b, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:40,504 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, 771090db-b668-466a-bd1d-903494788c4b, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:40,767 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:40,768 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:40,768 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, a61aa15a-7095-43ef-aff2-43f019d4a179, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:21:40,769 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, a61aa15a-7095-43ef-aff2-43f019d4a179, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:40,770 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, a61aa15a-7095-43ef-aff2-43f019d4a179, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:41,387 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:51,406 - DEBUG - ydb.query.pool.23341  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:51,407 - DEBUG - ydb.connection.23341  - _prepare_call: RpcState(ExecuteQuery, c2237050-b266-4316-8f11-1c928bc33beb, ghrun-a66hhqwkt4.auto.internal:3870): creating call state
2025-12-05 16:21:51,408 - DEBUG - ydb.connection.23341  - _log_request: RpcState(ExecuteQuery, c2237050-b266-4316-8f11-1c928bc33beb, ghrun-a66hhqwkt4.auto.internal:3870): request = { session_id: "ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-05 16:21:51,409 - DEBUG - ydb.connection.23341  - _log_response: RpcState(ExecuteQuery, c2237050-b266-4316-8f11-1c928bc33beb, ghrun-a66hhqwkt4.auto.internal:3870): response = { <_MultiThreadedRendezvous object> }
2025-12-05 16:21:52,025 - DEBUG - ydb.query.pool.23341  - release: Session returned to queue: ydb://session/3?node_id=1&id=YmNlYjQ0NWMtZDNhY2U4MTMtNjAxNDhjN2YtOGNiZmVhMTI=
2025-12-05 16:21:52,030 - INFO - root.23341  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_20_compression`, volumes `value` (8000000, 1465928)
2025-12-05 16:21:52,030 - INFO - root.23341  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_20_compression` 8092160 / 1465928: 5.520161972484324
2025-12-05 16:21:52,033 - INFO - ya.test.23341  - pytest_runtest_teardown: Test teardown
