2025-11-19 11:19:50,491 - INFO - ya.test.418882  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:19:50,491 - INFO - ya.test.418882  - pytest_runtest_setup: test_all_supported_compression[zstd_20_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 20]
2025-11-19 11:19:50,492 - INFO - ya.test.418882  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:19:50,492 - INFO - ya.test.418882  - pytest_runtest_setup: Test setup
2025-11-19 11:19:50,495 - DEBUG - ya.test.418882  - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-19 11:19:50,496 - DEBUG - ya.test.418882  - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-19 11:19:50,496 - DEBUG - ya.test.418882  - execute: Executing '['/home/runner/.ya/build/build_root/pbux/00155d/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-19 11:19:50,499 - DEBUG - ya.test.418882  - execute: Command pid: 420519
2025-11-19 11:19:51,356 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) rc: 0
2025-11-19 11:19:51,357 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) elapsed time (sec): 0.8557565212249756
2025-11-19 11:19:51,357 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) idrss: 0
2025-11-19 11:19:51,358 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) inblock: 0
2025-11-19 11:19:51,358 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) isrss: 0
2025-11-19 11:19:51,359 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) ixrss: 0
2025-11-19 11:19:51,359 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) majflt: 0
2025-11-19 11:19:51,359 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) maxrss: 491012
2025-11-19 11:19:51,359 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) minflt: 45463
2025-11-19 11:19:51,360 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) msgrcv: 0
2025-11-19 11:19:51,360 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) msgsnd: 0
2025-11-19 11:19:51,360 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) nivcsw: 132
2025-11-19 11:19:51,361 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) nsignals: 0
2025-11-19 11:19:51,361 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) nswap: 0
2025-11-19 11:19:51,361 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) nvcsw: 15
2025-11-19 11:19:51,362 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) oublock: 8
2025-11-19 11:19:51,362 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) stime: 0.21345
2025-11-19 11:19:51,362 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) utime: 0.508454
2025-11-19 11:19:51,363 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) wtime: 0.859
2025-11-19 11:19:51,363 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) output:
Arc info:
    Branch: unknown-vcs-branch
    Commit: THIS_REVISION_IS_A_DUMMY
    Author: ordinal
    Summary: No VCS
    Last Changed Rev: -1
    Last Changed Date: 2015-03-14T06:05:35.000000Z
Other info:
    Build by: runner
    Top src dir: 
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-alsqrntpxi    Host information: 
        Linux ghrun-alsqrntpxi 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-11-19 11:19:51,363 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420519) errors:

2025-11-19 11:19:51,368 - INFO - __tests__.base.418882  - _setup_ydb: Arc info:
    Branch: unknown-vcs-branch
    Commit: THIS_REVISION_IS_A_DUMMY
    Author: ordinal
    Summary: No VCS
    Last Changed Rev: -1
    Last Changed Date: 2015-03-14T06:05:35.000000Z
Other info:
    Build by: runner
    Top src dir: 
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-alsqrntpxi    Host information: 
        Linux ghrun-alsqrntpxi 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-11-19 11:19:51,552 - DEBUG - ya.test.418882  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pbux/00155d/ydb/apps/ydbd/ydbd
2025-11-19 11:19:51,553 - DEBUG - library.python.filelock.418882  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14939
2025-11-19 11:19:51,556 - DEBUG - ydb.tests.library.harness.kikimr_runner.418882  - start: Working directory: /home/runner/.ya/build/build_root/pbux/00155d/r3tmp/kikimr_cluster_7rjqdcu3
2025-11-19 11:19:51,602 - DEBUG - ya.test.418882  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pbux/00155d/ydb/apps/ydbd/ydbd
2025-11-19 11:19:51,603 - DEBUG - library.python.filelock.418882  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19887
2025-11-19 11:19:51,604 - DEBUG - library.python.filelock.418882  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14556
2025-11-19 11:19:51,605 - DEBUG - library.python.filelock.418882  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1734
2025-11-19 11:19:51,605 - DEBUG - library.python.filelock.418882  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62530
2025-11-19 11:19:51,606 - INFO - ydb.tests.library.harness.kikimr_runner.418882  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pbux/00155d/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/kikimr_configs"
2025-11-19 11:19:51,607 - INFO - ydb.tests.library.harness.kikimr_runner.418882  - __make_run_command: Final command: /home/runner/.ya/build/build_root/pbux/00155d/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/pbux/00155d/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/node_1/logfile_ccwiq0ha.log --grpc-port=19887 --mon-port=14556 --ic-port=14939
2025-11-19 11:19:51,607 - DEBUG - ydb.tests.library.harness.kikimr_runner.418882  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:19887/1, disk_size 68719476736
2025-11-19 11:19:51,608 - INFO - ydb.tests.library.harness.kikimr_runner.418882  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pbux/00155d/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/kikimr_configs"
2025-11-19 11:19:51,608 - INFO - ydb.tests.library.harness.kikimr_runner.418882  - __make_run_command: Final command: /home/runner/.ya/build/build_root/pbux/00155d/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/pbux/00155d/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/node_1/logfile_ccwiq0ha.log --grpc-port=19887 --mon-port=14556 --ic-port=14939
2025-11-19 11:19:51,610 - DEBUG - ya.test.418882  - execute: Executing '('/home/runner/.ya/build/build_root/pbux/00155d/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/pbux/00155d/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/pbux/00155d/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/node_1/logfile_ccwiq0ha.log', '--grpc-port=19887', '--mon-port=14556', '--ic-port=14939')' in '/home/runner/.ya/build/build_root/pbux/00155d/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/node_1' (no wait)
2025-11-19 11:19:51,613 - DEBUG - ya.test.418882  - execute: Command pid: 420992
2025-11-19 11:19:51,614 - INFO - ydb.tests.library.harness.kikimr_runner.418882  - start: Started node localhost:19887/1
2025-11-19 11:19:51,616 - DEBUG - ya.test.418882  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-19 11:19:51,617 - DEBUG - ya.test.418882  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-19 11:19:51,617 - DEBUG - ya.test.418882  - execute: Executing '['/home/runner/.ya/build/build_root/pbux/00155d/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-19 11:19:51,624 - DEBUG - ya.test.418882  - execute: Command pid: 420993
2025-11-19 11:19:52,436 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) rc: 0
2025-11-19 11:19:52,437 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) elapsed time (sec): 0.8106570243835449
2025-11-19 11:19:52,437 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) idrss: 0
2025-11-19 11:19:52,438 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) inblock: 0
2025-11-19 11:19:52,438 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) isrss: 0
2025-11-19 11:19:52,438 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) ixrss: 0
2025-11-19 11:19:52,439 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) majflt: 0
2025-11-19 11:19:52,439 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) maxrss: 496328
2025-11-19 11:19:52,439 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) minflt: 45423
2025-11-19 11:19:52,441 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) msgrcv: 0
2025-11-19 11:19:52,442 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) msgsnd: 0
2025-11-19 11:19:52,442 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) nivcsw: 96
2025-11-19 11:19:52,443 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) nsignals: 0
2025-11-19 11:19:52,443 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) nswap: 0
2025-11-19 11:19:52,443 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) nvcsw: 15
2025-11-19 11:19:52,443 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) oublock: 8
2025-11-19 11:19:52,444 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) stime: 0.22139099999999998
2025-11-19 11:19:52,444 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) utime: 0.520976
2025-11-19 11:19:52,444 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) wtime: 0.818
2025-11-19 11:19:52,445 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) output:
Arc info:
    Branch: unknown-vcs-branch
    Commit: THIS_REVISION_IS_A_DUMMY
    Author: ordinal
    Summary: No VCS
    Last Changed Rev: -1
    Last Changed Date: 2015-03-14T06:05:35.000000Z
Other info:
    Build by: runner
    Top src dir: 
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-alsqrntpxi    Host information: 
        Linux ghrun-alsqrntpxi 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-11-19 11:19:52,445 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 420993) errors:

2025-11-19 11:19:52,446 - INFO - ydb.tests.library.harness.kikimr_runner.418882  - start: Node 1 version:
Arc info:
    Branch: unknown-vcs-branch
    Commit: THIS_REVISION_IS_A_DUMMY
    Author: ordinal
    Summary: No VCS
    Last Changed Rev: -1
    Last Changed Date: 2015-03-14T06:05:35.000000Z
Other info:
    Build by: runner
    Top src dir: 
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-alsqrntpxi    Host information: 
        Linux ghrun-alsqrntpxi 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64
2025-11-19 11:19:57,593 - DEBUG - ya.test.418882  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pbux/00155d/ydb/apps/ydbd/ydbd
2025-11-19 11:19:57,593 - DEBUG - ydb.tests.library.harness.kikimr_runner.418882  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/pbux/00155d/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:19887', '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: 14939\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-19 11:19:57,595 - DEBUG - ya.test.418882  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-19 11:19:57,595 - DEBUG - ya.test.418882  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-19 11:19:57,596 - DEBUG - ya.test.418882  - execute: Executing '['/home/runner/.ya/build/build_root/pbux/00155d/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:19887', '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: 14939\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-19 11:19:57,598 - DEBUG - ya.test.418882  - execute: Command pid: 422464
2025-11-19 11:19:58,779 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) rc: 0
2025-11-19 11:19:58,779 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) elapsed time (sec): 1.1803035736083984
2025-11-19 11:19:58,780 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) idrss: 0
2025-11-19 11:19:58,780 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) inblock: 0
2025-11-19 11:19:58,780 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) isrss: 0
2025-11-19 11:19:58,780 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) ixrss: 0
2025-11-19 11:19:58,781 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) majflt: 0
2025-11-19 11:19:58,781 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) maxrss: 594972
2025-11-19 11:19:58,781 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) minflt: 51633
2025-11-19 11:19:58,781 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) msgrcv: 0
2025-11-19 11:19:58,782 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) msgsnd: 0
2025-11-19 11:19:58,782 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) nivcsw: 151
2025-11-19 11:19:58,782 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) nsignals: 0
2025-11-19 11:19:58,783 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) nswap: 0
2025-11-19 11:19:58,783 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) nvcsw: 598
2025-11-19 11:19:58,783 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) oublock: 8
2025-11-19 11:19:58,783 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) stime: 0.281114
2025-11-19 11:19:58,784 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) utime: 0.597407
2025-11-19 11:19:58,784 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) wtime: 1.182
2025-11-19 11:19:58,784 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-19 11:19:58,785 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422464) errors:

2025-11-19 11:19:58,786 - DEBUG - ya.test.418882  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pbux/00155d/ydb/apps/ydbd/ydbd
2025-11-19 11:19:58,786 - DEBUG - ydb.tests.library.harness.kikimr_runner.418882  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/pbux/00155d/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:19887', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-11-19 11:19:58,788 - DEBUG - ya.test.418882  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-19 11:19:58,789 - DEBUG - ya.test.418882  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-19 11:19:58,789 - DEBUG - ya.test.418882  - execute: Executing '['/home/runner/.ya/build/build_root/pbux/00155d/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:19887', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-19 11:19:58,796 - DEBUG - ya.test.418882  - execute: Command pid: 422597
2025-11-19 11:19:59,824 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) rc: 0
2025-11-19 11:19:59,825 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) elapsed time (sec): 1.0272026062011719
2025-11-19 11:19:59,825 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) idrss: 0
2025-11-19 11:19:59,826 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) inblock: 0
2025-11-19 11:19:59,826 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) isrss: 0
2025-11-19 11:19:59,826 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) ixrss: 0
2025-11-19 11:19:59,826 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) majflt: 0
2025-11-19 11:19:59,827 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) maxrss: 595184
2025-11-19 11:19:59,827 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) minflt: 51767
2025-11-19 11:19:59,827 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) msgrcv: 0
2025-11-19 11:19:59,827 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) msgsnd: 0
2025-11-19 11:19:59,828 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) nivcsw: 185
2025-11-19 11:19:59,828 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) nsignals: 0
2025-11-19 11:19:59,828 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) nswap: 0
2025-11-19 11:19:59,829 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) nvcsw: 588
2025-11-19 11:19:59,829 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) oublock: 8
2025-11-19 11:19:59,829 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) stime: 0.26501199999999997
2025-11-19 11:19:59,829 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) utime: 0.623074
2025-11-19 11:19:59,830 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) wtime: 1.034
2025-11-19 11:19:59,830 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-19 11:19:59,830 - DEBUG - ya.test.418882  - _save_outputs: Command (pid 422597) errors:

2025-11-19 11:19:59,831 - INFO - ydb.tests.library.harness.kikimr_runner.418882  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-19 11:20:00,021 - INFO - ydb.tests.library.harness.kikimr_runner.418882  - __run: Cluster started and initialized
2025-11-19 11:20:00,055 - DEBUG - ydb.connection.418882  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-19 11:20:00,067 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.418882  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:20:00,070 - DEBUG - ydb.connection.418882  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-19 11:20:00,088 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.418882  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:20:00,089 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ListEndpoints, 310823d3-f0a8-4c26-aa05-745ec0450176, localhost:19887): creating call state
2025-11-19 11:20:00,090 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ListEndpoints, 310823d3-f0a8-4c26-aa05-745ec0450176, localhost:19887): request = { database: "/Root" }
2025-11-19 11:20:00,109 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ListEndpoints, 310823d3-f0a8-4c26-aa05-745ec0450176, localhost:19887): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alsqrntpxi.auto.internal" port: 19887 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-19 11:20:00,110 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.418882  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alsqrntpxi.auto.internal:19887, location 1, ssl: False>]>
2025-11-19 11:20:00,112 - DEBUG - ydb.connection.418882  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-19 11:20:00,152 - DEBUG - ydb.connection.418882  - close: Closing channel for endpoint localhost:19887
2025-11-19 11:20:00,153 - DEBUG - ydb.query.pool.418882  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-19 11:20:00,155 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(CreateSession, ed9d74ab-e8fa-4951-b904-0b02d811708a, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:00,155 - DEBUG - ydb.connection.418882  - _log_request: RpcState(CreateSession, ed9d74ab-e8fa-4951-b904-0b02d811708a, ghrun-alsqrntpxi.auto.internal:19887): request = {  }
2025-11-19 11:20:00,165 - DEBUG - ydb.connection.418882  - _log_response: RpcState(CreateSession, ed9d74ab-e8fa-4951-b904-0b02d811708a, ghrun-alsqrntpxi.auto.internal:19887): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" node_id: 1 }
2025-11-19 11:20:00,167 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(AttachSession, 8c8d15f7-6feb-424c-9bdb-a6b991c062d5, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:00,167 - DEBUG - ydb.connection.418882  - _log_request: RpcState(AttachSession, 8c8d15f7-6feb-424c-9bdb-a6b991c062d5, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" }
2025-11-19 11:20:00,174 - DEBUG - ydb.connection.418882  - _log_response: RpcState(AttachSession, 8c8d15f7-6feb-424c-9bdb-a6b991c062d5, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:00,200 - DEBUG - ydb.query.pool.418882  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:00,201 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 511b273c-5e63-47af-ad1c-1ca349b316e7, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:00,202 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 511b273c-5e63-47af-ad1c-1ca349b316e7, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:00,205 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 511b273c-5e63-47af-ad1c-1ca349b316e7, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:03,285 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:03,286 - INFO - __tests__.alter_compression.418882  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-19 11:20:03,287 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:03,288 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 11677d72-d580-4120-bf1f-de8dde4b0e84, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:03,289 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 11677d72-d580-4120-bf1f-de8dde4b0e84, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:03,290 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 11677d72-d580-4120-bf1f-de8dde4b0e84, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:04,522 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:04,523 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:04,524 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 79aa1861-7937-4f67-afa3-c499b7d6183f, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:04,525 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 79aa1861-7937-4f67-afa3-c499b7d6183f, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:04,526 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 79aa1861-7937-4f67-afa3-c499b7d6183f, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:05,689 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:05,690 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:05,691 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, d225cc7e-792b-48ee-9436-f181e73ca328, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:05,692 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, d225cc7e-792b-48ee-9436-f181e73ca328, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:05,693 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, d225cc7e-792b-48ee-9436-f181e73ca328, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:06,598 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:06,599 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:06,600 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 633d5002-daeb-4dc2-8d4b-fd8bbb7508d5, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:06,601 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 633d5002-daeb-4dc2-8d4b-fd8bbb7508d5, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:06,603 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 633d5002-daeb-4dc2-8d4b-fd8bbb7508d5, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:08,124 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:08,125 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 1618432, 'BlobCount': 128}}
2025-11-19 11:20:08,125 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:08,126 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 033d9d1f-9a4c-4345-b8cd-7d8e36fe4240, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:08,126 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 033d9d1f-9a4c-4345-b8cd-7d8e36fe4240, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:08,127 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 033d9d1f-9a4c-4345-b8cd-7d8e36fe4240, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:09,021 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:09,022 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:09,023 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, d6cf245d-1857-48f7-8101-a1526da91fc8, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:09,024 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, d6cf245d-1857-48f7-8101-a1526da91fc8, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:09,025 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, d6cf245d-1857-48f7-8101-a1526da91fc8, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:09,248 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:09,248 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:09,249 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 74d698e8-967e-4695-8887-dfd33040cdf2, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:09,250 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 74d698e8-967e-4695-8887-dfd33040cdf2, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:09,251 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 74d698e8-967e-4695-8887-dfd33040cdf2, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:09,662 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:09,663 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 3236864, 'BlobCount': 256}}
2025-11-19 11:20:09,663 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:09,664 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 78fb9b7e-5091-45e8-9a5e-c11d34053534, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:09,665 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 78fb9b7e-5091-45e8-9a5e-c11d34053534, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:09,666 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 78fb9b7e-5091-45e8-9a5e-c11d34053534, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:10,800 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:10,801 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:10,801 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 0b71aa36-bdf8-442a-a050-28bc52d02c5d, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:10,802 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 0b71aa36-bdf8-442a-a050-28bc52d02c5d, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:10,805 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 0b71aa36-bdf8-442a-a050-28bc52d02c5d, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:11,060 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:11,061 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:11,062 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, baa5d546-4cc3-4a35-9d4b-01d607c3e88f, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:11,063 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, baa5d546-4cc3-4a35-9d4b-01d607c3e88f, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:11,068 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, baa5d546-4cc3-4a35-9d4b-01d607c3e88f, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:11,469 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:11,470 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 4855296, 'BlobCount': 384}}
2025-11-19 11:20:11,471 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:11,472 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 1eeb41ea-db86-462b-ae97-eb520f4f9dde, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:11,473 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 1eeb41ea-db86-462b-ae97-eb520f4f9dde, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:11,474 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 1eeb41ea-db86-462b-ae97-eb520f4f9dde, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:12,419 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:12,419 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:12,420 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, c235575f-f67b-46ba-b304-83b2000ee92d, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:12,421 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, c235575f-f67b-46ba-b304-83b2000ee92d, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:12,422 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, c235575f-f67b-46ba-b304-83b2000ee92d, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:12,761 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:12,762 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:12,763 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, aaa97c98-ded7-431c-a74b-bd5867799e43, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:12,764 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, aaa97c98-ded7-431c-a74b-bd5867799e43, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:12,765 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, aaa97c98-ded7-431c-a74b-bd5867799e43, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:13,160 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:13,161 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 6473728, 'BlobCount': 512}}
2025-11-19 11:20:13,162 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:13,163 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 420994e8-87c3-4065-9d65-613e8cb94ebd, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:13,164 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 420994e8-87c3-4065-9d65-613e8cb94ebd, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:13,165 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 420994e8-87c3-4065-9d65-613e8cb94ebd, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:14,219 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:14,220 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:14,221 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 955535d7-1a5d-4304-9240-af8a9f13b552, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:14,222 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 955535d7-1a5d-4304-9240-af8a9f13b552, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:14,223 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 955535d7-1a5d-4304-9240-af8a9f13b552, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:14,451 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:14,452 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:14,453 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 7c5de8b1-4fe3-48a0-9029-d082d7c6c031, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:14,454 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 7c5de8b1-4fe3-48a0-9029-d082d7c6c031, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:14,455 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 7c5de8b1-4fe3-48a0-9029-d082d7c6c031, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:14,868 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:14,869 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 8092160, 'BlobCount': 640}}
2025-11-19 11:20:14,870 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:14,871 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 9333c950-d1e0-4db5-959a-3b237e214b40, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:14,871 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 9333c950-d1e0-4db5-959a-3b237e214b40, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:14,873 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 9333c950-d1e0-4db5-959a-3b237e214b40, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:15,940 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:15,941 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:15,942 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 2e71078b-a2e2-4efb-b6b1-90631d6f1d6b, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:15,943 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 2e71078b-a2e2-4efb-b6b1-90631d6f1d6b, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:15,944 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 2e71078b-a2e2-4efb-b6b1-90631d6f1d6b, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:16,188 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:16,189 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:16,190 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 5f72b141-ab4c-4044-8d91-abc33e882df0, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:16,191 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 5f72b141-ab4c-4044-8d91-abc33e882df0, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:16,197 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 5f72b141-ab4c-4044-8d91-abc33e882df0, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:16,656 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:16,657 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 9710592, 'BlobCount': 768}}
2025-11-19 11:20:16,658 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:16,658 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 210231a7-d6b7-4e8c-8685-0c8f8c4c1943, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:16,660 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 210231a7-d6b7-4e8c-8685-0c8f8c4c1943, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:16,665 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 210231a7-d6b7-4e8c-8685-0c8f8c4c1943, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:17,772 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:17,773 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:17,774 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, ab6ddc75-4ea4-4190-bad9-786cf722705e, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:17,775 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, ab6ddc75-4ea4-4190-bad9-786cf722705e, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:17,776 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, ab6ddc75-4ea4-4190-bad9-786cf722705e, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:18,051 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:18,052 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:18,053 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, d88c0589-804d-4830-9750-4f59d7a01d07, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:18,053 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, d88c0589-804d-4830-9750-4f59d7a01d07, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:18,054 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, d88c0589-804d-4830-9750-4f59d7a01d07, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:18,764 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:18,765 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 11329024, 'BlobCount': 896}}
2025-11-19 11:20:18,766 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:18,766 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 6f912aa6-4980-4676-b503-2361bb342b0e, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:18,767 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 6f912aa6-4980-4676-b503-2361bb342b0e, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:18,769 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 6f912aa6-4980-4676-b503-2361bb342b0e, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:19,816 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:19,816 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:19,817 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, add36b5f-71e7-477b-b2fa-5527dd7e8f51, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:19,818 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, add36b5f-71e7-477b-b2fa-5527dd7e8f51, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:19,819 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, add36b5f-71e7-477b-b2fa-5527dd7e8f51, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:20,100 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:20,100 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:20,101 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, eb10787e-95d9-4ff1-9a4a-2e347a88b7b5, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:20,102 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, eb10787e-95d9-4ff1-9a4a-2e347a88b7b5, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:20,103 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, eb10787e-95d9-4ff1-9a4a-2e347a88b7b5, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:20,689 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:20,690 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 12947456, 'BlobCount': 1024}}
2025-11-19 11:20:20,691 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:20,691 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 3c7d520c-e566-43be-9354-59bdb88977b2, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:20,692 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 3c7d520c-e566-43be-9354-59bdb88977b2, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:20,694 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 3c7d520c-e566-43be-9354-59bdb88977b2, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:21,944 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:21,945 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:21,945 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 9c4fc23d-d1d3-4921-ac1d-d4bcd9be2bf1, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:21,946 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 9c4fc23d-d1d3-4921-ac1d-d4bcd9be2bf1, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:21,948 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 9c4fc23d-d1d3-4921-ac1d-d4bcd9be2bf1, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:22,308 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:22,309 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:22,310 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 0f54b9b4-4591-455d-b1d7-bf8df3b57554, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:22,311 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 0f54b9b4-4591-455d-b1d7-bf8df3b57554, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:22,313 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 0f54b9b4-4591-455d-b1d7-bf8df3b57554, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:23,116 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:23,117 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 14565888, 'BlobCount': 1152}}
2025-11-19 11:20:23,118 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:23,118 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, d54cdead-4fc1-49dc-b1eb-449eff68f854, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:23,119 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, d54cdead-4fc1-49dc-b1eb-449eff68f854, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:23,121 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, d54cdead-4fc1-49dc-b1eb-449eff68f854, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:24,298 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:24,298 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:24,299 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 5db7e572-782d-4e35-a7af-139a3c2f8131, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:24,300 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 5db7e572-782d-4e35-a7af-139a3c2f8131, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:24,304 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 5db7e572-782d-4e35-a7af-139a3c2f8131, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:24,611 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:24,611 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:24,612 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 9e5879cd-2243-49e1-80d9-a50c5425dc0f, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:24,613 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 9e5879cd-2243-49e1-80d9-a50c5425dc0f, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:24,620 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 9e5879cd-2243-49e1-80d9-a50c5425dc0f, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:25,389 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:25,390 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 16184320, 'BlobCount': 1280}}
2025-11-19 11:20:25,390 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:25,391 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 90e99f38-c7a0-43a8-ab0c-fd8e10d904df, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:25,391 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 90e99f38-c7a0-43a8-ab0c-fd8e10d904df, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:25,393 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 90e99f38-c7a0-43a8-ab0c-fd8e10d904df, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:26,498 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:26,499 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:26,500 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, ab8884f5-43dd-4c59-80c4-d4b653114e21, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:26,500 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, ab8884f5-43dd-4c59-80c4-d4b653114e21, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:26,502 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, ab8884f5-43dd-4c59-80c4-d4b653114e21, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:26,784 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:26,785 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:26,785 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 33ba9856-25d6-4b11-82c4-5e1e3f5af23c, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:26,786 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 33ba9856-25d6-4b11-82c4-5e1e3f5af23c, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:26,787 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 33ba9856-25d6-4b11-82c4-5e1e3f5af23c, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:27,264 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:27,265 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:27,266 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 9e0dc3f4-fb61-4255-9d81-d68dd1a3c495, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:27,267 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 9e0dc3f4-fb61-4255-9d81-d68dd1a3c495, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:27,269 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 9e0dc3f4-fb61-4255-9d81-d68dd1a3c495, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:32,041 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:42,092 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:42,093 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, db50f47b-60d5-43b7-9159-14665b9f73c6, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:42,094 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, db50f47b-60d5-43b7-9159-14665b9f73c6, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:42,095 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, db50f47b-60d5-43b7-9159-14665b9f73c6, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:44,759 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:44,795 - INFO - root.418882  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-19 11:20:44,796 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:44,797 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 0e17b63b-35b0-4e65-b4b8-d8cfd53bedee, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:44,798 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 0e17b63b-35b0-4e65-b4b8-d8cfd53bedee, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:44,799 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 0e17b63b-35b0-4e65-b4b8-d8cfd53bedee, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:47,223 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:57,260 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:20:57,261 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 45555103-814b-4813-8946-d048682dca6b, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:20:57,261 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 45555103-814b-4813-8946-d048682dca6b, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:20:57,263 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 45555103-814b-4813-8946-d048682dca6b, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:00,277 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:00,313 - INFO - root.418882  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-19 11:21:00,314 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:00,315 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, c3a385c5-d7f2-4874-8c2c-16e31d4e50f5, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:00,316 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, c3a385c5-d7f2-4874-8c2c-16e31d4e50f5, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:00,317 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, c3a385c5-d7f2-4874-8c2c-16e31d4e50f5, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:00,521 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:00,525 - INFO - ya.test.418882  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_20_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 20])
2025-11-19 11:21:00,526 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:00,527 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, a1317dbd-3add-4b71-993a-ba556c6c8afb, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:00,528 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, a1317dbd-3add-4b71-993a-ba556c6c8afb, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_20_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 20)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:00,530 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, a1317dbd-3add-4b71-993a-ba556c6c8afb, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:03,897 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:03,897 - INFO - __tests__.alter_compression.418882  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_20_compression created
2025-11-19 11:21:03,898 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:03,899 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, f612191a-5702-4bcb-8ddc-33e00fd8e086, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:03,900 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, f612191a-5702-4bcb-8ddc-33e00fd8e086, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_20_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:03,901 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, f612191a-5702-4bcb-8ddc-33e00fd8e086, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:04,651 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:04,652 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:04,653 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, a4c26cff-9185-4354-98b3-4b964a58cf12, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:04,654 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, a4c26cff-9185-4354-98b3-4b964a58cf12, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:04,656 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, a4c26cff-9185-4354-98b3-4b964a58cf12, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:08,674 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:08,675 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:08,675 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, c4d6f547-6aff-467b-a131-7e25b8670bf4, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:08,676 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, c4d6f547-6aff-467b-a131-7e25b8670bf4, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:08,678 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, c4d6f547-6aff-467b-a131-7e25b8670bf4, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:09,720 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:09,721 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:09,721 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, f3bb76a5-88c7-49d8-be5d-173d99654e21, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:09,722 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, f3bb76a5-88c7-49d8-be5d-173d99654e21, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:09,724 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, f3bb76a5-88c7-49d8-be5d-173d99654e21, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:11,564 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:11,565 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-11-19 11:21:11,565 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:11,566 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, cc8422ef-9bc3-4789-ab50-7beb7296831a, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:11,567 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, cc8422ef-9bc3-4789-ab50-7beb7296831a, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:11,568 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, cc8422ef-9bc3-4789-ab50-7beb7296831a, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:15,725 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:15,725 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:15,726 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 61b291c3-e45e-4157-8341-d4d5820e8175, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:15,727 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 61b291c3-e45e-4157-8341-d4d5820e8175, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:15,729 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 61b291c3-e45e-4157-8341-d4d5820e8175, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:16,003 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:16,004 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:16,005 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, bbbdd004-7670-4b35-9ea2-accc321dc30d, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:16,005 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, bbbdd004-7670-4b35-9ea2-accc321dc30d, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:16,007 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, bbbdd004-7670-4b35-9ea2-accc321dc30d, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:16,318 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:16,319 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-11-19 11:21:16,320 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:16,320 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 974ca868-9a4a-4805-aded-cb09e950062e, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:16,321 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 974ca868-9a4a-4805-aded-cb09e950062e, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:16,322 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 974ca868-9a4a-4805-aded-cb09e950062e, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:20,241 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:20,242 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:20,243 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, a46300a1-2c42-43bd-94c5-00a56990f4a0, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:20,244 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, a46300a1-2c42-43bd-94c5-00a56990f4a0, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:20,245 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, a46300a1-2c42-43bd-94c5-00a56990f4a0, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:20,512 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:20,513 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:20,513 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 0e3fe518-c27f-49eb-bfda-7b5f4d12db86, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:20,514 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 0e3fe518-c27f-49eb-bfda-7b5f4d12db86, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:20,515 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 0e3fe518-c27f-49eb-bfda-7b5f4d12db86, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:20,902 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:20,903 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-11-19 11:21:20,904 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:20,905 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 08066096-d0dc-422e-a73a-06319124af74, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:20,906 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 08066096-d0dc-422e-a73a-06319124af74, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:20,907 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 08066096-d0dc-422e-a73a-06319124af74, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:24,958 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:24,959 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:24,960 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, c0848138-fdeb-4c78-8c9a-77b556c1cdd0, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:24,961 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, c0848138-fdeb-4c78-8c9a-77b556c1cdd0, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:24,984 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, c0848138-fdeb-4c78-8c9a-77b556c1cdd0, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:25,296 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:25,297 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:25,297 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, e18e7d61-1775-4117-9ca9-4f3be4907820, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:25,298 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, e18e7d61-1775-4117-9ca9-4f3be4907820, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:25,302 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, e18e7d61-1775-4117-9ca9-4f3be4907820, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:25,715 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:25,716 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-11-19 11:21:25,716 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:25,718 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, fb07148f-c292-4a5a-a0ab-51c94b5b98be, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:25,718 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, fb07148f-c292-4a5a-a0ab-51c94b5b98be, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:25,720 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, fb07148f-c292-4a5a-a0ab-51c94b5b98be, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:29,923 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:29,924 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:29,925 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 89bd54d3-1772-4460-be8d-4a7ad2e9e0f7, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:29,925 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 89bd54d3-1772-4460-be8d-4a7ad2e9e0f7, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:29,927 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 89bd54d3-1772-4460-be8d-4a7ad2e9e0f7, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:30,292 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:30,293 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:30,294 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 832d9d1e-c86c-40ac-98ef-7ceb43a6359c, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:30,295 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 832d9d1e-c86c-40ac-98ef-7ceb43a6359c, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:30,296 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 832d9d1e-c86c-40ac-98ef-7ceb43a6359c, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:30,868 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:30,869 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-11-19 11:21:30,870 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:30,870 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 74b1c274-07c2-478f-8a73-785ad64d8a82, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:30,871 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 74b1c274-07c2-478f-8a73-785ad64d8a82, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:30,873 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 74b1c274-07c2-478f-8a73-785ad64d8a82, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:35,191 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:35,192 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:35,192 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 9b3ad206-0ba3-43c2-9d66-b315404255cd, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:35,194 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 9b3ad206-0ba3-43c2-9d66-b315404255cd, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:35,195 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 9b3ad206-0ba3-43c2-9d66-b315404255cd, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:35,461 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:35,462 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:35,462 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 8fedc2c7-b95d-4457-aa7d-4c65e721f1b6, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:35,463 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 8fedc2c7-b95d-4457-aa7d-4c65e721f1b6, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:35,465 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 8fedc2c7-b95d-4457-aa7d-4c65e721f1b6, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:36,050 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:36,050 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-11-19 11:21:36,051 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:36,052 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 2c880909-9d65-4926-a483-6d88c12a46f6, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:36,053 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 2c880909-9d65-4926-a483-6d88c12a46f6, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:36,054 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 2c880909-9d65-4926-a483-6d88c12a46f6, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:40,272 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:40,273 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:40,274 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 075d6cce-a422-42a5-95a2-47dd50457e1c, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:40,274 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 075d6cce-a422-42a5-95a2-47dd50457e1c, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:40,276 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 075d6cce-a422-42a5-95a2-47dd50457e1c, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:40,533 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:40,534 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:40,535 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, e7029d77-287e-4f19-bb46-b8779e9b8205, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:40,536 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, e7029d77-287e-4f19-bb46-b8779e9b8205, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:40,538 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, e7029d77-287e-4f19-bb46-b8779e9b8205, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:41,049 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:41,050 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-11-19 11:21:41,050 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:41,051 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 97c6c123-452f-44f4-bd79-774e0c192b01, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:41,052 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 97c6c123-452f-44f4-bd79-774e0c192b01, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:41,055 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 97c6c123-452f-44f4-bd79-774e0c192b01, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:45,013 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:45,014 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:45,019 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, bbce8d6f-51f9-4c7b-87d3-22d398c0569b, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:45,020 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, bbce8d6f-51f9-4c7b-87d3-22d398c0569b, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:45,021 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, bbce8d6f-51f9-4c7b-87d3-22d398c0569b, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:45,262 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:45,264 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:45,265 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, fb93f6b8-c03e-4e5f-8836-9bbe63f49506, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:45,266 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, fb93f6b8-c03e-4e5f-8836-9bbe63f49506, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:45,268 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, fb93f6b8-c03e-4e5f-8836-9bbe63f49506, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:45,764 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:45,764 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-11-19 11:21:45,765 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:45,766 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, a15522ac-7709-47df-8606-c5a4eb2a00ad, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:45,767 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, a15522ac-7709-47df-8606-c5a4eb2a00ad, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:45,768 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, a15522ac-7709-47df-8606-c5a4eb2a00ad, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:49,390 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:49,391 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:49,392 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 042aa80b-5fb2-44bc-a52d-0d2fb82fafbb, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:49,393 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 042aa80b-5fb2-44bc-a52d-0d2fb82fafbb, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:49,394 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 042aa80b-5fb2-44bc-a52d-0d2fb82fafbb, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:49,636 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:49,637 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:49,637 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 2f10db37-9610-4aad-80a5-e396be598421, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:49,638 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 2f10db37-9610-4aad-80a5-e396be598421, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:49,640 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 2f10db37-9610-4aad-80a5-e396be598421, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:50,173 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:50,174 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-11-19 11:21:50,174 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:50,175 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, a1b22586-25ed-4496-a52c-5d5b4619f5bd, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:50,176 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, a1b22586-25ed-4496-a52c-5d5b4619f5bd, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:50,177 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, a1b22586-25ed-4496-a52c-5d5b4619f5bd, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:52,208 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.418882  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:21:52,209 - DEBUG - ydb.connection.418882  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-19 11:21:52,226 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.418882  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:21:52,227 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ListEndpoints, 9733c310-65a2-4062-9716-f2bddb724a76, localhost:19887): creating call state
2025-11-19 11:21:52,228 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ListEndpoints, 9733c310-65a2-4062-9716-f2bddb724a76, localhost:19887): request = { database: "/Root" }
2025-11-19 11:21:52,232 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ListEndpoints, 9733c310-65a2-4062-9716-f2bddb724a76, localhost:19887): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alsqrntpxi.auto.internal" port: 19887 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-19 11:21:52,238 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.418882  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alsqrntpxi.auto.internal:19887, location 1, ssl: False>]>
2025-11-19 11:21:52,239 - DEBUG - ydb.connection.418882  - close: Closing channel for endpoint localhost:19887
2025-11-19 11:21:53,577 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:53,577 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:53,578 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, c7b2db31-8108-4f28-be7f-4ce1ed973a92, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:53,579 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, c7b2db31-8108-4f28-be7f-4ce1ed973a92, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:53,580 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, c7b2db31-8108-4f28-be7f-4ce1ed973a92, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:53,828 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:53,829 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:53,829 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 6ea90087-aba1-403b-867f-0ef637801243, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:53,830 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 6ea90087-aba1-403b-867f-0ef637801243, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:53,832 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 6ea90087-aba1-403b-867f-0ef637801243, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:54,544 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:54,545 - INFO - __tests__.alter_compression.418882  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-11-19 11:21:54,545 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:54,546 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 24334e7d-abdc-4cec-92b2-5f729daa9aae, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:54,547 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 24334e7d-abdc-4cec-92b2-5f729daa9aae, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_20_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:54,548 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 24334e7d-abdc-4cec-92b2-5f729daa9aae, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:55,705 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:55,705 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:55,706 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 496bfc86-7fb0-4821-9280-7f3115fba396, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:55,707 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 496bfc86-7fb0-4821-9280-7f3115fba396, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:55,708 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 496bfc86-7fb0-4821-9280-7f3115fba396, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:56,025 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:56,026 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:56,027 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, c949493e-79a5-4b95-b08a-02a11667abc1, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:56,028 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, c949493e-79a5-4b95-b08a-02a11667abc1, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:56,029 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, c949493e-79a5-4b95-b08a-02a11667abc1, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:56,387 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:56,387 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:21:56,388 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 87df2d15-9b75-4731-b787-acc380560bf1, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:21:56,389 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 87df2d15-9b75-4731-b787-acc380560bf1, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:56,390 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 87df2d15-9b75-4731-b787-acc380560bf1, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:01,869 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:22:11,923 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:22:11,924 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, f7b137e3-5232-4cd0-8630-2d836ed9a64d, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:22:11,925 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, f7b137e3-5232-4cd0-8630-2d836ed9a64d, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:11,926 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, f7b137e3-5232-4cd0-8630-2d836ed9a64d, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:18,934 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:22:29,001 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:22:29,002 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 55937165-3219-4ac9-a477-2f083936bac3, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:22:29,003 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 55937165-3219-4ac9-a477-2f083936bac3, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:29,004 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 55937165-3219-4ac9-a477-2f083936bac3, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:32,996 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:22:43,031 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:22:43,032 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, e823b253-cbde-461d-a924-a0d231b51040, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:22:43,032 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, e823b253-cbde-461d-a924-a0d231b51040, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:43,034 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, e823b253-cbde-461d-a924-a0d231b51040, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:45,335 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:22:55,345 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:22:55,346 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 59b4fb6e-01bc-4d2a-9457-9421e3c4a4aa, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:22:55,346 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 59b4fb6e-01bc-4d2a-9457-9421e3c4a4aa, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:55,351 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 59b4fb6e-01bc-4d2a-9457-9421e3c4a4aa, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:56,326 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:22:56,331 - INFO - root.418882  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_20_compression`, volumes `value` (8000000, 1465928)
2025-11-19 11:22:56,332 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:22:56,332 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, ed1673a5-390d-4760-863f-4b0a70d1e15d, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:22:56,334 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, ed1673a5-390d-4760-863f-4b0a70d1e15d, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:56,336 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, ed1673a5-390d-4760-863f-4b0a70d1e15d, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:56,910 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:22:56,910 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:22:56,911 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, ec1f5b44-4a88-42ed-8cf9-b34bb0779630, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:22:56,912 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, ec1f5b44-4a88-42ed-8cf9-b34bb0779630, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:56,917 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, ec1f5b44-4a88-42ed-8cf9-b34bb0779630, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:58,157 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:23:01,716 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.418882  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:23:01,717 - DEBUG - ydb.connection.418882  - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-19 11:23:01,765 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.418882  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:23:01,765 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ListEndpoints, 36b57cfd-870c-4f26-b74e-d4d3fe734eb4, localhost:19887): creating call state
2025-11-19 11:23:01,766 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ListEndpoints, 36b57cfd-870c-4f26-b74e-d4d3fe734eb4, localhost:19887): request = { database: "/Root" }
2025-11-19 11:23:01,778 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ListEndpoints, 36b57cfd-870c-4f26-b74e-d4d3fe734eb4, localhost:19887): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alsqrntpxi.auto.internal" port: 19887 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-19 11:23:01,779 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.418882  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alsqrntpxi.auto.internal:19887, location 1, ssl: False>]>
2025-11-19 11:23:01,781 - DEBUG - ydb.connection.418882  - close: Closing channel for endpoint localhost:19887
2025-11-19 11:23:08,162 - DEBUG - ydb.query.pool.418882  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:23:08,163 - DEBUG - ydb.connection.418882  - _prepare_call: RpcState(ExecuteQuery, 585977a0-2ffd-4e3c-97a4-a2029f23cdc7, ghrun-alsqrntpxi.auto.internal:19887): creating call state
2025-11-19 11:23:08,164 - DEBUG - ydb.connection.418882  - _log_request: RpcState(ExecuteQuery, 585977a0-2ffd-4e3c-97a4-a2029f23cdc7, ghrun-alsqrntpxi.auto.internal:19887): request = { session_id: "ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:23:08,166 - DEBUG - ydb.connection.418882  - _log_response: RpcState(ExecuteQuery, 585977a0-2ffd-4e3c-97a4-a2029f23cdc7, ghrun-alsqrntpxi.auto.internal:19887): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:23:09,390 - DEBUG - ydb.query.pool.418882  - release: Session returned to queue: ydb://session/3?node_id=1&id=NWVkMzMxMDgtOGY0ZmI0YjItNzZlNzlkMjEtYzlkNmZhMjk=
2025-11-19 11:23:09,394 - INFO - root.418882  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_20_compression`, volumes `value` (8000000, 1465928)
2025-11-19 11:23:09,395 - INFO - root.418882  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_20_compression` 8092160 / 1465928: 5.520161972484324
2025-11-19 11:23:09,397 - INFO - ya.test.418882  - pytest_runtest_teardown: Test teardown
