2025-12-04 21:02:05,637 - INFO - ya.test.687566  - pytest_runtest_setup: ####################################################################################################
2025-12-04 21:02:05,637 - INFO - ya.test.687566  - pytest_runtest_setup: test_all_supported_compression[zstd_15_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 15]
2025-12-04 21:02:05,638 - INFO - ya.test.687566  - pytest_runtest_setup: ####################################################################################################
2025-12-04 21:02:05,638 - INFO - ya.test.687566  - pytest_runtest_setup: Test setup
2025-12-04 21:02:05,640 - DEBUG - ya.test.687566  - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-04 21:02:05,641 - DEBUG - ya.test.687566  - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-04 21:02:05,641 - DEBUG - ya.test.687566  - execute: Executing '['/home/runner/.ya/build/build_root/uyxd/001a1d/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 21:02:05,660 - DEBUG - ya.test.687566  - execute: Command pid: 688817
2025-12-04 21:02:06,452 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) rc: 0
2025-12-04 21:02:06,453 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) elapsed time (sec): 0.7907233238220215
2025-12-04 21:02:06,453 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) idrss: 0
2025-12-04 21:02:06,453 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) inblock: 0
2025-12-04 21:02:06,454 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) isrss: 0
2025-12-04 21:02:06,454 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) ixrss: 0
2025-12-04 21:02:06,454 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) majflt: 0
2025-12-04 21:02:06,454 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) maxrss: 501360
2025-12-04 21:02:06,455 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) minflt: 45732
2025-12-04 21:02:06,455 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) msgrcv: 0
2025-12-04 21:02:06,455 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) msgsnd: 0
2025-12-04 21:02:06,456 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) nivcsw: 151
2025-12-04 21:02:06,456 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) nsignals: 0
2025-12-04 21:02:06,456 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) nswap: 0
2025-12-04 21:02:06,457 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) nvcsw: 14
2025-12-04 21:02:06,457 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) oublock: 8
2025-12-04 21:02:06,464 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) stime: 0.19574799999999998
2025-12-04 21:02:06,464 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) utime: 0.487498
2025-12-04 21:02:06,465 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) wtime: 0.81
2025-12-04 21:02:06,465 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) output:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-fryxcz52gi    Host information: 
        Linux ghrun-fryxcz52gi 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-04 21:02:06,465 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 688817) errors:

2025-12-04 21:02:06,466 - INFO - __tests__.base.687566  - _setup_ydb: Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-fryxcz52gi    Host information: 
        Linux ghrun-fryxcz52gi 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-04 21:02:06,574 - DEBUG - ya.test.687566  - get_binary: Binary was found by /home/runner/.ya/build/build_root/uyxd/001a1d/ydb/apps/ydbd/ydbd
2025-12-04 21:02:06,575 - DEBUG - library.python.filelock.687566  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11304
2025-12-04 21:02:06,577 - DEBUG - ydb.tests.library.harness.kikimr_runner.687566  - start: Working directory: /home/runner/.ya/build/build_root/uyxd/001a1d/r3tmp/kikimr_cluster_4a3co5_v
2025-12-04 21:02:06,617 - DEBUG - ya.test.687566  - get_binary: Binary was found by /home/runner/.ya/build/build_root/uyxd/001a1d/ydb/apps/ydbd/ydbd
2025-12-04 21:02:06,617 - DEBUG - library.python.filelock.687566  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6537
2025-12-04 21:02:06,618 - DEBUG - library.python.filelock.687566  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27210
2025-12-04 21:02:06,619 - DEBUG - library.python.filelock.687566  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19896
2025-12-04 21:02:06,619 - DEBUG - library.python.filelock.687566  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11393
2025-12-04 21:02:06,620 - INFO - ydb.tests.library.harness.kikimr_runner.687566  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/uyxd/001a1d/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/kikimr_configs"
2025-12-04 21:02:06,621 - INFO - ydb.tests.library.harness.kikimr_runner.687566  - __make_run_command: Final command: /home/runner/.ya/build/build_root/uyxd/001a1d/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/uyxd/001a1d/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1/logfile_ysq3sdra.log --grpc-port=6537 --mon-port=27210 --ic-port=11304
2025-12-04 21:02:06,621 - DEBUG - ydb.tests.library.harness.kikimr_runner.687566  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:6537/1, disk_size 68719476736
2025-12-04 21:02:06,622 - INFO - ydb.tests.library.harness.kikimr_runner.687566  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/uyxd/001a1d/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/kikimr_configs"
2025-12-04 21:02:06,622 - INFO - ydb.tests.library.harness.kikimr_runner.687566  - __make_run_command: Final command: /home/runner/.ya/build/build_root/uyxd/001a1d/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/uyxd/001a1d/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1/logfile_ysq3sdra.log --grpc-port=6537 --mon-port=27210 --ic-port=11304
2025-12-04 21:02:06,623 - DEBUG - ya.test.687566  - execute: Executing '('/home/runner/.ya/build/build_root/uyxd/001a1d/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/uyxd/001a1d/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/uyxd/001a1d/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1/logfile_ysq3sdra.log', '--grpc-port=6537', '--mon-port=27210', '--ic-port=11304')' in '/home/runner/.ya/build/build_root/uyxd/001a1d/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_15_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.15/cluster/node_1' (no wait)
2025-12-04 21:02:06,626 - DEBUG - ya.test.687566  - execute: Command pid: 689120
2025-12-04 21:02:06,627 - INFO - ydb.tests.library.harness.kikimr_runner.687566  - start: Started node localhost:6537/1
2025-12-04 21:02:06,628 - DEBUG - ya.test.687566  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-04 21:02:06,629 - DEBUG - ya.test.687566  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-04 21:02:06,630 - DEBUG - ya.test.687566  - execute: Executing '['/home/runner/.ya/build/build_root/uyxd/001a1d/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-04 21:02:06,644 - DEBUG - ya.test.687566  - execute: Command pid: 689122
2025-12-04 21:02:07,502 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) rc: 0
2025-12-04 21:02:07,502 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) elapsed time (sec): 0.8565716743469238
2025-12-04 21:02:07,503 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) idrss: 0
2025-12-04 21:02:07,503 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) inblock: 0
2025-12-04 21:02:07,503 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) isrss: 0
2025-12-04 21:02:07,504 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) ixrss: 0
2025-12-04 21:02:07,504 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) majflt: 0
2025-12-04 21:02:07,504 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) maxrss: 511828
2025-12-04 21:02:07,504 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) minflt: 45752
2025-12-04 21:02:07,505 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) msgrcv: 0
2025-12-04 21:02:07,505 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) msgsnd: 0
2025-12-04 21:02:07,505 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) nivcsw: 170
2025-12-04 21:02:07,506 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) nsignals: 0
2025-12-04 21:02:07,506 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) nswap: 0
2025-12-04 21:02:07,507 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) nvcsw: 13
2025-12-04 21:02:07,508 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) oublock: 8
2025-12-04 21:02:07,508 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) stime: 0.21298599999999998
2025-12-04 21:02:07,508 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) utime: 0.48754699999999995
2025-12-04 21:02:07,509 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) wtime: 0.871
2025-12-04 21:02:07,509 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) output:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-fryxcz52gi    Host information: 
        Linux ghrun-fryxcz52gi 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-04 21:02:07,509 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 689122) errors:

2025-12-04 21:02:07,510 - INFO - ydb.tests.library.harness.kikimr_runner.687566  - start: Node 1 version:
Git info:
    Commit: 90dd67a37cfb741f29eee0fef0c766380665ddfd
    Branch: 
    Author: Pavel Velikhov <pavel.velikhov@gmail.com>
    Summary: Merge 0d970fa1db908c828a42e48f6775426d76a77466 into e654da9fb4dc56d3ce85e2435d2c2c6f4757aa6d
Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-fryxcz52gi    Host information: 
        Linux ghrun-fryxcz52gi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-12-04 21:02:12,423 - DEBUG - ya.test.687566  - get_binary: Binary was found by /home/runner/.ya/build/build_root/uyxd/001a1d/ydb/apps/ydbd/ydbd
2025-12-04 21:02:12,424 - DEBUG - ydb.tests.library.harness.kikimr_runner.687566  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/uyxd/001a1d/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6537', '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: 11304\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-04 21:02:12,425 - DEBUG - ya.test.687566  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-04 21:02:12,426 - DEBUG - ya.test.687566  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-04 21:02:12,426 - DEBUG - ya.test.687566  - execute: Executing '['/home/runner/.ya/build/build_root/uyxd/001a1d/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6537', '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: 11304\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-04 21:02:12,434 - DEBUG - ya.test.687566  - execute: Command pid: 691925
2025-12-04 21:02:13,436 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) rc: 0
2025-12-04 21:02:13,436 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) elapsed time (sec): 1.0005488395690918
2025-12-04 21:02:13,437 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) idrss: 0
2025-12-04 21:02:13,437 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) inblock: 0
2025-12-04 21:02:13,437 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) isrss: 0
2025-12-04 21:02:13,438 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) ixrss: 0
2025-12-04 21:02:13,438 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) majflt: 0
2025-12-04 21:02:13,438 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) maxrss: 607376
2025-12-04 21:02:13,438 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) minflt: 52051
2025-12-04 21:02:13,439 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) msgrcv: 0
2025-12-04 21:02:13,439 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) msgsnd: 0
2025-12-04 21:02:13,439 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) nivcsw: 179
2025-12-04 21:02:13,439 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) nsignals: 0
2025-12-04 21:02:13,439 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) nswap: 0
2025-12-04 21:02:13,439 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) nvcsw: 699
2025-12-04 21:02:13,440 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) oublock: 8
2025-12-04 21:02:13,440 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) stime: 0.28287999999999996
2025-12-04 21:02:13,440 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) utime: 0.508258
2025-12-04 21:02:13,440 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) wtime: 1.009
2025-12-04 21:02:13,440 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-04 21:02:13,441 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 691925) errors:

2025-12-04 21:02:13,442 - DEBUG - ya.test.687566  - get_binary: Binary was found by /home/runner/.ya/build/build_root/uyxd/001a1d/ydb/apps/ydbd/ydbd
2025-12-04 21:02:13,442 - DEBUG - ydb.tests.library.harness.kikimr_runner.687566  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/uyxd/001a1d/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6537', '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-04 21:02:13,444 - DEBUG - ya.test.687566  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-04 21:02:13,444 - DEBUG - ya.test.687566  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-04 21:02:13,444 - DEBUG - ya.test.687566  - execute: Executing '['/home/runner/.ya/build/build_root/uyxd/001a1d/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6537', '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-04 21:02:13,446 - DEBUG - ya.test.687566  - execute: Command pid: 692184
2025-12-04 21:02:14,436 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) rc: 0
2025-12-04 21:02:14,436 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) elapsed time (sec): 0.9895014762878418
2025-12-04 21:02:14,436 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) idrss: 0
2025-12-04 21:02:14,437 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) inblock: 0
2025-12-04 21:02:14,437 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) isrss: 0
2025-12-04 21:02:14,437 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) ixrss: 0
2025-12-04 21:02:14,437 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) majflt: 0
2025-12-04 21:02:14,437 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) maxrss: 608384
2025-12-04 21:02:14,438 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) minflt: 52293
2025-12-04 21:02:14,438 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) msgrcv: 0
2025-12-04 21:02:14,438 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) msgsnd: 0
2025-12-04 21:02:14,438 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) nivcsw: 115
2025-12-04 21:02:14,439 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) nsignals: 0
2025-12-04 21:02:14,439 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) nswap: 0
2025-12-04 21:02:14,439 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) nvcsw: 845
2025-12-04 21:02:14,439 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) oublock: 8
2025-12-04 21:02:14,440 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) stime: 0.288553
2025-12-04 21:02:14,440 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) utime: 0.47487599999999996
2025-12-04 21:02:14,440 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) wtime: 0.991
2025-12-04 21:02:14,441 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-04 21:02:14,441 - DEBUG - ya.test.687566  - _save_outputs: Command (pid 692184) errors:

2025-12-04 21:02:14,441 - INFO - ydb.tests.library.harness.kikimr_runner.687566  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-04 21:02:14,535 - INFO - ydb.tests.library.harness.kikimr_runner.687566  - __run: Cluster started and initialized
2025-12-04 21:02:14,571 - DEBUG - ydb.connection.687566  - 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-04 21:02:14,574 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.687566  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:02:14,575 - DEBUG - ydb.connection.687566  - 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-04 21:02:14,606 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.687566  - context_resolve: Resolving endpoints for database /Root
2025-12-04 21:02:14,607 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ListEndpoints, d9e5d9d3-274b-4a4a-81f8-5a860a4c438a, localhost:6537): creating call state
2025-12-04 21:02:14,607 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ListEndpoints, d9e5d9d3-274b-4a4a-81f8-5a860a4c438a, localhost:6537): request = { database: "/Root" }
2025-12-04 21:02:14,616 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ListEndpoints, d9e5d9d3-274b-4a4a-81f8-5a860a4c438a, localhost:6537): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-fryxcz52gi.auto.internal" port: 6537 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-04 21:02:14,617 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.687566  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-fryxcz52gi.auto.internal:6537, location 1, ssl: False>]>
2025-12-04 21:02:14,617 - DEBUG - ydb.connection.687566  - 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-04 21:02:14,640 - DEBUG - ydb.connection.687566  - close: Closing channel for endpoint localhost:6537
2025-12-04 21:02:14,641 - DEBUG - ydb.query.pool.687566  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-04 21:02:14,643 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(CreateSession, e0e9674a-4015-4e89-92f0-c1c730526d8b, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:14,643 - DEBUG - ydb.connection.687566  - _log_request: RpcState(CreateSession, e0e9674a-4015-4e89-92f0-c1c730526d8b, ghrun-fryxcz52gi.auto.internal:6537): request = {  }
2025-12-04 21:02:14,651 - DEBUG - ydb.connection.687566  - _log_response: RpcState(CreateSession, e0e9674a-4015-4e89-92f0-c1c730526d8b, ghrun-fryxcz52gi.auto.internal:6537): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" node_id: 1 }
2025-12-04 21:02:14,652 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(AttachSession, 3d5ad181-373e-4b8a-ac1c-b79ed2e0b138, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:14,653 - DEBUG - ydb.connection.687566  - _log_request: RpcState(AttachSession, 3d5ad181-373e-4b8a-ac1c-b79ed2e0b138, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" }
2025-12-04 21:02:14,655 - DEBUG - ydb.connection.687566  - _log_response: RpcState(AttachSession, 3d5ad181-373e-4b8a-ac1c-b79ed2e0b138, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:14,660 - DEBUG - ydb.query.pool.687566  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:14,661 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 33947da4-9a7a-46d1-992f-14e0d49de83c, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:14,662 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 33947da4-9a7a-46d1-992f-14e0d49de83c, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:14,663 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 33947da4-9a7a-46d1-992f-14e0d49de83c, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:16,831 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:16,832 - INFO - __tests__.alter_compression.687566  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-12-04 21:02:16,833 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:16,833 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 44fbcd7e-c2e3-4abf-90b2-7908756476bf, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:16,834 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 44fbcd7e-c2e3-4abf-90b2-7908756476bf, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:16,836 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 44fbcd7e-c2e3-4abf-90b2-7908756476bf, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:17,673 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:17,674 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:17,675 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 61eb56e6-ef2a-4cd9-9e39-de60e060a1ed, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:17,676 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 61eb56e6-ef2a-4cd9-9e39-de60e060a1ed, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:17,677 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 61eb56e6-ef2a-4cd9-9e39-de60e060a1ed, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:18,772 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:18,773 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:18,773 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 96f01d56-b0f0-4295-8bd2-1f1967020269, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:18,775 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 96f01d56-b0f0-4295-8bd2-1f1967020269, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:18,776 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 96f01d56-b0f0-4295-8bd2-1f1967020269, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:19,704 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:19,705 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:19,705 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 42a00b77-5345-43f8-bc63-3b229d8bbae1, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:19,706 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 42a00b77-5345-43f8-bc63-3b229d8bbae1, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:19,712 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 42a00b77-5345-43f8-bc63-3b229d8bbae1, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:21,184 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:21,185 - INFO - __tests__.alter_compression.687566  - 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-04 21:02:21,185 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:21,186 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 9aa202df-2e44-4fff-a825-29c2d26d9d1c, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:21,187 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 9aa202df-2e44-4fff-a825-29c2d26d9d1c, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:21,189 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 9aa202df-2e44-4fff-a825-29c2d26d9d1c, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:22,238 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:22,239 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:22,240 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, d2162294-22ab-4325-8b7b-6dad08376775, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:22,241 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, d2162294-22ab-4325-8b7b-6dad08376775, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:22,242 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, d2162294-22ab-4325-8b7b-6dad08376775, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:22,530 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:22,530 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:22,531 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 139f5453-43dd-4c39-8fe7-305dbd96f757, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:22,532 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 139f5453-43dd-4c39-8fe7-305dbd96f757, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:22,534 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 139f5453-43dd-4c39-8fe7-305dbd96f757, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:23,005 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:23,006 - INFO - __tests__.alter_compression.687566  - 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-04 21:02:23,006 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:23,007 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, c6dbf103-628d-45d1-a47b-9e5ace90f805, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:23,008 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, c6dbf103-628d-45d1-a47b-9e5ace90f805, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:23,021 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, c6dbf103-628d-45d1-a47b-9e5ace90f805, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:24,012 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:24,013 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:24,014 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, b18732d7-47e2-4a4e-8dd4-81e89207b353, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:24,015 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, b18732d7-47e2-4a4e-8dd4-81e89207b353, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:24,016 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, b18732d7-47e2-4a4e-8dd4-81e89207b353, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:24,283 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:24,284 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:24,285 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, a0c00dc0-b6f0-4a33-921e-66082bb6ddaf, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:24,286 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, a0c00dc0-b6f0-4a33-921e-66082bb6ddaf, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:24,288 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, a0c00dc0-b6f0-4a33-921e-66082bb6ddaf, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:24,675 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:24,676 - INFO - __tests__.alter_compression.687566  - 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-04 21:02:24,676 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:24,677 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 7d9d7fdb-c3c8-4ad2-abfd-21086cdf0ce0, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:24,678 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 7d9d7fdb-c3c8-4ad2-abfd-21086cdf0ce0, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:24,680 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 7d9d7fdb-c3c8-4ad2-abfd-21086cdf0ce0, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:25,800 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:25,800 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:25,801 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 37fb24f1-a7ae-4d65-bd0c-459cc2f198df, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:25,802 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 37fb24f1-a7ae-4d65-bd0c-459cc2f198df, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:25,803 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 37fb24f1-a7ae-4d65-bd0c-459cc2f198df, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:26,156 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:26,157 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:26,157 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 952fc58e-a51c-4bc8-b994-ab9c31f00da5, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:26,158 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 952fc58e-a51c-4bc8-b994-ab9c31f00da5, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:26,159 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 952fc58e-a51c-4bc8-b994-ab9c31f00da5, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:26,873 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:26,874 - INFO - __tests__.alter_compression.687566  - 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-04 21:02:26,874 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:26,875 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 47b831b1-3f56-4e70-93f1-e727c4819c51, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:26,876 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 47b831b1-3f56-4e70-93f1-e727c4819c51, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:26,880 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 47b831b1-3f56-4e70-93f1-e727c4819c51, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:28,128 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:28,128 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:28,129 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, dde26522-85e8-42c9-ac48-f7569e8e41ab, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:28,130 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, dde26522-85e8-42c9-ac48-f7569e8e41ab, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:28,132 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, dde26522-85e8-42c9-ac48-f7569e8e41ab, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:28,438 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:28,438 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:28,439 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 31f85027-e9ff-4a15-bc8a-fb8a18d8e325, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:28,440 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 31f85027-e9ff-4a15-bc8a-fb8a18d8e325, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:28,441 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 31f85027-e9ff-4a15-bc8a-fb8a18d8e325, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:29,007 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:29,007 - INFO - __tests__.alter_compression.687566  - 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-04 21:02:29,008 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:29,009 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 7f1dd23c-5ef8-485e-9860-9c59d49f6ddc, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:29,009 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 7f1dd23c-5ef8-485e-9860-9c59d49f6ddc, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:29,012 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 7f1dd23c-5ef8-485e-9860-9c59d49f6ddc, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:30,221 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:30,222 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:30,222 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, f2e1fcff-895b-4ac7-b148-0b6567f6240f, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:30,223 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, f2e1fcff-895b-4ac7-b148-0b6567f6240f, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:30,225 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, f2e1fcff-895b-4ac7-b148-0b6567f6240f, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:30,595 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:30,596 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:30,597 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, f3f7343b-f277-40d2-8dcd-404fb776222e, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:30,598 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, f3f7343b-f277-40d2-8dcd-404fb776222e, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:30,601 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, f3f7343b-f277-40d2-8dcd-404fb776222e, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:31,401 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:31,402 - INFO - __tests__.alter_compression.687566  - 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-04 21:02:31,403 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:31,408 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, df9ddc15-1735-46e7-9493-fe6782191c1c, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:31,420 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, df9ddc15-1735-46e7-9493-fe6782191c1c, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:31,422 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, df9ddc15-1735-46e7-9493-fe6782191c1c, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:32,586 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:32,587 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:32,588 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, deaa67c0-51eb-46fd-9dfe-c6963034a5d2, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:32,588 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, deaa67c0-51eb-46fd-9dfe-c6963034a5d2, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:32,589 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, deaa67c0-51eb-46fd-9dfe-c6963034a5d2, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:32,923 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:32,923 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:32,924 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 720fbff6-8e08-4b8b-9261-81c30e5e6196, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:32,925 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 720fbff6-8e08-4b8b-9261-81c30e5e6196, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:32,926 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 720fbff6-8e08-4b8b-9261-81c30e5e6196, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:33,750 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:33,751 - INFO - __tests__.alter_compression.687566  - 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-04 21:02:33,751 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:33,752 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 1312f510-0c33-4142-b72c-f9570e583353, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:33,753 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 1312f510-0c33-4142-b72c-f9570e583353, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:33,764 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 1312f510-0c33-4142-b72c-f9570e583353, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:34,917 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:34,918 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:34,918 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 35cb0ea5-4eaa-4a10-b8aa-f3ce27f19af9, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:34,919 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 35cb0ea5-4eaa-4a10-b8aa-f3ce27f19af9, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:34,923 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 35cb0ea5-4eaa-4a10-b8aa-f3ce27f19af9, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:35,277 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:35,278 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:35,279 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 44d269cf-9958-4845-b42d-b39412502150, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:35,280 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 44d269cf-9958-4845-b42d-b39412502150, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:35,283 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 44d269cf-9958-4845-b42d-b39412502150, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:36,477 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:36,478 - INFO - __tests__.alter_compression.687566  - 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-04 21:02:36,478 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:36,479 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, ac849d53-fece-46c8-9452-094ebdd554c4, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:36,480 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, ac849d53-fece-46c8-9452-094ebdd554c4, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:36,482 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, ac849d53-fece-46c8-9452-094ebdd554c4, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:38,007 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:38,008 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:38,009 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 173ce824-5e41-474e-b443-5044ffcace33, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:38,010 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 173ce824-5e41-474e-b443-5044ffcace33, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:38,011 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 173ce824-5e41-474e-b443-5044ffcace33, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:38,653 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:38,654 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:38,655 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 5423dbe2-e90d-4dde-9b0e-077a69d3f3e1, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:38,656 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 5423dbe2-e90d-4dde-9b0e-077a69d3f3e1, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:38,661 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 5423dbe2-e90d-4dde-9b0e-077a69d3f3e1, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:39,846 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:39,847 - INFO - __tests__.alter_compression.687566  - 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-04 21:02:39,847 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:39,848 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 3f0c40af-2a3f-4c82-b316-a787f39a83cd, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:39,849 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 3f0c40af-2a3f-4c82-b316-a787f39a83cd, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:39,850 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 3f0c40af-2a3f-4c82-b316-a787f39a83cd, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:41,520 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:41,521 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:41,521 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 0968fe1f-fac0-4d74-b2f2-eecd8a7f5b87, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:41,522 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 0968fe1f-fac0-4d74-b2f2-eecd8a7f5b87, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:41,532 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 0968fe1f-fac0-4d74-b2f2-eecd8a7f5b87, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:42,008 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:42,008 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:42,009 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 3a77f5a9-cec4-4f05-87d4-3ac743a83125, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:42,010 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 3a77f5a9-cec4-4f05-87d4-3ac743a83125, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:42,016 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 3a77f5a9-cec4-4f05-87d4-3ac743a83125, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:43,292 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:43,293 - INFO - __tests__.alter_compression.687566  - 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-04 21:02:43,293 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:43,294 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 0b8087ed-7e79-4f48-aa36-9f242e5a21f9, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:43,295 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 0b8087ed-7e79-4f48-aa36-9f242e5a21f9, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:43,317 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 0b8087ed-7e79-4f48-aa36-9f242e5a21f9, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:45,826 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:45,827 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:45,828 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, e559a157-1070-47c3-886b-18f6c06669a0, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:45,829 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, e559a157-1070-47c3-886b-18f6c06669a0, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:45,837 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, e559a157-1070-47c3-886b-18f6c06669a0, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:46,191 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:46,192 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:46,193 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 020b5d13-2fe9-465f-bf2b-db4e57f6af72, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:46,194 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 020b5d13-2fe9-465f-bf2b-db4e57f6af72, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:46,195 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 020b5d13-2fe9-465f-bf2b-db4e57f6af72, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:46,659 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:46,660 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:02:46,661 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 15904f9e-bbe2-4ba0-bc95-2818cbd06773, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:02:46,662 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 15904f9e-bbe2-4ba0-bc95-2818cbd06773, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:02:46,665 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 15904f9e-bbe2-4ba0-bc95-2818cbd06773, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:02:52,149 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:02,188 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:02,189 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 4502fe69-2998-45a9-a454-64e49f4082ae, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:03:02,190 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 4502fe69-2998-45a9-a454-64e49f4082ae, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:03:02,191 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 4502fe69-2998-45a9-a454-64e49f4082ae, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:07,038 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:07,079 - INFO - root.687566  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-12-04 21:03:07,080 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:07,081 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, f4e7f5de-c138-4416-9833-fc66cd1c3b41, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:03:07,082 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, f4e7f5de-c138-4416-9833-fc66cd1c3b41, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:03:07,083 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, f4e7f5de-c138-4416-9833-fc66cd1c3b41, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:11,235 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:18,795 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.687566  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:03:18,796 - DEBUG - ydb.connection.687566  - 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-04 21:03:18,860 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.687566  - context_resolve: Resolving endpoints for database /Root
2025-12-04 21:03:18,861 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ListEndpoints, c7ad9fc0-a775-470f-969c-60fc79e3329d, localhost:6537): creating call state
2025-12-04 21:03:18,862 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ListEndpoints, c7ad9fc0-a775-470f-969c-60fc79e3329d, localhost:6537): request = { database: "/Root" }
2025-12-04 21:03:18,868 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ListEndpoints, c7ad9fc0-a775-470f-969c-60fc79e3329d, localhost:6537): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-fryxcz52gi.auto.internal" port: 6537 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-04 21:03:18,869 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.687566  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-fryxcz52gi.auto.internal:6537, location 1, ssl: False>]>
2025-12-04 21:03:18,870 - DEBUG - ydb.connection.687566  - close: Closing channel for endpoint localhost:6537
2025-12-04 21:03:21,272 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:21,273 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, f7ef42a9-93a1-4b47-b99b-8fb83ae4496b, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:03:21,274 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, f7ef42a9-93a1-4b47-b99b-8fb83ae4496b, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:03:21,275 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, f7ef42a9-93a1-4b47-b99b-8fb83ae4496b, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:24,800 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:34,850 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:34,851 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 40cb7869-f38f-427a-a58c-5d3c5543ce4f, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:03:34,852 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 40cb7869-f38f-427a-a58c-5d3c5543ce4f, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:03:34,854 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 40cb7869-f38f-427a-a58c-5d3c5543ce4f, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:36,242 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:46,252 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:46,253 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 70422954-507a-400e-a5e7-5d106cb8f8ae, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:03:46,254 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 70422954-507a-400e-a5e7-5d106cb8f8ae, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:03:46,255 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 70422954-507a-400e-a5e7-5d106cb8f8ae, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:47,176 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:47,184 - INFO - root.687566  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-12-04 21:03:47,184 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:47,185 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 4a898702-1ff4-4380-a11c-de2ba7f28a40, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:03:47,188 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 4a898702-1ff4-4380-a11c-de2ba7f28a40, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" 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-04 21:03:47,201 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 4a898702-1ff4-4380-a11c-de2ba7f28a40, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:47,752 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:47,771 - INFO - ya.test.687566  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_15_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 15])
2025-12-04 21:03:47,772 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:47,773 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, ff6543eb-5ffb-43d4-b900-c19cb1f712bc, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:03:47,774 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, ff6543eb-5ffb-43d4-b900-c19cb1f712bc, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_15_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 15)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:03:47,789 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, ff6543eb-5ffb-43d4-b900-c19cb1f712bc, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:52,697 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:52,697 - INFO - __tests__.alter_compression.687566  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_15_compression created
2025-12-04 21:03:52,698 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:52,698 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 2ae2e1fa-b204-41d2-b98e-db63d7cf375b, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:03:52,699 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 2ae2e1fa-b204-41d2-b98e-db63d7cf375b, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_15_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:03:52,701 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 2ae2e1fa-b204-41d2-b98e-db63d7cf375b, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:54,231 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:54,232 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:54,233 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 784847f1-776c-4d31-a95b-870d7a5e4b2e, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:03:54,234 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 784847f1-776c-4d31-a95b-870d7a5e4b2e, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:03:54,237 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 784847f1-776c-4d31-a95b-870d7a5e4b2e, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:03:59,481 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:59,481 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:03:59,482 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 0c372680-2fd5-4410-9f8b-3a045cf8a206, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:03:59,483 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 0c372680-2fd5-4410-9f8b-3a045cf8a206, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:03:59,493 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 0c372680-2fd5-4410-9f8b-3a045cf8a206, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:01,192 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:01,193 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:01,194 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 13eb1696-8ac1-4ef4-942a-d5dc09457b73, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:01,195 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 13eb1696-8ac1-4ef4-942a-d5dc09457b73, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:01,196 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 13eb1696-8ac1-4ef4-942a-d5dc09457b73, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:03,695 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:03,696 - INFO - __tests__.alter_compression.687566  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318544, 'BlobCount': 128}}
2025-12-04 21:04:03,696 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:03,697 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 44c3b834-3eca-4097-937f-c0e6fc474bf0, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:03,698 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 44c3b834-3eca-4097-937f-c0e6fc474bf0, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:03,715 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 44c3b834-3eca-4097-937f-c0e6fc474bf0, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:07,698 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:07,699 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:07,700 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 09b1d5ce-b4a4-4c1f-8758-c0ac09797265, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:07,701 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 09b1d5ce-b4a4-4c1f-8758-c0ac09797265, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:07,702 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 09b1d5ce-b4a4-4c1f-8758-c0ac09797265, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:08,117 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:08,118 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:08,118 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, a7b78f43-40b7-4200-b654-d12ad6f2f67e, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:08,119 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, a7b78f43-40b7-4200-b654-d12ad6f2f67e, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:08,124 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, a7b78f43-40b7-4200-b654-d12ad6f2f67e, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:08,685 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:08,686 - INFO - __tests__.alter_compression.687566  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638064, 'BlobCount': 256}}
2025-12-04 21:04:08,687 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:08,687 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 95d339bb-402e-4efe-a0d2-aa3681b9fc23, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:08,688 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 95d339bb-402e-4efe-a0d2-aa3681b9fc23, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:08,692 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 95d339bb-402e-4efe-a0d2-aa3681b9fc23, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:12,368 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:12,369 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:12,369 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 9a2f423a-a14f-4ecf-abc9-69da5c2efd14, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:12,370 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 9a2f423a-a14f-4ecf-abc9-69da5c2efd14, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:12,372 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 9a2f423a-a14f-4ecf-abc9-69da5c2efd14, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:12,852 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:12,853 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:12,854 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 2d8f7bc1-104e-47bb-ba2d-6e53b383ea09, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:12,855 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 2d8f7bc1-104e-47bb-ba2d-6e53b383ea09, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:12,856 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 2d8f7bc1-104e-47bb-ba2d-6e53b383ea09, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:13,540 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:13,541 - INFO - __tests__.alter_compression.687566  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957088, 'BlobCount': 384}}
2025-12-04 21:04:13,541 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:13,542 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 8f00d9ab-a95e-40ed-aa15-651610a44f69, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:13,543 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 8f00d9ab-a95e-40ed-aa15-651610a44f69, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:13,545 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 8f00d9ab-a95e-40ed-aa15-651610a44f69, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:17,016 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:17,016 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:17,017 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 0279941c-c15e-4c78-8c2a-13dd45328a9a, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:17,018 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 0279941c-c15e-4c78-8c2a-13dd45328a9a, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:17,020 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 0279941c-c15e-4c78-8c2a-13dd45328a9a, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:17,436 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:17,437 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:17,438 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, a5c242e4-1e2d-487d-801c-15dfb9862d22, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:17,439 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, a5c242e4-1e2d-487d-801c-15dfb9862d22, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:17,440 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, a5c242e4-1e2d-487d-801c-15dfb9862d22, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:17,985 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:17,986 - INFO - __tests__.alter_compression.687566  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276560, 'BlobCount': 512}}
2025-12-04 21:04:17,987 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:17,987 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 818095cd-a3c6-4045-9aa7-90e3c02029fa, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:17,988 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 818095cd-a3c6-4045-9aa7-90e3c02029fa, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:17,991 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 818095cd-a3c6-4045-9aa7-90e3c02029fa, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:21,770 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:21,771 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:21,772 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, ec00a038-0c1a-4ab6-8e3f-871116a58483, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:21,773 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, ec00a038-0c1a-4ab6-8e3f-871116a58483, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:21,775 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, ec00a038-0c1a-4ab6-8e3f-871116a58483, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:22,644 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:22,662 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:22,663 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 9996e827-95e2-47f5-a5a4-a335a020fa81, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:22,669 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 9996e827-95e2-47f5-a5a4-a335a020fa81, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:22,677 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 9996e827-95e2-47f5-a5a4-a335a020fa81, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:23,608 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:23,609 - INFO - __tests__.alter_compression.687566  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595584, 'BlobCount': 640}}
2025-12-04 21:04:23,610 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:23,611 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, f67aea3f-a524-46bc-942f-923fc893d72b, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:23,613 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, f67aea3f-a524-46bc-942f-923fc893d72b, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:23,614 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, f67aea3f-a524-46bc-942f-923fc893d72b, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:27,882 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:27,883 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:27,884 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 87276d44-451a-4c98-94c8-3d9cd9ae53d8, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:27,885 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 87276d44-451a-4c98-94c8-3d9cd9ae53d8, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:27,886 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 87276d44-451a-4c98-94c8-3d9cd9ae53d8, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:28,397 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:28,398 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:28,399 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, f68f29a6-c501-4303-ab19-77cef22c6dc8, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:28,400 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, f68f29a6-c501-4303-ab19-77cef22c6dc8, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:28,401 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, f68f29a6-c501-4303-ab19-77cef22c6dc8, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:29,317 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:29,317 - INFO - __tests__.alter_compression.687566  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914960, 'BlobCount': 768}}
2025-12-04 21:04:29,319 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:29,320 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 800e46e5-ce7c-472b-9ac8-40bbd176d89f, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:29,327 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 800e46e5-ce7c-472b-9ac8-40bbd176d89f, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:29,334 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 800e46e5-ce7c-472b-9ac8-40bbd176d89f, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:33,567 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:33,568 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:33,569 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, a9028b33-edd0-40e6-a031-2ce7676f8d21, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:33,570 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, a9028b33-edd0-40e6-a031-2ce7676f8d21, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:33,576 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, a9028b33-edd0-40e6-a031-2ce7676f8d21, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:34,001 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:34,002 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:34,002 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 70c45884-22cc-4313-a6b9-efae98954461, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:34,003 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 70c45884-22cc-4313-a6b9-efae98954461, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:34,004 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 70c45884-22cc-4313-a6b9-efae98954461, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:34,691 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:34,692 - INFO - __tests__.alter_compression.687566  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234080, 'BlobCount': 896}}
2025-12-04 21:04:34,693 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:34,694 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, f841fb5d-708a-44b2-ba11-c50c9f85f618, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:34,695 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, f841fb5d-708a-44b2-ba11-c50c9f85f618, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:34,703 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, f841fb5d-708a-44b2-ba11-c50c9f85f618, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:38,839 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:38,840 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:38,841 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 457102d1-dc8a-45f6-8e2b-93cdd55dfa39, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:38,842 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 457102d1-dc8a-45f6-8e2b-93cdd55dfa39, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:38,843 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 457102d1-dc8a-45f6-8e2b-93cdd55dfa39, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:39,365 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:39,366 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:39,367 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 26ae8028-c0f4-41b0-b326-f4ec1903aeb6, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:39,368 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 26ae8028-c0f4-41b0-b326-f4ec1903aeb6, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:39,381 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 26ae8028-c0f4-41b0-b326-f4ec1903aeb6, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:40,312 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:40,313 - INFO - __tests__.alter_compression.687566  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2553696, 'BlobCount': 1024}}
2025-12-04 21:04:40,314 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:40,315 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, a0511469-400a-4c4e-a5c5-7f2e324ae420, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:40,316 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, a0511469-400a-4c4e-a5c5-7f2e324ae420, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:40,317 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, a0511469-400a-4c4e-a5c5-7f2e324ae420, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:43,563 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:43,564 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:43,565 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, bd567dc7-7b18-4639-803d-3178da50b257, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:43,565 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, bd567dc7-7b18-4639-803d-3178da50b257, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:43,567 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, bd567dc7-7b18-4639-803d-3178da50b257, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:43,924 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:43,924 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:43,925 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 29aeee5a-6e6a-4aa3-9785-f6bec0dd7979, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:43,926 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 29aeee5a-6e6a-4aa3-9785-f6bec0dd7979, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:43,928 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 29aeee5a-6e6a-4aa3-9785-f6bec0dd7979, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:44,903 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:44,904 - INFO - __tests__.alter_compression.687566  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2872720, 'BlobCount': 1152}}
2025-12-04 21:04:44,905 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:44,907 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 1939dd96-cc5e-43da-b03c-a4302747a9cf, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:44,908 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 1939dd96-cc5e-43da-b03c-a4302747a9cf, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_15_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:44,909 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 1939dd96-cc5e-43da-b03c-a4302747a9cf, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:48,575 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:48,576 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:48,586 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 1572dcb0-0c16-4eba-b682-3b916ef731be, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:48,592 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 1572dcb0-0c16-4eba-b682-3b916ef731be, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:48,613 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 1572dcb0-0c16-4eba-b682-3b916ef731be, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:49,105 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:49,106 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:49,106 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 615586a7-87d0-4778-b863-00419d60f039, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:49,108 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 615586a7-87d0-4778-b863-00419d60f039, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:49,109 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 615586a7-87d0-4778-b863-00419d60f039, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:49,823 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:49,824 - INFO - __tests__.alter_compression.687566  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_15_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3192432, 'BlobCount': 1280}}
2025-12-04 21:04:49,825 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:49,826 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 5da051f5-7053-4aab-94ad-bf49b8d5a41a, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:49,827 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 5da051f5-7053-4aab-94ad-bf49b8d5a41a, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_15_compression`" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:49,828 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 5da051f5-7053-4aab-94ad-bf49b8d5a41a, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:51,127 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:51,128 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:51,129 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 8bf649b4-c9eb-4e94-bc17-a740a6ea2a19, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:51,133 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 8bf649b4-c9eb-4e94-bc17-a740a6ea2a19, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:51,134 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 8bf649b4-c9eb-4e94-bc17-a740a6ea2a19, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:51,440 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:51,441 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:51,442 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, fcf04ae3-7557-4bb4-a84c-f5b8ed2d86c4, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:51,443 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, fcf04ae3-7557-4bb4-a84c-f5b8ed2d86c4, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:51,446 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, fcf04ae3-7557-4bb4-a84c-f5b8ed2d86c4, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:51,712 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:51,713 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:04:51,714 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, f7b068b3-0c19-4662-934a-8a082f840789, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:04:51,736 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, f7b068b3-0c19-4662-934a-8a082f840789, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:04:51,749 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, f7b068b3-0c19-4662-934a-8a082f840789, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:04:56,414 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:05:06,516 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:05:06,525 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, ec43dfdd-2972-43e1-baf2-783b460ef2fa, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:05:06,527 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, ec43dfdd-2972-43e1-baf2-783b460ef2fa, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:05:06,529 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, ec43dfdd-2972-43e1-baf2-783b460ef2fa, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:05:07,312 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.687566  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-04 21:05:07,312 - DEBUG - ydb.connection.687566  - 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-04 21:05:07,339 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.687566  - context_resolve: Resolving endpoints for database /Root
2025-12-04 21:05:07,340 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ListEndpoints, 052ae2f1-c89d-4071-94ed-4f78cab59a18, localhost:6537): creating call state
2025-12-04 21:05:07,341 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ListEndpoints, 052ae2f1-c89d-4071-94ed-4f78cab59a18, localhost:6537): request = { database: "/Root" }
2025-12-04 21:05:07,358 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ListEndpoints, 052ae2f1-c89d-4071-94ed-4f78cab59a18, localhost:6537): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-fryxcz52gi.auto.internal" port: 6537 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-04 21:05:07,359 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.687566  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-fryxcz52gi.auto.internal:6537, location 1, ssl: False>]>
2025-12-04 21:05:07,360 - DEBUG - ydb.connection.687566  - close: Closing channel for endpoint localhost:6537
2025-12-04 21:05:08,924 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:05:18,940 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:05:18,944 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, d981c7b0-cc82-4b65-b24f-8afe4ab7c1f5, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:05:18,959 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, d981c7b0-cc82-4b65-b24f-8afe4ab7c1f5, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:05:18,969 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, d981c7b0-cc82-4b65-b24f-8afe4ab7c1f5, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:05:20,001 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:05:30,006 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:05:30,009 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, b5070eb9-4739-4658-944c-a0feb953f8c2, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:05:30,010 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, b5070eb9-4739-4658-944c-a0feb953f8c2, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:05:30,013 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, b5070eb9-4739-4658-944c-a0feb953f8c2, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:05:31,086 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:05:31,096 - INFO - root.687566  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_15_compression`, volumes `value` (8000000, 1870856)
2025-12-04 21:05:31,097 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:05:31,097 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 57cf901e-3777-4e02-b428-0817476eda86, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:05:31,113 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 57cf901e-3777-4e02-b428-0817476eda86, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:05:31,121 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 57cf901e-3777-4e02-b428-0817476eda86, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:05:31,684 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:05:31,694 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:05:31,694 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, c43d3b7f-8028-4c43-bd24-3b4388f9dbdf, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:05:31,695 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, c43d3b7f-8028-4c43-bd24-3b4388f9dbdf, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:05:31,716 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, c43d3b7f-8028-4c43-bd24-3b4388f9dbdf, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:05:32,581 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:05:42,600 - DEBUG - ydb.query.pool.687566  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:05:42,602 - DEBUG - ydb.connection.687566  - _prepare_call: RpcState(ExecuteQuery, 763dfa3e-9670-4306-b7df-92df01c351a1, ghrun-fryxcz52gi.auto.internal:6537): creating call state
2025-12-04 21:05:42,605 - DEBUG - ydb.connection.687566  - _log_request: RpcState(ExecuteQuery, 763dfa3e-9670-4306-b7df-92df01c351a1, ghrun-fryxcz52gi.auto.internal:6537): request = { session_id: "ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_15_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE schema_inclusion_mode: SCHEMA_INCLUSION_MODE_ALWAYS result_set_format: FORMAT_VALUE }
2025-12-04 21:05:42,609 - DEBUG - ydb.connection.687566  - _log_response: RpcState(ExecuteQuery, 763dfa3e-9670-4306-b7df-92df01c351a1, ghrun-fryxcz52gi.auto.internal:6537): response = { <_MultiThreadedRendezvous object> }
2025-12-04 21:05:43,947 - DEBUG - ydb.query.pool.687566  - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWZhMjFhYjYtYTRiMGNmMjAtYjVhNGI1M2QtYzViNGM4Mg==
2025-12-04 21:05:43,954 - INFO - root.687566  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_15_compression`, volumes `value` (8000000, 1870856)
2025-12-04 21:05:43,954 - INFO - root.687566  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_15_compression` 8092160 / 1870856: 4.325378329491954
2025-12-04 21:05:43,988 - INFO - ya.test.687566  - pytest_runtest_teardown: Test teardown
