2025-11-19 11:19:52,595 - INFO - ya.test.419682  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:19:52,595 - INFO - ya.test.419682  - pytest_runtest_setup: test_all_supported_compression[zstd_12_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 12]
2025-11-19 11:19:52,597 - INFO - ya.test.419682  - pytest_runtest_setup: ####################################################################################################
2025-11-19 11:19:52,597 - INFO - ya.test.419682  - pytest_runtest_setup: Test setup
2025-11-19 11:19:52,599 - DEBUG - ya.test.419682  - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-19 11:19:52,600 - DEBUG - ya.test.419682  - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-19 11:19:52,600 - DEBUG - ya.test.419682  - execute: Executing '['/home/runner/.ya/build/build_root/pbux/00155c/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-19 11:19:52,602 - DEBUG - ya.test.419682  - execute: Command pid: 421440
2025-11-19 11:19:53,448 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) rc: 0
2025-11-19 11:19:53,449 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) elapsed time (sec): 0.8451800346374512
2025-11-19 11:19:53,449 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) idrss: 0
2025-11-19 11:19:53,449 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) inblock: 0
2025-11-19 11:19:53,450 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) isrss: 0
2025-11-19 11:19:53,450 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) ixrss: 0
2025-11-19 11:19:53,450 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) majflt: 0
2025-11-19 11:19:53,450 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) maxrss: 493848
2025-11-19 11:19:53,451 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) minflt: 45354
2025-11-19 11:19:53,451 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) msgrcv: 0
2025-11-19 11:19:53,451 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) msgsnd: 0
2025-11-19 11:19:53,451 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) nivcsw: 143
2025-11-19 11:19:53,451 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) nsignals: 0
2025-11-19 11:19:53,454 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) nswap: 0
2025-11-19 11:19:53,455 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) nvcsw: 14
2025-11-19 11:19:53,455 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) oublock: 8
2025-11-19 11:19:53,455 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) stime: 0.198982
2025-11-19 11:19:53,456 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) utime: 0.550825
2025-11-19 11:19:53,456 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) wtime: 0.847
2025-11-19 11:19:53,456 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) 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:53,457 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421440) errors:

2025-11-19 11:19:53,457 - INFO - __tests__.base.419682  - _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:53,570 - DEBUG - ya.test.419682  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pbux/00155c/ydb/apps/ydbd/ydbd
2025-11-19 11:19:53,571 - DEBUG - library.python.filelock.419682  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23547
2025-11-19 11:19:53,574 - DEBUG - ydb.tests.library.harness.kikimr_runner.419682  - start: Working directory: /home/runner/.ya/build/build_root/pbux/00155c/r3tmp/kikimr_cluster_e_gz7ago
2025-11-19 11:19:53,619 - DEBUG - ya.test.419682  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pbux/00155c/ydb/apps/ydbd/ydbd
2025-11-19 11:19:53,619 - DEBUG - library.python.filelock.419682  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10572
2025-11-19 11:19:53,620 - DEBUG - library.python.filelock.419682  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6745
2025-11-19 11:19:53,621 - DEBUG - library.python.filelock.419682  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11876
2025-11-19 11:19:53,621 - DEBUG - library.python.filelock.419682  - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8490
2025-11-19 11:19:53,622 - INFO - ydb.tests.library.harness.kikimr_runner.419682  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pbux/00155c/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk1/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_12_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.12/cluster/kikimr_configs"
2025-11-19 11:19:53,623 - INFO - ydb.tests.library.harness.kikimr_runner.419682  - __make_run_command: Final command: /home/runner/.ya/build/build_root/pbux/00155c/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/00155c/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk1/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_12_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.12/cluster/node_1/logfile_04mhxmnm.log --grpc-port=10572 --mon-port=6745 --ic-port=23547
2025-11-19 11:19:53,623 - DEBUG - ydb.tests.library.harness.kikimr_runner.419682  - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:10572/1, disk_size 68719476736
2025-11-19 11:19:53,623 - INFO - ydb.tests.library.harness.kikimr_runner.419682  - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/pbux/00155c/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk1/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_12_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.12/cluster/kikimr_configs"
2025-11-19 11:19:53,624 - INFO - ydb.tests.library.harness.kikimr_runner.419682  - __make_run_command: Final command: /home/runner/.ya/build/build_root/pbux/00155c/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/00155c/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk1/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_12_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.12/cluster/node_1/logfile_04mhxmnm.log --grpc-port=10572 --mon-port=6745 --ic-port=23547
2025-11-19 11:19:53,625 - DEBUG - ya.test.419682  - execute: Executing '('/home/runner/.ya/build/build_root/pbux/00155c/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/pbux/00155c/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk1/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_12_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.12/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/pbux/00155c/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk1/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_12_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.12/cluster/node_1/logfile_04mhxmnm.log', '--grpc-port=10572', '--mon-port=6745', '--ic-port=23547')' in '/home/runner/.ya/build/build_root/pbux/00155c/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk1/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_12_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.12/cluster/node_1' (no wait)
2025-11-19 11:19:53,648 - DEBUG - ya.test.419682  - execute: Command pid: 421677
2025-11-19 11:19:53,649 - INFO - ydb.tests.library.harness.kikimr_runner.419682  - start: Started node localhost:10572/1
2025-11-19 11:19:53,650 - DEBUG - ya.test.419682  - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-19 11:19:53,651 - DEBUG - ya.test.419682  - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-19 11:19:53,652 - DEBUG - ya.test.419682  - execute: Executing '['/home/runner/.ya/build/build_root/pbux/00155c/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-19 11:19:53,676 - DEBUG - ya.test.419682  - execute: Command pid: 421679
2025-11-19 11:19:54,650 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) rc: 0
2025-11-19 11:19:54,651 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) elapsed time (sec): 0.9727482795715332
2025-11-19 11:19:54,651 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) idrss: 0
2025-11-19 11:19:54,651 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) inblock: 0
2025-11-19 11:19:54,652 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) isrss: 0
2025-11-19 11:19:54,652 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) ixrss: 0
2025-11-19 11:19:54,652 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) majflt: 0
2025-11-19 11:19:54,653 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) maxrss: 504752
2025-11-19 11:19:54,653 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) minflt: 45413
2025-11-19 11:19:54,653 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) msgrcv: 0
2025-11-19 11:19:54,654 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) msgsnd: 0
2025-11-19 11:19:54,654 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) nivcsw: 153
2025-11-19 11:19:54,655 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) nsignals: 0
2025-11-19 11:19:54,655 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) nswap: 0
2025-11-19 11:19:54,655 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) nvcsw: 14
2025-11-19 11:19:54,656 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) oublock: 8
2025-11-19 11:19:54,656 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) stime: 0.24331699999999998
2025-11-19 11:19:54,656 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) utime: 0.530471
2025-11-19 11:19:54,657 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) wtime: 0.997
2025-11-19 11:19:54,657 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) 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:54,658 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 421679) errors:

2025-11-19 11:19:54,658 - INFO - ydb.tests.library.harness.kikimr_runner.419682  - 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:59,744 - DEBUG - ya.test.419682  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pbux/00155c/ydb/apps/ydbd/ydbd
2025-11-19 11:19:59,745 - DEBUG - ydb.tests.library.harness.kikimr_runner.419682  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/pbux/00155c/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10572', '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: 23547\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-19 11:19:59,746 - DEBUG - ya.test.419682  - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-19 11:19:59,746 - DEBUG - ya.test.419682  - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-19 11:19:59,747 - DEBUG - ya.test.419682  - execute: Executing '['/home/runner/.ya/build/build_root/pbux/00155c/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10572', '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: 23547\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-19 11:19:59,748 - DEBUG - ya.test.419682  - execute: Command pid: 422747
2025-11-19 11:20:00,944 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) rc: 0
2025-11-19 11:20:00,945 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) elapsed time (sec): 1.1954405307769775
2025-11-19 11:20:00,945 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) idrss: 0
2025-11-19 11:20:00,945 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) inblock: 0
2025-11-19 11:20:00,946 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) isrss: 0
2025-11-19 11:20:00,946 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) ixrss: 0
2025-11-19 11:20:00,946 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) majflt: 0
2025-11-19 11:20:00,946 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) maxrss: 604988
2025-11-19 11:20:00,947 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) minflt: 51742
2025-11-19 11:20:00,947 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) msgrcv: 0
2025-11-19 11:20:00,947 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) msgsnd: 0
2025-11-19 11:20:00,948 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) nivcsw: 202
2025-11-19 11:20:00,948 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) nsignals: 0
2025-11-19 11:20:00,948 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) nswap: 0
2025-11-19 11:20:00,948 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) nvcsw: 575
2025-11-19 11:20:00,949 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) oublock: 8
2025-11-19 11:20:00,949 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) stime: 0.307467
2025-11-19 11:20:00,949 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) utime: 0.5894969999999999
2025-11-19 11:20:00,950 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) wtime: 1.197
2025-11-19 11:20:00,950 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-19 11:20:00,950 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 422747) errors:

2025-11-19 11:20:00,952 - DEBUG - ya.test.419682  - get_binary: Binary was found by /home/runner/.ya/build/build_root/pbux/00155c/ydb/apps/ydbd/ydbd
2025-11-19 11:20:00,952 - DEBUG - ydb.tests.library.harness.kikimr_runner.419682  - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/pbux/00155c/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10572', '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:20:00,953 - DEBUG - ya.test.419682  - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-19 11:20:00,954 - DEBUG - ya.test.419682  - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-19 11:20:00,955 - DEBUG - ya.test.419682  - execute: Executing '['/home/runner/.ya/build/build_root/pbux/00155c/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10572', '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:20:00,957 - DEBUG - ya.test.419682  - execute: Command pid: 423143
2025-11-19 11:20:02,222 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) rc: 0
2025-11-19 11:20:02,222 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) elapsed time (sec): 1.2637994289398193
2025-11-19 11:20:02,223 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) idrss: 0
2025-11-19 11:20:02,223 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) inblock: 0
2025-11-19 11:20:02,223 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) isrss: 0
2025-11-19 11:20:02,224 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) ixrss: 0
2025-11-19 11:20:02,224 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) majflt: 0
2025-11-19 11:20:02,224 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) maxrss: 605344
2025-11-19 11:20:02,225 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) minflt: 51656
2025-11-19 11:20:02,225 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) msgrcv: 0
2025-11-19 11:20:02,225 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) msgsnd: 0
2025-11-19 11:20:02,226 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) nivcsw: 212
2025-11-19 11:20:02,226 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) nsignals: 0
2025-11-19 11:20:02,226 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) nswap: 0
2025-11-19 11:20:02,226 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) nvcsw: 514
2025-11-19 11:20:02,227 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) oublock: 8
2025-11-19 11:20:02,227 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) stime: 0.298444
2025-11-19 11:20:02,227 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) utime: 0.615856
2025-11-19 11:20:02,228 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) wtime: 1.266
2025-11-19 11:20:02,228 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-19 11:20:02,228 - DEBUG - ya.test.419682  - _save_outputs: Command (pid 423143) errors:

2025-11-19 11:20:02,229 - INFO - ydb.tests.library.harness.kikimr_runner.419682  - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-19 11:20:02,313 - INFO - ydb.tests.library.harness.kikimr_runner.419682  - __run: Cluster started and initialized
2025-11-19 11:20:02,356 - DEBUG - ydb.connection.419682  - 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:02,376 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:20:02,377 - DEBUG - ydb.connection.419682  - 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:02,419 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:20:02,420 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ListEndpoints, 0b9b27f7-ba93-4572-9c02-6099274980aa, localhost:10572): creating call state
2025-11-19 11:20:02,421 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ListEndpoints, 0b9b27f7-ba93-4572-9c02-6099274980aa, localhost:10572): request = { database: "/Root" }
2025-11-19 11:20:02,434 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ListEndpoints, 0b9b27f7-ba93-4572-9c02-6099274980aa, localhost:10572): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alsqrntpxi.auto.internal" port: 10572 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:02,436 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alsqrntpxi.auto.internal:10572, location 1, ssl: False>]>
2025-11-19 11:20:02,437 - DEBUG - ydb.connection.419682  - 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:02,485 - DEBUG - ydb.connection.419682  - close: Closing channel for endpoint localhost:10572
2025-11-19 11:20:02,488 - DEBUG - ydb.query.pool.419682  - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-19 11:20:02,490 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(CreateSession, 7bb9923e-5bec-4256-9c64-46a5451f7ae3, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:02,491 - DEBUG - ydb.connection.419682  - _log_request: RpcState(CreateSession, 7bb9923e-5bec-4256-9c64-46a5451f7ae3, ghrun-alsqrntpxi.auto.internal:10572): request = {  }
2025-11-19 11:20:02,499 - DEBUG - ydb.connection.419682  - _log_response: RpcState(CreateSession, 7bb9923e-5bec-4256-9c64-46a5451f7ae3, ghrun-alsqrntpxi.auto.internal:10572): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" node_id: 1 }
2025-11-19 11:20:02,501 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(AttachSession, c85ce214-9ee2-4ff4-981f-849100ff07a9, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:02,501 - DEBUG - ydb.connection.419682  - _log_request: RpcState(AttachSession, c85ce214-9ee2-4ff4-981f-849100ff07a9, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" }
2025-11-19 11:20:02,521 - DEBUG - ydb.connection.419682  - _log_response: RpcState(AttachSession, c85ce214-9ee2-4ff4-981f-849100ff07a9, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:02,536 - DEBUG - ydb.query.pool.419682  - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:02,537 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 5860c3c0-eff0-4a6a-8737-ec45be4d7af4, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:02,538 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 5860c3c0-eff0-4a6a-8737-ec45be4d7af4, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:02,540 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 5860c3c0-eff0-4a6a-8737-ec45be4d7af4, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:05,858 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:05,859 - INFO - __tests__.alter_compression.419682  - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-19 11:20:05,860 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:05,861 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, c8054efe-bc17-4a7a-9b60-88edd7bb0127, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:05,862 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, c8054efe-bc17-4a7a-9b60-88edd7bb0127, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:05,865 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, c8054efe-bc17-4a7a-9b60-88edd7bb0127, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:06,936 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:06,937 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:06,938 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 170182e5-5256-4cfd-be91-746b0b5713c3, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:06,939 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 170182e5-5256-4cfd-be91-746b0b5713c3, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:06,941 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 170182e5-5256-4cfd-be91-746b0b5713c3, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:08,200 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:08,201 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:08,202 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, caa6ec88-aa5b-4ce2-9c29-a7acd5930823, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:08,203 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, caa6ec88-aa5b-4ce2-9c29-a7acd5930823, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:08,205 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, caa6ec88-aa5b-4ce2-9c29-a7acd5930823, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:08,984 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:08,985 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:08,986 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, a628f05d-5865-4323-a033-0d51abacb7ca, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:08,987 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, a628f05d-5865-4323-a033-0d51abacb7ca, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:08,990 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, a628f05d-5865-4323-a033-0d51abacb7ca, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:10,352 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:10,353 - INFO - __tests__.alter_compression.419682  - 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:10,353 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:10,354 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 0cca587d-695e-443b-97d0-5f8da0817259, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:10,354 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 0cca587d-695e-443b-97d0-5f8da0817259, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:10,355 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 0cca587d-695e-443b-97d0-5f8da0817259, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:11,399 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:11,400 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:11,400 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 5ea2d89c-37d6-45eb-9a84-3dcaca6958ce, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:11,401 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 5ea2d89c-37d6-45eb-9a84-3dcaca6958ce, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:11,403 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 5ea2d89c-37d6-45eb-9a84-3dcaca6958ce, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:11,617 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:11,618 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:11,619 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 4ecfc9ef-ea9a-40f6-891a-89b4f5f4a469, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:11,620 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 4ecfc9ef-ea9a-40f6-891a-89b4f5f4a469, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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,626 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 4ecfc9ef-ea9a-40f6-891a-89b4f5f4a469, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:11,934 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:11,935 - INFO - __tests__.alter_compression.419682  - 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:11,935 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:11,936 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 24aaf135-6bde-420a-9ad5-c533094aff50, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:11,938 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 24aaf135-6bde-420a-9ad5-c533094aff50, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:11,939 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 24aaf135-6bde-420a-9ad5-c533094aff50, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:12,921 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:12,922 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:12,923 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, d4282471-d6e4-4f69-ba21-0a1474e3fb7f, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:12,924 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, d4282471-d6e4-4f69-ba21-0a1474e3fb7f, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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,928 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, d4282471-d6e4-4f69-ba21-0a1474e3fb7f, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:13,177 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:13,178 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:13,179 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 3523ecb1-0297-4667-a39f-4885887c47ac, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:13,180 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 3523ecb1-0297-4667-a39f-4885887c47ac, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:13,182 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 3523ecb1-0297-4667-a39f-4885887c47ac, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:13,621 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:13,622 - INFO - __tests__.alter_compression.419682  - 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:13,623 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:13,624 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, b817e4d6-1dbc-4ff5-80a8-01888c2ca726, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:13,625 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, b817e4d6-1dbc-4ff5-80a8-01888c2ca726, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:13,627 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, b817e4d6-1dbc-4ff5-80a8-01888c2ca726, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:14,656 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:14,657 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:14,658 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 2a71f7e8-a211-44bd-92a3-9724d1e61bc2, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:14,659 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 2a71f7e8-a211-44bd-92a3-9724d1e61bc2, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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,661 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 2a71f7e8-a211-44bd-92a3-9724d1e61bc2, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:14,928 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:14,929 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:14,930 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, fb1c7121-b889-47b0-88f6-d12da60df20b, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:14,931 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, fb1c7121-b889-47b0-88f6-d12da60df20b, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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,932 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, fb1c7121-b889-47b0-88f6-d12da60df20b, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:15,411 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:15,411 - INFO - __tests__.alter_compression.419682  - 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:15,412 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:15,413 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, df5cd65d-45a1-41f2-940d-9f28937e7f31, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:15,414 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, df5cd65d-45a1-41f2-940d-9f28937e7f31, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:15,415 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, df5cd65d-45a1-41f2-940d-9f28937e7f31, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:16,376 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:16,377 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:16,377 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 90b74c30-19a4-4c68-aeac-50d009aafab5, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:16,378 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 90b74c30-19a4-4c68-aeac-50d009aafab5, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:16,379 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 90b74c30-19a4-4c68-aeac-50d009aafab5, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:16,614 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:16,614 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:16,615 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, fa1cb86c-e59c-4a93-8126-a94659c6da8b, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:16,616 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, fa1cb86c-e59c-4a93-8126-a94659c6da8b, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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,618 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, fa1cb86c-e59c-4a93-8126-a94659c6da8b, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:17,190 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:17,190 - INFO - __tests__.alter_compression.419682  - 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:17,191 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:17,191 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, b2389c35-1610-4a4d-866b-a2f0266c5217, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:17,192 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, b2389c35-1610-4a4d-866b-a2f0266c5217, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:17,194 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, b2389c35-1610-4a4d-866b-a2f0266c5217, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:18,226 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:18,227 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:18,227 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 41844aab-12ce-44c1-b443-717479b737a5, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:18,228 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 41844aab-12ce-44c1-b443-717479b737a5, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:18,230 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 41844aab-12ce-44c1-b443-717479b737a5, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:18,464 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:18,465 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:18,465 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, dae90b74-cc09-46e1-94ab-f4191e0e0a47, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:18,466 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, dae90b74-cc09-46e1-94ab-f4191e0e0a47, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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,468 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, dae90b74-cc09-46e1-94ab-f4191e0e0a47, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:18,924 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:18,925 - INFO - __tests__.alter_compression.419682  - 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:18,926 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:18,926 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 99b7d37a-ed6b-4436-83af-ab2045a7d14f, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:18,932 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 99b7d37a-ed6b-4436-83af-ab2045a7d14f, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:18,934 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 99b7d37a-ed6b-4436-83af-ab2045a7d14f, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:20,024 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:20,025 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:20,026 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 755bfd58-6814-4a84-a86d-ad665e59ff7e, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:20,026 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 755bfd58-6814-4a84-a86d-ad665e59ff7e, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:20,036 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 755bfd58-6814-4a84-a86d-ad665e59ff7e, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:20,349 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:20,350 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:20,351 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, b04c4897-c8a8-4c99-89e4-d79957bc633d, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:20,352 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, b04c4897-c8a8-4c99-89e4-d79957bc633d, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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,356 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, b04c4897-c8a8-4c99-89e4-d79957bc633d, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:20,923 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:20,924 - INFO - __tests__.alter_compression.419682  - 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:20,924 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:20,925 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, fcdddb7a-94c8-45d9-9473-d852c9b6c472, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:20,926 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, fcdddb7a-94c8-45d9-9473-d852c9b6c472, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:20,937 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, fcdddb7a-94c8-45d9-9473-d852c9b6c472, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:22,212 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:22,213 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:22,214 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, dcb911f4-b3ec-4cc2-ad99-fd01568c11d4, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:22,215 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, dcb911f4-b3ec-4cc2-ad99-fd01568c11d4, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:22,217 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, dcb911f4-b3ec-4cc2-ad99-fd01568c11d4, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:22,620 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:22,621 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:22,622 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, c8cdc68f-e7a2-4841-a9f8-d8e5b63f63ba, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:22,623 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, c8cdc68f-e7a2-4841-a9f8-d8e5b63f63ba, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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,626 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, c8cdc68f-e7a2-4841-a9f8-d8e5b63f63ba, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:23,326 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:23,327 - INFO - __tests__.alter_compression.419682  - 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:23,327 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:23,328 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 39d4934c-32bc-4e90-830d-aca6626f346b, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:23,329 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 39d4934c-32bc-4e90-830d-aca6626f346b, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:23,331 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 39d4934c-32bc-4e90-830d-aca6626f346b, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:24,408 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:24,409 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:24,414 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 86cbf6c2-948c-4a44-b596-1cee27da7ca6, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:24,415 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 86cbf6c2-948c-4a44-b596-1cee27da7ca6, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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,432 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 86cbf6c2-948c-4a44-b596-1cee27da7ca6, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:24,786 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:24,810 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:24,811 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 63dc2296-6ea3-4f9d-95bc-ab58575f2cf8, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:24,812 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 63dc2296-6ea3-4f9d-95bc-ab58575f2cf8, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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,814 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 63dc2296-6ea3-4f9d-95bc-ab58575f2cf8, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:25,620 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:25,621 - INFO - __tests__.alter_compression.419682  - 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:25,622 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:25,623 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, ddbd7295-9490-4ba6-bace-b3b2ded989a4, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:25,628 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, ddbd7295-9490-4ba6-bace-b3b2ded989a4, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:25,630 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, ddbd7295-9490-4ba6-bace-b3b2ded989a4, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:26,721 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:26,722 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:26,722 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 7b5c85cf-5e57-41c2-b3d2-08e2fef05fe2, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:26,723 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 7b5c85cf-5e57-41c2-b3d2-08e2fef05fe2, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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,724 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 7b5c85cf-5e57-41c2-b3d2-08e2fef05fe2, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:27,035 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:27,036 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:27,037 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, f169aaee-98aa-45e0-bc62-8b71f7a8d146, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:27,038 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, f169aaee-98aa-45e0-bc62-8b71f7a8d146, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:27,039 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, f169aaee-98aa-45e0-bc62-8b71f7a8d146, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:27,745 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:27,746 - INFO - __tests__.alter_compression.419682  - 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:27,747 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:27,748 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, f6ac0b59-b095-438c-9115-f9927b9a9682, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:27,748 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, f6ac0b59-b095-438c-9115-f9927b9a9682, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:27,750 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, f6ac0b59-b095-438c-9115-f9927b9a9682, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:28,962 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:28,963 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:28,964 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, c72c304e-a45f-4966-bf1f-7a5a8290e231, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:28,965 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, c72c304e-a45f-4966-bf1f-7a5a8290e231, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:28,966 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, c72c304e-a45f-4966-bf1f-7a5a8290e231, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:29,352 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:29,353 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:29,354 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, f1ece079-b239-4b50-a1e2-313ff0654a37, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:29,355 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, f1ece079-b239-4b50-a1e2-313ff0654a37, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:29,356 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, f1ece079-b239-4b50-a1e2-313ff0654a37, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:29,748 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:29,749 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:29,757 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, f1c1927c-61bf-4ae8-9d0e-e5f8c229d40f, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:29,758 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, f1c1927c-61bf-4ae8-9d0e-e5f8c229d40f, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:29,759 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, f1c1927c-61bf-4ae8-9d0e-e5f8c229d40f, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:34,157 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:44,199 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:44,200 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 627ebf61-9ca9-42f2-b7a9-030c20f9247f, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:44,201 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 627ebf61-9ca9-42f2-b7a9-030c20f9247f, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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,203 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 627ebf61-9ca9-42f2-b7a9-030c20f9247f, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:47,121 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:47,165 - INFO - root.419682  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-19 11:20:47,165 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:20:47,166 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 26f907a0-2132-461a-8185-a6f61231899b, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:20:47,167 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 26f907a0-2132-461a-8185-a6f61231899b, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:47,168 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 26f907a0-2132-461a-8185-a6f61231899b, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:20:50,136 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:00,182 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:00,183 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 2912e1ec-c3bb-43a7-982c-b68d6138c45c, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:00,184 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 2912e1ec-c3bb-43a7-982c-b68d6138c45c, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:21:00,185 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 2912e1ec-c3bb-43a7-982c-b68d6138c45c, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:02,942 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:03,002 - INFO - root.419682  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-19 11:21:03,003 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:03,003 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, cccc807c-c2da-4606-8845-fbfcd56dea4e, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:03,004 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, cccc807c-c2da-4606-8845-fbfcd56dea4e, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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:03,006 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, cccc807c-c2da-4606-8845-fbfcd56dea4e, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:03,248 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:03,251 - INFO - ya.test.419682  - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_12_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 12])
2025-11-19 11:21:03,251 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:03,252 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, f836791e-d1ac-4d0c-8f07-fdd1f9308226, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:03,253 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, f836791e-d1ac-4d0c-8f07-fdd1f9308226, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_12_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 12)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:03,254 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, f836791e-d1ac-4d0c-8f07-fdd1f9308226, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:06,824 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:06,825 - INFO - __tests__.alter_compression.419682  - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_12_compression created
2025-11-19 11:21:06,825 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:06,826 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 03684171-6f97-446d-8169-ad9141903135, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:06,826 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 03684171-6f97-446d-8169-ad9141903135, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_12_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:06,828 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 03684171-6f97-446d-8169-ad9141903135, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:07,772 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:07,773 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:07,773 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, a93d2dd1-445c-4edd-afff-6f85d29603ce, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:07,774 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, a93d2dd1-445c-4edd-afff-6f85d29603ce, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:07,776 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, a93d2dd1-445c-4edd-afff-6f85d29603ce, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:10,705 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:10,706 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:10,707 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 62325daf-b8ab-4af7-ba04-535f3daba710, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:10,708 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 62325daf-b8ab-4af7-ba04-535f3daba710, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:10,709 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 62325daf-b8ab-4af7-ba04-535f3daba710, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:11,701 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:11,702 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:11,703 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 252fa6c0-7ae5-49cc-aff1-bef7715a6d0a, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:11,704 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 252fa6c0-7ae5-49cc-aff1-bef7715a6d0a, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_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:11,707 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 252fa6c0-7ae5-49cc-aff1-bef7715a6d0a, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:13,635 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:13,636 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 375776, 'BlobCount': 128}}
2025-11-19 11:21:13,637 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:13,637 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 526423eb-6305-4b29-b3dc-92e9d3f1da2b, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:13,638 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 526423eb-6305-4b29-b3dc-92e9d3f1da2b, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:13,641 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 526423eb-6305-4b29-b3dc-92e9d3f1da2b, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:16,212 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:16,213 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:16,214 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 85f4f1b4-ea70-47a1-b173-46c849ab7af6, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:16,215 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 85f4f1b4-ea70-47a1-b173-46c849ab7af6, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:16,220 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 85f4f1b4-ea70-47a1-b173-46c849ab7af6, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:16,529 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:16,530 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:16,531 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, ea8d32b8-10b6-4a33-9f70-34f9676d4faa, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:16,532 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, ea8d32b8-10b6-4a33-9f70-34f9676d4faa, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_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,533 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, ea8d32b8-10b6-4a33-9f70-34f9676d4faa, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:16,921 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:16,922 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 753024, 'BlobCount': 256}}
2025-11-19 11:21:16,922 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:16,923 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 47b21cfe-626e-454f-a6f5-da29989a7d89, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:16,924 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 47b21cfe-626e-454f-a6f5-da29989a7d89, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:16,925 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 47b21cfe-626e-454f-a6f5-da29989a7d89, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:19,126 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:19,127 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:19,128 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, e355e260-be17-4fa7-80f6-ecab978feff5, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:19,129 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, e355e260-be17-4fa7-80f6-ecab978feff5, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:19,130 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, e355e260-be17-4fa7-80f6-ecab978feff5, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:19,435 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:19,436 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:19,437 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, f7ecdfd0-d149-4ea2-97ba-694c799eb103, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:19,438 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, f7ecdfd0-d149-4ea2-97ba-694c799eb103, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_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:19,441 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, f7ecdfd0-d149-4ea2-97ba-694c799eb103, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:19,871 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:19,872 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 1129904, 'BlobCount': 384}}
2025-11-19 11:21:19,873 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:19,873 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 97bb946b-7c26-492d-9e55-75d6ceb4c562, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:19,874 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 97bb946b-7c26-492d-9e55-75d6ceb4c562, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:19,875 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 97bb946b-7c26-492d-9e55-75d6ceb4c562, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:19,949 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-19 11:21:19,950 - DEBUG - ydb.connection.419682  - 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:19,974 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - context_resolve: Resolving endpoints for database /Root
2025-11-19 11:21:19,975 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ListEndpoints, 1470e5d5-c756-402a-82c8-65f060f4ddfb, localhost:10572): creating call state
2025-11-19 11:21:19,975 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ListEndpoints, 1470e5d5-c756-402a-82c8-65f060f4ddfb, localhost:10572): request = { database: "/Root" }
2025-11-19 11:21:19,986 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ListEndpoints, 1470e5d5-c756-402a-82c8-65f060f4ddfb, localhost:10572): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alsqrntpxi.auto.internal" port: 10572 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:19,988 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver.419682  - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alsqrntpxi.auto.internal:10572, location 1, ssl: False>]>
2025-11-19 11:21:19,990 - DEBUG - ydb.connection.419682  - close: Closing channel for endpoint localhost:10572
2025-11-19 11:21:22,196 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:22,197 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:22,197 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, d6442191-e5e2-4df7-8529-8244f8f2586f, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:22,198 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, d6442191-e5e2-4df7-8529-8244f8f2586f, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:22,200 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, d6442191-e5e2-4df7-8529-8244f8f2586f, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:22,456 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:22,457 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:22,457 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, b265dcf8-f496-4f8a-8334-2ab02e5ed267, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:22,458 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, b265dcf8-f496-4f8a-8334-2ab02e5ed267, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_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:22,460 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, b265dcf8-f496-4f8a-8334-2ab02e5ed267, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:22,964 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:22,965 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1507088, 'BlobCount': 512}}
2025-11-19 11:21:22,965 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:22,966 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 9ecf3d5d-9cce-483f-9867-125cff89e9b6, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:22,967 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 9ecf3d5d-9cce-483f-9867-125cff89e9b6, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:22,968 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 9ecf3d5d-9cce-483f-9867-125cff89e9b6, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:25,408 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:25,409 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:25,410 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 56752e5e-4431-43a7-a1a6-d97c973333fa, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:25,411 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 56752e5e-4431-43a7-a1a6-d97c973333fa, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:25,412 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 56752e5e-4431-43a7-a1a6-d97c973333fa, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:25,658 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:25,662 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:25,663 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 0f189d02-f19c-4624-8183-7b67b9f098c7, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:25,664 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 0f189d02-f19c-4624-8183-7b67b9f098c7, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_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,666 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 0f189d02-f19c-4624-8183-7b67b9f098c7, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:26,196 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:26,197 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1883952, 'BlobCount': 640}}
2025-11-19 11:21:26,198 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:26,199 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 47fad110-7dd9-4159-9662-12c465dcf56d, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:26,200 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 47fad110-7dd9-4159-9662-12c465dcf56d, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:26,201 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 47fad110-7dd9-4159-9662-12c465dcf56d, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:28,586 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:28,587 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:28,587 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 61c43069-dca3-4b0d-9b7e-8ecd278b20be, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:28,588 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 61c43069-dca3-4b0d-9b7e-8ecd278b20be, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:28,593 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 61c43069-dca3-4b0d-9b7e-8ecd278b20be, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:28,960 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:28,961 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:28,962 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, dd11de1c-6b72-4365-af52-b1684ee30388, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:28,963 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, dd11de1c-6b72-4365-af52-b1684ee30388, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_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:28,964 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, dd11de1c-6b72-4365-af52-b1684ee30388, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:29,478 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:29,479 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 2261152, 'BlobCount': 768}}
2025-11-19 11:21:29,479 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:29,480 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 3ea77306-e741-4d34-93d9-bd441023b4e6, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:29,481 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 3ea77306-e741-4d34-93d9-bd441023b4e6, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:29,488 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 3ea77306-e741-4d34-93d9-bd441023b4e6, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:32,340 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:32,341 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:32,342 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 1815620e-6190-4ace-a67d-4afa4cd36283, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:32,343 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 1815620e-6190-4ace-a67d-4afa4cd36283, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:32,344 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 1815620e-6190-4ace-a67d-4afa4cd36283, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:32,629 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:32,630 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:32,634 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 44ab2e84-30b1-4ec4-97da-685cf1718951, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:32,635 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 44ab2e84-30b1-4ec4-97da-685cf1718951, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_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:32,648 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 44ab2e84-30b1-4ec4-97da-685cf1718951, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:33,272 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:33,273 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2638128, 'BlobCount': 896}}
2025-11-19 11:21:33,273 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:33,274 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 1cb030c0-0946-4642-a293-8b9c2444673b, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:33,275 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 1cb030c0-0946-4642-a293-8b9c2444673b, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:33,278 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 1cb030c0-0946-4642-a293-8b9c2444673b, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:35,744 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:35,748 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:35,749 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 316ce694-3f39-4593-98e8-fda803359132, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:35,750 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 316ce694-3f39-4593-98e8-fda803359132, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:35,751 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 316ce694-3f39-4593-98e8-fda803359132, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:36,136 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:36,137 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:36,138 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 36ea308f-4c86-4a70-915b-29818c099ae1, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:36,139 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 36ea308f-4c86-4a70-915b-29818c099ae1, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_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:36,141 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 36ea308f-4c86-4a70-915b-29818c099ae1, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:36,811 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:36,812 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 3015296, 'BlobCount': 1024}}
2025-11-19 11:21:36,814 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:36,815 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 30a94118-1852-4199-a37b-eaee1710ca3f, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:36,816 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 30a94118-1852-4199-a37b-eaee1710ca3f, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:36,817 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 30a94118-1852-4199-a37b-eaee1710ca3f, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:39,573 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:39,574 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:39,575 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 6a51aae9-1f75-4228-b923-753d15b30fcb, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:39,576 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 6a51aae9-1f75-4228-b923-753d15b30fcb, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:39,577 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 6a51aae9-1f75-4228-b923-753d15b30fcb, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:39,936 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:39,937 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:39,938 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 253e0e4e-5f49-4217-9c7d-ee9386a38369, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:39,939 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 253e0e4e-5f49-4217-9c7d-ee9386a38369, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_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:39,941 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 253e0e4e-5f49-4217-9c7d-ee9386a38369, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:40,705 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:40,706 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 3392304, 'BlobCount': 1152}}
2025-11-19 11:21:40,707 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:40,708 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 967d48a6-52fd-4e37-8a74-2333c3129c5f, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:40,709 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 967d48a6-52fd-4e37-8a74-2333c3129c5f, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:40,716 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 967d48a6-52fd-4e37-8a74-2333c3129c5f, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:43,120 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:43,120 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:43,121 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 1826ef9f-3c91-44da-afc9-a5b6a2eecf77, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:43,122 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 1826ef9f-3c91-44da-afc9-a5b6a2eecf77, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:43,124 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 1826ef9f-3c91-44da-afc9-a5b6a2eecf77, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:43,401 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:43,402 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:43,403 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 71b6c426-91b6-40e4-976a-08c372bba1ef, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:43,404 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 71b6c426-91b6-40e4-976a-08c372bba1ef, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_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:43,406 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 71b6c426-91b6-40e4-976a-08c372bba1ef, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:44,076 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:44,077 - INFO - __tests__.alter_compression.419682  - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_12_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3769616, 'BlobCount': 1280}}
2025-11-19 11:21:44,077 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:44,078 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 14a02ece-bbd7-4350-aab9-390b085ef82e, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:44,078 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 14a02ece-bbd7-4350-aab9-390b085ef82e, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_12_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:44,079 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 14a02ece-bbd7-4350-aab9-390b085ef82e, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:45,184 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:45,185 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:45,186 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 53f27634-473b-49b5-8ab7-494ee04b669a, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:45,187 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 53f27634-473b-49b5-8ab7-494ee04b669a, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:45,188 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 53f27634-473b-49b5-8ab7-494ee04b669a, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:45,461 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:45,461 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:45,462 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 6e7b5307-7f6c-42ad-8b64-950cb828bd21, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:45,468 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 6e7b5307-7f6c-42ad-8b64-950cb828bd21, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:45,470 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 6e7b5307-7f6c-42ad-8b64-950cb828bd21, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:45,737 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:45,737 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:45,738 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, fb256cc0-e078-4da0-a2f4-40900183615b, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:45,739 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, fb256cc0-e078-4da0-a2f4-40900183615b, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_12_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:45,741 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, fb256cc0-e078-4da0-a2f4-40900183615b, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:21:49,180 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:59,219 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:21:59,220 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 5d7d9e01-e7d4-4054-bbe9-24c147be31a2, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:21:59,221 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 5d7d9e01-e7d4-4054-bbe9-24c147be31a2, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_12_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:21:59,222 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 5d7d9e01-e7d4-4054-bbe9-24c147be31a2, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:07,391 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:22:07,560 - INFO - root.419682  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_12_compression`, volumes `value` (8000000, 1884808)
2025-11-19 11:22:07,561 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:22:07,562 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 2171f202-6aec-4228-972f-3ee09f25f82e, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:22:07,563 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 2171f202-6aec-4228-972f-3ee09f25f82e, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" 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_12_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:07,577 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 2171f202-6aec-4228-972f-3ee09f25f82e, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:08,385 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:22:08,386 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:22:08,387 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 37fe4d07-255c-4e79-b22a-ba47f0d23d4c, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:22:08,459 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 37fe4d07-255c-4e79-b22a-ba47f0d23d4c, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_12_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:08,465 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 37fe4d07-255c-4e79-b22a-ba47f0d23d4c, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:15,729 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:22:25,771 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:22:25,772 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, a8455130-d068-42d5-a62e-249ec6192cf3, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:22:25,773 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, a8455130-d068-42d5-a62e-249ec6192cf3, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_12_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:25,774 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, a8455130-d068-42d5-a62e-249ec6192cf3, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:29,666 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:22:39,700 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:22:39,701 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, 41acac4d-f444-4ed2-bd61-0ccd50895e93, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:22:39,702 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, 41acac4d-f444-4ed2-bd61-0ccd50895e93, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_12_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:39,704 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, 41acac4d-f444-4ed2-bd61-0ccd50895e93, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:41,088 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:22:51,097 - DEBUG - ydb.query.pool.419682  - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:22:51,097 - DEBUG - ydb.connection.419682  - _prepare_call: RpcState(ExecuteQuery, fc1e9321-5e38-479d-bfff-377b1d533cc3, ghrun-alsqrntpxi.auto.internal:10572): creating call state
2025-11-19 11:22:51,098 - DEBUG - ydb.connection.419682  - _log_request: RpcState(ExecuteQuery, fc1e9321-5e38-479d-bfff-377b1d533cc3, ghrun-alsqrntpxi.auto.internal:10572): request = { session_id: "ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_12_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-19 11:22:51,100 - DEBUG - ydb.connection.419682  - _log_response: RpcState(ExecuteQuery, fc1e9321-5e38-479d-bfff-377b1d533cc3, ghrun-alsqrntpxi.auto.internal:10572): response = { <_MultiThreadedRendezvous object> }
2025-11-19 11:22:52,576 - DEBUG - ydb.query.pool.419682  - release: Session returned to queue: ydb://session/3?node_id=1&id=MTEwN2ViOWItZGQxNGU3NDYtYzY5YjNhODEtNzk5MWM0MGI=
2025-11-19 11:22:52,580 - INFO - root.419682  - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_12_compression`, volumes `value` (8000000, 1465648)
2025-11-19 11:22:52,580 - INFO - root.419682  - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_12_compression` 8092160 / 1465648: 5.52121655404299
2025-11-19 11:22:52,582 - INFO - ya.test.419682  - pytest_runtest_teardown: Test teardown
