2025-10-16 17:16:43,223 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:16:43,229 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_20_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 20]
2025-10-16 17:16:43,229 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-16 17:16:43,229 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-16 17:16:43,232 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-10-16 17:16:43,237 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-10-16 17:16:43,238 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/001704/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 17:16:43,248 - DEBUG - ya.test - execute: Command pid: 1005124
2025-10-16 17:16:44,289 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) rc: 0
2025-10-16 17:16:44,289 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) elapsed time (sec): 1.0394539833068848
2025-10-16 17:16:44,290 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) idrss: 0
2025-10-16 17:16:44,290 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) inblock: 0
2025-10-16 17:16:44,290 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) isrss: 0
2025-10-16 17:16:44,291 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) ixrss: 0
2025-10-16 17:16:44,291 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) majflt: 0
2025-10-16 17:16:44,291 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) maxrss: 477868
2025-10-16 17:16:44,291 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) minflt: 45106
2025-10-16 17:16:44,292 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) msgrcv: 0
2025-10-16 17:16:44,292 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) msgsnd: 0
2025-10-16 17:16:44,294 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) nivcsw: 306
2025-10-16 17:16:44,294 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) nsignals: 0
2025-10-16 17:16:44,295 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) nswap: 0
2025-10-16 17:16:44,295 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) nvcsw: 14
2025-10-16 17:16:44,295 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) oublock: 8
2025-10-16 17:16:44,296 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) stime: 0.17684999999999998
2025-10-16 17:16:44,296 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) utime: 0.55926
2025-10-16 17:16:44,296 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) wtime: 1.05
2025-10-16 17:16:44,297 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) output:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a

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

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

2025-10-16 17:16:44,297 - DEBUG - ya.test - _save_outputs: Command (pid 1005124) errors:

2025-10-16 17:16:44,298 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a

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

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

2025-10-16 17:16:44,499 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/001704/ydb/apps/ydbd/ydbd
2025-10-16 17:16:44,501 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/23027
2025-10-16 17:16:44,503 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/b9t0/001704/r3tmp/kikimr_cluster_lgnhpbje
2025-10-16 17:16:44,571 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/001704/ydb/apps/ydbd/ydbd
2025-10-16 17:16:44,571 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13587
2025-10-16 17:16:44,572 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20944
2025-10-16 17:16:44,573 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7600
2025-10-16 17:16:44,573 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17011
2025-10-16 17:16:44,574 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/b9t0/001704/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/kikimr_configs"
2025-10-16 17:16:44,575 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/b9t0/001704/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/b9t0/001704/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/node_1/logfile_b6gsft0c.log --grpc-port=13587 --mon-port=20944 --ic-port=23027
2025-10-16 17:16:44,575 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:13587/1, disk_size 68719476736
2025-10-16 17:16:44,576 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/b9t0/001704/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/kikimr_configs"
2025-10-16 17:16:44,576 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/b9t0/001704/ydb/apps/ydbd/ydbd server --suppress-version-check --yaml-config=$CFG_DIR_PATH/config.yaml --node=1 --log-file-name=/home/runner/.ya/build/build_root/b9t0/001704/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/node_1/logfile_b6gsft0c.log --grpc-port=13587 --mon-port=20944 --ic-port=23027
2025-10-16 17:16:44,577 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/b9t0/001704/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/b9t0/001704/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/b9t0/001704/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/node_1/logfile_b6gsft0c.log', '--grpc-port=13587', '--mon-port=20944', '--ic-port=23027')' in '/home/runner/.ya/build/build_root/b9t0/001704/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_20_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.20/cluster/node_1' (no wait)
2025-10-16 17:16:44,589 - DEBUG - ya.test - execute: Command pid: 1005188
2025-10-16 17:16:44,590 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:13587/1
2025-10-16 17:16:44,591 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-10-16 17:16:44,596 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-10-16 17:16:44,597 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/001704/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-16 17:16:44,611 - DEBUG - ya.test - execute: Command pid: 1005189
2025-10-16 17:16:45,865 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) rc: 0
2025-10-16 17:16:45,865 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) elapsed time (sec): 1.2523279190063477
2025-10-16 17:16:45,866 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) idrss: 0
2025-10-16 17:16:45,866 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) inblock: 0
2025-10-16 17:16:45,866 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) isrss: 0
2025-10-16 17:16:45,867 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) ixrss: 0
2025-10-16 17:16:45,867 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) majflt: 0
2025-10-16 17:16:45,867 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) maxrss: 487556
2025-10-16 17:16:45,867 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) minflt: 45166
2025-10-16 17:16:45,868 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) msgrcv: 0
2025-10-16 17:16:45,868 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) msgsnd: 0
2025-10-16 17:16:45,869 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) nivcsw: 215
2025-10-16 17:16:45,869 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) nsignals: 0
2025-10-16 17:16:45,870 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) nswap: 0
2025-10-16 17:16:45,870 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) nvcsw: 14
2025-10-16 17:16:45,870 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) oublock: 8
2025-10-16 17:16:45,871 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) stime: 0.171503
2025-10-16 17:16:45,871 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) utime: 0.597147
2025-10-16 17:16:45,871 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) wtime: 1.267
2025-10-16 17:16:45,872 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) output:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a

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

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

2025-10-16 17:16:45,872 - DEBUG - ya.test - _save_outputs: Command (pid 1005189) errors:

2025-10-16 17:16:45,881 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: d615d0ffd6e2f23c896ca96121aca842413fb672
    Branch: 
    Author: Andrey Zaspa <zacpa@ya.ru>
    Summary: Merge 2f4fc663ace566d7ea72dd9c85ebd0092851e7e4 into 6819ac59eb1f43dd80b94010061f13b3ff17d91a
2025-10-16 17:16:52,046 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/001704/ydb/apps/ydbd/ydbd
2025-10-16 17:16:52,050 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/b9t0/001704/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:13587', '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: 23027\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-16 17:16:52,052 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-10-16 17:16:52,053 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-10-16 17:16:52,053 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/001704/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:13587', '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: 23027\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-16 17:16:52,058 - DEBUG - ya.test - execute: Command pid: 1005913
2025-10-16 17:16:53,813 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) rc: 0
2025-10-16 17:16:53,814 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) elapsed time (sec): 1.7539458274841309
2025-10-16 17:16:53,814 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) idrss: 0
2025-10-16 17:16:53,814 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) inblock: 0
2025-10-16 17:16:53,814 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) isrss: 0
2025-10-16 17:16:53,815 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) ixrss: 0
2025-10-16 17:16:53,815 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) majflt: 0
2025-10-16 17:16:53,815 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) maxrss: 590940
2025-10-16 17:16:53,815 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) minflt: 51223
2025-10-16 17:16:53,816 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) msgrcv: 0
2025-10-16 17:16:53,816 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) msgsnd: 0
2025-10-16 17:16:53,816 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) nivcsw: 294
2025-10-16 17:16:53,822 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) nsignals: 0
2025-10-16 17:16:53,823 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) nswap: 0
2025-10-16 17:16:53,823 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) nvcsw: 548
2025-10-16 17:16:53,823 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) oublock: 8
2025-10-16 17:16:53,824 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) stime: 0.284706
2025-10-16 17:16:53,824 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) utime: 0.586159
2025-10-16 17:16:53,824 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) wtime: 1.759
2025-10-16 17:16:53,824 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-16 17:16:53,825 - DEBUG - ya.test - _save_outputs: Command (pid 1005913) errors:

2025-10-16 17:16:53,826 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/b9t0/001704/ydb/apps/ydbd/ydbd
2025-10-16 17:16:53,827 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/b9t0/001704/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:13587', '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-10-16 17:16:53,828 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-10-16 17:16:53,834 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-10-16 17:16:53,834 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/b9t0/001704/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:13587', '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-10-16 17:16:53,841 - DEBUG - ya.test - execute: Command pid: 1006037
2025-10-16 17:16:55,453 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) rc: 0
2025-10-16 17:16:55,453 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) elapsed time (sec): 1.6082839965820312
2025-10-16 17:16:55,454 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) idrss: 0
2025-10-16 17:16:55,454 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) inblock: 0
2025-10-16 17:16:55,454 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) isrss: 0
2025-10-16 17:16:55,455 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) ixrss: 0
2025-10-16 17:16:55,455 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) majflt: 0
2025-10-16 17:16:55,455 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) maxrss: 591484
2025-10-16 17:16:55,455 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) minflt: 51416
2025-10-16 17:16:55,456 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) msgrcv: 0
2025-10-16 17:16:55,456 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) msgsnd: 0
2025-10-16 17:16:55,484 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) nivcsw: 269
2025-10-16 17:16:55,484 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) nsignals: 0
2025-10-16 17:16:55,485 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) nswap: 0
2025-10-16 17:16:55,485 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) nvcsw: 422
2025-10-16 17:16:55,485 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) oublock: 8
2025-10-16 17:16:55,486 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) stime: 0.21909299999999998
2025-10-16 17:16:55,486 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) utime: 0.6311749999999999
2025-10-16 17:16:55,486 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) wtime: 1.618
2025-10-16 17:16:55,486 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-10-16 17:16:55,487 - DEBUG - ya.test - _save_outputs: Command (pid 1006037) errors:

2025-10-16 17:16:55,487 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-16 17:16:55,657 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-16 17:16:55,719 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 17:16:55,741 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:16:55,742 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 17:16:55,838 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-16 17:16:55,839 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 89034b10-b851-4370-ab81-35d530e158bc, localhost:13587): creating call state
2025-10-16 17:16:55,840 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 89034b10-b851-4370-ab81-35d530e158bc, localhost:13587): request = { database: "/Root" }
2025-10-16 17:16:55,854 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 89034b10-b851-4370-ab81-35d530e158bc, localhost:13587): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 13587 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-10-16 17:16:55,855 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:13587, location 1, ssl: False>]>
2025-10-16 17:16:55,856 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 17:16:55,892 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:13587
2025-10-16 17:16:55,896 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-10-16 17:16:55,897 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 34ba3c01-9258-4023-88ea-5ab172119e25, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:16:55,898 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 34ba3c01-9258-4023-88ea-5ab172119e25, ghrun-2kszfvc6cm.auto.internal:13587): request = {  }
2025-10-16 17:16:55,907 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 34ba3c01-9258-4023-88ea-5ab172119e25, ghrun-2kszfvc6cm.auto.internal:13587): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" node_id: 1 }
2025-10-16 17:16:55,908 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, d6cda3f2-421a-4973-91f5-69dd1e12e437, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:16:55,910 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, d6cda3f2-421a-4973-91f5-69dd1e12e437, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" }
2025-10-16 17:16:55,919 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, d6cda3f2-421a-4973-91f5-69dd1e12e437, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:16:55,943 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:16:55,944 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5bc48785-8155-4a26-9a5c-e102ce254841, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:16:55,945 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5bc48785-8155-4a26-9a5c-e102ce254841, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:16:55,946 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5bc48785-8155-4a26-9a5c-e102ce254841, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:01,677 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:01,678 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-10-16 17:17:01,678 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:01,679 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b23a9bb1-3342-47e4-b38e-0fcff4e7842c, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:01,680 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b23a9bb1-3342-47e4-b38e-0fcff4e7842c, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:01,689 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b23a9bb1-3342-47e4-b38e-0fcff4e7842c, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:03,182 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:03,183 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:03,184 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4705321d-1b9e-46a9-aa6d-6236c66446d2, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:03,185 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4705321d-1b9e-46a9-aa6d-6236c66446d2, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:03,187 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4705321d-1b9e-46a9-aa6d-6236c66446d2, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:04,945 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:04,946 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:04,947 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bbdf5a7d-1efa-4024-aa29-709ca541c972, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:04,947 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bbdf5a7d-1efa-4024-aa29-709ca541c972, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:04,953 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bbdf5a7d-1efa-4024-aa29-709ca541c972, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:06,731 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:06,732 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:06,733 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fcbe7da5-25d4-4654-b309-105b7bf4f536, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:06,734 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fcbe7da5-25d4-4654-b309-105b7bf4f536, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:06,736 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fcbe7da5-25d4-4654-b309-105b7bf4f536, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:09,580 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:09,580 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 1618432, 'BlobCount': 128}}
2025-10-16 17:17:09,581 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:09,582 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 98813078-2c36-410f-a5ed-7bfc83d67883, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:09,583 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 98813078-2c36-410f-a5ed-7bfc83d67883, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:09,585 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 98813078-2c36-410f-a5ed-7bfc83d67883, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:11,141 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:11,142 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:11,143 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 945f6981-5519-4e3f-827b-49ebf894bc72, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:11,147 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 945f6981-5519-4e3f-827b-49ebf894bc72, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:11,149 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 945f6981-5519-4e3f-827b-49ebf894bc72, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:11,477 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:11,478 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:11,479 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 24de1a78-3a79-49f2-8e03-a4b85ab08b6a, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:11,480 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 24de1a78-3a79-49f2-8e03-a4b85ab08b6a, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:11,485 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 24de1a78-3a79-49f2-8e03-a4b85ab08b6a, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:11,947 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:11,948 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 3236864, 'BlobCount': 256}}
2025-10-16 17:17:11,948 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:11,949 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c806857b-76dd-464d-9926-c24e589f74b6, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:11,950 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c806857b-76dd-464d-9926-c24e589f74b6, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:11,951 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c806857b-76dd-464d-9926-c24e589f74b6, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:14,089 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:14,090 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:14,091 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, da4abb5b-a139-402d-8eb0-1fbee7874b83, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:14,092 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, da4abb5b-a139-402d-8eb0-1fbee7874b83, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:14,103 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, da4abb5b-a139-402d-8eb0-1fbee7874b83, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:14,611 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:14,612 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:14,613 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fad2d0e5-012d-4257-ab95-5536f7d18ad1, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:14,614 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fad2d0e5-012d-4257-ab95-5536f7d18ad1, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:14,622 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fad2d0e5-012d-4257-ab95-5536f7d18ad1, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:15,396 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:15,397 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 312332, 'Portions': 191}}, blobs: {'__DEFAULT': {'Portions': 182, 'BlobSize': 4858592, 'BlobCount': 384}}
2025-10-16 17:17:15,398 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:15,398 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 81056d49-42b4-483d-93a3-864fbdc592a5, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:15,399 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 81056d49-42b4-483d-93a3-864fbdc592a5, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:15,409 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 81056d49-42b4-483d-93a3-864fbdc592a5, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:17,224 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:17,225 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:17,225 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ff333fc3-cfb1-424e-8ac3-a690b77eb375, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:17,226 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ff333fc3-cfb1-424e-8ac3-a690b77eb375, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:17,228 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ff333fc3-cfb1-424e-8ac3-a690b77eb375, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:17,750 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:17,751 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:17,751 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 98c00fa2-cbd9-4ada-8632-4beeca73c7a0, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:17,753 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 98c00fa2-cbd9-4ada-8632-4beeca73c7a0, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:17,754 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 98c00fa2-cbd9-4ada-8632-4beeca73c7a0, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:18,401 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:18,402 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 404636, 'Portions': 243}}, blobs: {'__DEFAULT': {'Portions': 240, 'BlobSize': 6477496, 'BlobCount': 506}}
2025-10-16 17:17:18,403 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:18,403 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 85f34b87-3be6-42eb-920a-c84a9973163a, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:18,408 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 85f34b87-3be6-42eb-920a-c84a9973163a, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:18,410 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 85f34b87-3be6-42eb-920a-c84a9973163a, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:19,622 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:19,623 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:19,624 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4be2b296-e366-410f-bfe7-cb9993efe4e6, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:19,625 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4be2b296-e366-410f-bfe7-cb9993efe4e6, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:19,637 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4be2b296-e366-410f-bfe7-cb9993efe4e6, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:20,027 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:20,027 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:20,028 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c524f657-0a1c-4a6d-8d51-2897987ad612, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:20,030 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c524f657-0a1c-4a6d-8d51-2897987ad612, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:20,032 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c524f657-0a1c-4a6d-8d51-2897987ad612, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:20,865 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:20,866 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 512651, 'Portions': 303}}, blobs: {'__DEFAULT': {'Portions': 295, 'BlobSize': 8095896, 'BlobCount': 622}}
2025-10-16 17:17:20,867 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:20,867 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5712f9b3-8e9b-40c1-b9f2-c81f8f2fd33b, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:20,873 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5712f9b3-8e9b-40c1-b9f2-c81f8f2fd33b, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:20,875 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5712f9b3-8e9b-40c1-b9f2-c81f8f2fd33b, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:22,342 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:22,342 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:22,343 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3873755f-c093-4fe7-b38a-f6fa95656282, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:22,344 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3873755f-c093-4fe7-b38a-f6fa95656282, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:22,350 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3873755f-c093-4fe7-b38a-f6fa95656282, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:22,713 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:22,714 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:22,714 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 49eda634-d3e0-46ff-92c6-f5a60ddb0b81, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:22,715 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 49eda634-d3e0-46ff-92c6-f5a60ddb0b81, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:22,721 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 49eda634-d3e0-46ff-92c6-f5a60ddb0b81, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:23,449 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:23,450 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 355}}, blobs: {'__DEFAULT': {'Portions': 355, 'BlobSize': 9714136, 'BlobCount': 744}}
2025-10-16 17:17:23,450 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:23,451 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 941edb27-92f9-43bb-a3d0-23773e36dc65, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:23,452 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 941edb27-92f9-43bb-a3d0-23773e36dc65, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:23,454 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 941edb27-92f9-43bb-a3d0-23773e36dc65, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:24,927 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:24,928 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:24,929 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 22adb406-d2d1-4db5-88b0-b2fcc4e506f8, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:24,930 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 22adb406-d2d1-4db5-88b0-b2fcc4e506f8, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:24,932 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 22adb406-d2d1-4db5-88b0-b2fcc4e506f8, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:25,197 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:25,198 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:25,199 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1c519d61-fa88-4ba5-ac17-a39481ecec15, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:25,200 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1c519d61-fa88-4ba5-ac17-a39481ecec15, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:25,201 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1c519d61-fa88-4ba5-ac17-a39481ecec15, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:25,979 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:25,980 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 399}}, blobs: {'__DEFAULT': {'Portions': 399, 'BlobSize': 11331232, 'BlobCount': 840}}
2025-10-16 17:17:25,980 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:25,981 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1879eb9e-dcd7-4d24-ba0b-c355dd9893bc, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:25,982 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1879eb9e-dcd7-4d24-ba0b-c355dd9893bc, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:25,985 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1879eb9e-dcd7-4d24-ba0b-c355dd9893bc, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:27,421 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:27,422 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:27,422 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b558c059-1202-437d-abb2-4107c9b8f5a0, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:27,423 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b558c059-1202-437d-abb2-4107c9b8f5a0, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:27,428 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b558c059-1202-437d-abb2-4107c9b8f5a0, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:28,032 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:28,033 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:28,034 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 855f537a-9987-4990-96fd-ce0d92da3c0b, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:28,035 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 855f537a-9987-4990-96fd-ce0d92da3c0b, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:28,037 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 855f537a-9987-4990-96fd-ce0d92da3c0b, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:28,882 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:28,883 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 810913, 'Portions': 452}}, blobs: {'__DEFAULT': {'Portions': 427, 'BlobSize': 12946592, 'BlobCount': 908}}
2025-10-16 17:17:28,883 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:28,884 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 48f6938d-6f26-468b-b963-9b2294d2e376, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:28,885 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 48f6938d-6f26-468b-b963-9b2294d2e376, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:28,886 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 48f6938d-6f26-468b-b963-9b2294d2e376, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:30,222 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:30,223 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:30,224 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 82533d40-61fd-42b9-846b-983e856f71d7, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:30,225 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 82533d40-61fd-42b9-846b-983e856f71d7, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:30,229 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 82533d40-61fd-42b9-846b-983e856f71d7, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:30,721 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:30,725 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:30,730 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1b74ab5e-af41-4721-bb71-b3e7a3536c11, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:30,732 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1b74ab5e-af41-4721-bb71-b3e7a3536c11, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:30,734 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1b74ab5e-af41-4721-bb71-b3e7a3536c11, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:31,382 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:31,382 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 925051, 'Portions': 479}}, blobs: {'__DEFAULT': {'Portions': 456, 'BlobSize': 14561600, 'BlobCount': 976}}
2025-10-16 17:17:31,383 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:31,384 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 08d4fd62-55ca-4f29-8ce5-705464f7c823, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:31,385 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 08d4fd62-55ca-4f29-8ce5-705464f7c823, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:31,387 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 08d4fd62-55ca-4f29-8ce5-705464f7c823, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:32,637 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:32,638 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:32,639 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cb00d1b7-31c5-4055-ac05-4c65a4949dc1, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:32,640 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cb00d1b7-31c5-4055-ac05-4c65a4949dc1, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:32,645 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cb00d1b7-31c5-4055-ac05-4c65a4949dc1, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:33,118 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:33,119 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:33,120 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a3cf9028-86eb-4b2d-9f33-db2ed621916a, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:33,121 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a3cf9028-86eb-4b2d-9f33-db2ed621916a, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:33,127 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a3cf9028-86eb-4b2d-9f33-db2ed621916a, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:33,898 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:33,899 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 496}}, blobs: {'__DEFAULT': {'Portions': 496, 'BlobSize': 16177448, 'BlobCount': 1062}}
2025-10-16 17:17:33,899 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:33,900 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2b8e7778-c836-49cd-801c-20771b232afe, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:33,900 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2b8e7778-c836-49cd-801c-20771b232afe, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:33,901 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2b8e7778-c836-49cd-801c-20771b232afe, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:35,143 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:35,144 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:35,145 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4a0edc3e-711b-4985-a8b7-61569470dd0c, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:35,146 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4a0edc3e-711b-4985-a8b7-61569470dd0c, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:35,147 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4a0edc3e-711b-4985-a8b7-61569470dd0c, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:35,480 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:35,481 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:35,482 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cc67e3fe-705a-4a5a-9a1b-288522e50ec1, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:35,483 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cc67e3fe-705a-4a5a-9a1b-288522e50ec1, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:35,492 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cc67e3fe-705a-4a5a-9a1b-288522e50ec1, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:35,910 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:35,911 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:35,912 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3244d41a-d58a-4ed5-a379-18caee19ab29, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:35,913 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3244d41a-d58a-4ed5-a379-18caee19ab29, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:35,914 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3244d41a-d58a-4ed5-a379-18caee19ab29, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:40,777 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:50,811 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:50,812 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a1b698ec-157c-4847-81af-5530f8f037dc, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:50,813 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a1b698ec-157c-4847-81af-5530f8f037dc, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:50,814 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a1b698ec-157c-4847-81af-5530f8f037dc, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:54,344 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:54,374 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8071424)
2025-10-16 17:17:54,374 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:17:54,375 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 342ce69a-cd0c-40d5-b3d2-9ff464a09901, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:17:54,376 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 342ce69a-cd0c-40d5-b3d2-9ff464a09901, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:17:54,378 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 342ce69a-cd0c-40d5-b3d2-9ff464a09901, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:17:58,611 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:08,661 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:08,662 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4ac0a55b-d573-4647-a02d-8a1282b0201c, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:08,663 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4ac0a55b-d573-4647-a02d-8a1282b0201c, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:18:08,665 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4ac0a55b-d573-4647-a02d-8a1282b0201c, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:10,988 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:11,004 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8033840)
2025-10-16 17:18:11,005 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:11,006 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, adb3f037-d28e-4fa1-83a0-a059480f07a4, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:11,007 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, adb3f037-d28e-4fa1-83a0-a059480f07a4, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" 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-10-16 17:18:11,008 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, adb3f037-d28e-4fa1-83a0-a059480f07a4, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:11,375 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:11,378 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_20_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 20])
2025-10-16 17:18:11,379 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:11,380 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4da56302-d4d8-4409-a3cc-95bf3842ec97, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:11,381 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4da56302-d4d8-4409-a3cc-95bf3842ec97, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_20_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 20)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:11,389 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4da56302-d4d8-4409-a3cc-95bf3842ec97, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:16,065 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:16,065 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_20_compression created
2025-10-16 17:18:16,066 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:16,067 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 34cc26fc-68a1-44a6-8de6-a9843c8e982f, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:16,068 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 34cc26fc-68a1-44a6-8de6-a9843c8e982f, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_20_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:16,070 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 34cc26fc-68a1-44a6-8de6-a9843c8e982f, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:16,923 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:16,924 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:16,925 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 865b7f96-ebc0-4280-81bd-5e69547014d1, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:16,926 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 865b7f96-ebc0-4280-81bd-5e69547014d1, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:16,928 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 865b7f96-ebc0-4280-81bd-5e69547014d1, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:22,533 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:22,534 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:22,534 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 41bf6473-091b-40fe-a93c-5b93272339cf, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:22,536 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 41bf6473-091b-40fe-a93c-5b93272339cf, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:22,537 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 41bf6473-091b-40fe-a93c-5b93272339cf, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:23,717 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:23,719 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:23,719 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2d1411ef-2972-47fe-b71a-a1765f259749, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:23,723 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2d1411ef-2972-47fe-b71a-a1765f259749, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:23,725 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2d1411ef-2972-47fe-b71a-a1765f259749, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:26,007 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:26,008 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-10-16 17:18:26,009 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:26,010 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 50a90542-cd37-4fb2-9747-49f402303eb9, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:26,011 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 50a90542-cd37-4fb2-9747-49f402303eb9, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:26,017 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 50a90542-cd37-4fb2-9747-49f402303eb9, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:27,654 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:18:27,655 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 17:18:27,672 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-16 17:18:27,674 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 557ffb06-5c1d-4928-bc54-d780ed92778a, localhost:13587): creating call state
2025-10-16 17:18:27,674 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 557ffb06-5c1d-4928-bc54-d780ed92778a, localhost:13587): request = { database: "/Root" }
2025-10-16 17:18:27,686 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 557ffb06-5c1d-4928-bc54-d780ed92778a, localhost:13587): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 13587 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-10-16 17:18:27,688 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:13587, location 1, ssl: False>]>
2025-10-16 17:18:27,688 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:13587
2025-10-16 17:18:31,238 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:31,239 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:31,240 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 14f5f92c-7ed8-48a3-ae89-b7f80b14fdfe, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:31,241 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 14f5f92c-7ed8-48a3-ae89-b7f80b14fdfe, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:31,243 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 14f5f92c-7ed8-48a3-ae89-b7f80b14fdfe, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:31,681 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:31,682 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:31,682 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a6e23b42-b4be-43a6-9727-89027fd2a7e9, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:31,690 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a6e23b42-b4be-43a6-9727-89027fd2a7e9, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:31,691 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a6e23b42-b4be-43a6-9727-89027fd2a7e9, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:32,269 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:32,270 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-10-16 17:18:32,271 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:32,272 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 057bba57-6a71-40ee-b7f5-fefaf4e1b17b, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:32,275 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 057bba57-6a71-40ee-b7f5-fefaf4e1b17b, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:32,277 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 057bba57-6a71-40ee-b7f5-fefaf4e1b17b, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:37,610 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:37,611 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:37,612 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 37b69074-0668-4dcc-acf0-d6e38073820a, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:37,613 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 37b69074-0668-4dcc-acf0-d6e38073820a, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:37,616 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 37b69074-0668-4dcc-acf0-d6e38073820a, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:37,978 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:37,978 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:37,979 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5e0c671c-4e9a-485f-946a-9a836009cc02, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:37,980 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5e0c671c-4e9a-485f-946a-9a836009cc02, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:37,985 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5e0c671c-4e9a-485f-946a-9a836009cc02, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:38,483 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:38,484 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 171}}, blobs: {'__DEFAULT': {'Portions': 171, 'BlobSize': 961792, 'BlobCount': 384}}
2025-10-16 17:18:38,485 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:38,486 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 36f6b8a2-8446-4647-a328-f8196dd3fdd4, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:38,487 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 36f6b8a2-8446-4647-a328-f8196dd3fdd4, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:38,488 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 36f6b8a2-8446-4647-a328-f8196dd3fdd4, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:43,963 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:43,964 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:43,965 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a7327326-8f36-48f6-ae63-667dad69a0df, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:43,966 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a7327326-8f36-48f6-ae63-667dad69a0df, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:43,967 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a7327326-8f36-48f6-ae63-667dad69a0df, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:44,440 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:44,441 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:44,442 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 21e8d806-5961-44aa-a8f7-02a7428b4622, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:44,443 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 21e8d806-5961-44aa-a8f7-02a7428b4622, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:44,445 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 21e8d806-5961-44aa-a8f7-02a7428b4622, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:45,136 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:45,137 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 225}}, blobs: {'__DEFAULT': {'Portions': 225, 'BlobSize': 1280392, 'BlobCount': 502}}
2025-10-16 17:18:45,137 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:45,138 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9ed7171b-84c8-4b6b-8b3e-f8e5e060b1f7, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:45,138 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9ed7171b-84c8-4b6b-8b3e-f8e5e060b1f7, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:45,139 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9ed7171b-84c8-4b6b-8b3e-f8e5e060b1f7, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:51,426 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:51,427 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:51,428 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7bccf5da-d539-42b8-b1db-ae3a5d7b37e4, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:51,428 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7bccf5da-d539-42b8-b1db-ae3a5d7b37e4, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:51,431 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7bccf5da-d539-42b8-b1db-ae3a5d7b37e4, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:51,892 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:51,893 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:51,894 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c2954460-5821-4af4-bb91-c891fcead841, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:51,895 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c2954460-5821-4af4-bb91-c891fcead841, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:51,897 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c2954460-5821-4af4-bb91-c891fcead841, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:52,510 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:52,511 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 268}}, blobs: {'__DEFAULT': {'Portions': 268, 'BlobSize': 1595752, 'BlobCount': 602}}
2025-10-16 17:18:52,511 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:52,512 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3e6ec4b5-1cb4-4a26-ae72-92d7ecfba0d9, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:52,513 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3e6ec4b5-1cb4-4a26-ae72-92d7ecfba0d9, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:52,521 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3e6ec4b5-1cb4-4a26-ae72-92d7ecfba0d9, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:58,675 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:58,676 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:58,677 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9c63c25a-0439-47fb-9da4-8b424773621f, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:58,677 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9c63c25a-0439-47fb-9da4-8b424773621f, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:58,679 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9c63c25a-0439-47fb-9da4-8b424773621f, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:59,024 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:59,025 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:59,026 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ed9f194b-acf2-4cc0-847c-4af983eeb9cf, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:59,027 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ed9f194b-acf2-4cc0-847c-4af983eeb9cf, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:59,029 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ed9f194b-acf2-4cc0-847c-4af983eeb9cf, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:18:59,548 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:59,549 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 607741, 'Portions': 309}}, blobs: {'__DEFAULT': {'Portions': 304, 'BlobSize': 1909152, 'BlobCount': 688}}
2025-10-16 17:18:59,549 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:18:59,550 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9b205815-b201-4879-bad9-fcaa910535b4, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:18:59,550 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9b205815-b201-4879-bad9-fcaa910535b4, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:18:59,551 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9b205815-b201-4879-bad9-fcaa910535b4, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:06,186 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:06,186 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:06,187 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 479c0122-3398-4b7c-9a03-fd1411e9698c, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:06,188 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 479c0122-3398-4b7c-9a03-fd1411e9698c, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:06,190 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 479c0122-3398-4b7c-9a03-fd1411e9698c, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:07,025 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:07,026 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:07,027 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e39ad31f-1385-4e92-a9f5-8247434a709e, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:07,028 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e39ad31f-1385-4e92-a9f5-8247434a709e, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:07,030 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e39ad31f-1385-4e92-a9f5-8247434a709e, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:08,144 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:08,145 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 368}}, blobs: {'__DEFAULT': {'Portions': 368, 'BlobSize': 2228192, 'BlobCount': 816}}
2025-10-16 17:19:08,146 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:08,148 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b314cebd-d57d-4d06-a0f6-fcf685bebcbd, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:08,149 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b314cebd-d57d-4d06-a0f6-fcf685bebcbd, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:08,155 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b314cebd-d57d-4d06-a0f6-fcf685bebcbd, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:15,820 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:15,820 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:15,821 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bbb25153-c2bf-41ec-8d69-65708df0c6be, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:15,822 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bbb25153-c2bf-41ec-8d69-65708df0c6be, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:15,824 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bbb25153-c2bf-41ec-8d69-65708df0c6be, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:16,365 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:16,366 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:16,367 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd75a333-dd91-4dea-9a9b-e428a967549b, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:16,368 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd75a333-dd91-4dea-9a9b-e428a967549b, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:16,386 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd75a333-dd91-4dea-9a9b-e428a967549b, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:17,428 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:17,428 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 810900, 'Portions': 369}}, blobs: {'__DEFAULT': {'Portions': 364, 'BlobSize': 2596824, 'BlobCount': 836}}
2025-10-16 17:19:17,429 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:17,430 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 701ecc9e-be66-4ee3-a95d-f2040023ff56, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:17,431 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 701ecc9e-be66-4ee3-a95d-f2040023ff56, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:17,434 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 701ecc9e-be66-4ee3-a95d-f2040023ff56, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:23,150 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:23,151 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:23,151 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e08321d6-b05a-4968-9440-67dfbaf9afdf, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:23,152 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e08321d6-b05a-4968-9440-67dfbaf9afdf, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:23,154 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e08321d6-b05a-4968-9440-67dfbaf9afdf, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:23,942 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:23,943 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:23,944 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 90dc99d4-a659-4104-82b1-cf2b89caf36d, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:23,945 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 90dc99d4-a659-4104-82b1-cf2b89caf36d, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:23,947 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 90dc99d4-a659-4104-82b1-cf2b89caf36d, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:24,732 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:24,733 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 360}}, blobs: {'__DEFAULT': {'Portions': 360, 'BlobSize': 2833048, 'BlobCount': 846}}
2025-10-16 17:19:24,733 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:24,734 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3ad9f1a0-3b73-4ea5-8e1d-5838fe2ad22b, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:24,735 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3ad9f1a0-3b73-4ea5-8e1d-5838fe2ad22b, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_20_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:24,737 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3ad9f1a0-3b73-4ea5-8e1d-5838fe2ad22b, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:31,601 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:31,602 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:31,602 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3ad00373-46e3-4cdb-b0f7-827c424e1123, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:31,607 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3ad00373-46e3-4cdb-b0f7-827c424e1123, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:31,609 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3ad00373-46e3-4cdb-b0f7-827c424e1123, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:32,226 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:32,227 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:32,228 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 07e6a34d-de03-4a4f-9a35-e1fdc03d2425, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:32,229 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 07e6a34d-de03-4a4f-9a35-e1fdc03d2425, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:32,235 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 07e6a34d-de03-4a4f-9a35-e1fdc03d2425, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:33,029 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:33,030 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_20_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 416}}, blobs: {'__DEFAULT': {'Portions': 416, 'BlobSize': 3150432, 'BlobCount': 960}}
2025-10-16 17:19:33,030 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:33,031 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d6fae099-43a9-4e0b-97df-eb0f18ee8532, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:33,032 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d6fae099-43a9-4e0b-97df-eb0f18ee8532, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_20_compression`" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:33,034 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d6fae099-43a9-4e0b-97df-eb0f18ee8532, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:34,869 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:34,870 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:34,871 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ae583a26-bb0a-4f0f-9538-6b6e70a78506, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:34,872 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ae583a26-bb0a-4f0f-9538-6b6e70a78506, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:34,874 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ae583a26-bb0a-4f0f-9538-6b6e70a78506, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:35,488 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:35,489 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:35,490 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fa6fa698-c826-4008-869c-51cc6741b591, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:35,491 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fa6fa698-c826-4008-869c-51cc6741b591, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:35,501 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fa6fa698-c826-4008-869c-51cc6741b591, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:36,114 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:36,115 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:36,119 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d9e2f8b0-65bd-4465-85a5-bf53fa0a4393, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:36,121 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d9e2f8b0-65bd-4465-85a5-bf53fa0a4393, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:36,123 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d9e2f8b0-65bd-4465-85a5-bf53fa0a4393, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:41,241 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:51,316 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:51,317 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e2590047-6840-405b-b03d-21d37366adc0, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:51,318 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e2590047-6840-405b-b03d-21d37366adc0, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:51,319 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e2590047-6840-405b-b03d-21d37366adc0, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:51,845 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-16 17:19:51,846 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-10-16 17:19:51,873 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-16 17:19:51,874 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 3aca9549-b084-4cfd-a04f-928e7b28840d, localhost:13587): creating call state
2025-10-16 17:19:51,875 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 3aca9549-b084-4cfd-a04f-928e7b28840d, localhost:13587): request = { database: "/Root" }
2025-10-16 17:19:52,005 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 3aca9549-b084-4cfd-a04f-928e7b28840d, localhost:13587): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-2kszfvc6cm.auto.internal" port: 13587 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-10-16 17:19:52,006 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-2kszfvc6cm.auto.internal:13587, location 1, ssl: False>]>
2025-10-16 17:19:52,007 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:13587
2025-10-16 17:19:55,290 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:55,323 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_20_compression`, volumes `value` (8000000, 1546016)
2025-10-16 17:19:55,324 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:55,325 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 61f390bd-96be-4e48-9135-24cb79f7d215, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:55,326 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 61f390bd-96be-4e48-9135-24cb79f7d215, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:55,330 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 61f390bd-96be-4e48-9135-24cb79f7d215, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:56,046 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:56,047 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:19:56,048 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5b839864-3edf-41f9-b2d8-32fd7e7ea23e, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:19:56,049 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5b839864-3edf-41f9-b2d8-32fd7e7ea23e, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:19:56,052 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5b839864-3edf-41f9-b2d8-32fd7e7ea23e, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:19:59,912 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:20:09,945 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:20:09,946 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 22864321-7848-4822-a9be-4f2dbcebd2ec, ghrun-2kszfvc6cm.auto.internal:13587): creating call state
2025-10-16 17:20:09,947 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 22864321-7848-4822-a9be-4f2dbcebd2ec, ghrun-2kszfvc6cm.auto.internal:13587): request = { session_id: "ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_20_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-10-16 17:20:09,949 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 22864321-7848-4822-a9be-4f2dbcebd2ec, ghrun-2kszfvc6cm.auto.internal:13587): response = { <_MultiThreadedRendezvous object> }
2025-10-16 17:20:13,434 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OWZmMzg5YWUtZWIyMzkxNDgtNTk0YzI4M2UtNmNkNTJiMzI=
2025-10-16 17:20:13,499 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_20_compression`, volumes `value` (8000000, 1546016)
2025-10-16 17:20:13,500 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_20_compression` 8071424 / 1546016: 5.2207894355556475
2025-10-16 17:20:13,503 - INFO - ya.test - pytest_runtest_teardown: Test teardown
