2025-11-05 12:21:52,021 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-05 12:21:52,021 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_18_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 18]
2025-11-05 12:21:52,021 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-05 12:21:52,022 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-05 12:21:52,024 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-05 12:21:52,024 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-05 12:21:52,025 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/c688/002808/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-05 12:21:52,026 - DEBUG - ya.test - execute: Command pid: 2463813
2025-11-05 12:21:52,775 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) rc: 0
2025-11-05 12:21:52,775 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) elapsed time (sec): 0.7471821308135986
2025-11-05 12:21:52,776 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) idrss: 0
2025-11-05 12:21:52,776 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) inblock: 0
2025-11-05 12:21:52,776 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) isrss: 0
2025-11-05 12:21:52,777 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) ixrss: 0
2025-11-05 12:21:52,777 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) majflt: 0
2025-11-05 12:21:52,777 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) maxrss: 494716
2025-11-05 12:21:52,777 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) minflt: 45356
2025-11-05 12:21:52,778 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) msgrcv: 0
2025-11-05 12:21:52,778 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) msgsnd: 0
2025-11-05 12:21:52,778 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) nivcsw: 56
2025-11-05 12:21:52,778 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) nsignals: 0
2025-11-05 12:21:52,779 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) nswap: 0
2025-11-05 12:21:52,779 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) nvcsw: 14
2025-11-05 12:21:52,779 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) oublock: 8
2025-11-05 12:21:52,780 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) stime: 0.216795
2025-11-05 12:21:52,780 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) utime: 0.450142
2025-11-05 12:21:52,780 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) wtime: 0.749
2025-11-05 12:21:52,781 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) output:
Git info:
    Commit: bb8d7ee9685d84658f39bc9c7238597c599c6433
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge c59276c0e0e506b21793b800196e5a8048164207 into f38187482f0084fe7bc5ab264dbd7817a983fef9

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-anwhr6cmha
    Host information: 
        Linux ghrun-anwhr6cmha 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-11-05 12:21:52,781 - DEBUG - ya.test - _save_outputs: Command (pid 2463813) errors:

2025-11-05 12:21:52,781 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: bb8d7ee9685d84658f39bc9c7238597c599c6433
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge c59276c0e0e506b21793b800196e5a8048164207 into f38187482f0084fe7bc5ab264dbd7817a983fef9

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-anwhr6cmha
    Host information: 
        Linux ghrun-anwhr6cmha 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-11-05 12:21:52,889 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/c688/002808/ydb/apps/ydbd/ydbd
2025-11-05 12:21:52,891 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/15769
2025-11-05 12:21:52,892 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/c688/002808/r3tmp/kikimr_cluster_bw29eape
2025-11-05 12:21:52,931 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/c688/002808/ydb/apps/ydbd/ydbd
2025-11-05 12:21:52,932 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/5587
2025-11-05 12:21:52,932 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21023
2025-11-05 12:21:52,933 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18484
2025-11-05 12:21:52,933 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10746
2025-11-05 12:21:52,934 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/c688/002808/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs"
2025-11-05 12:21:52,935 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/c688/002808/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/c688/002808/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_np0341r1.log --grpc-port=5587 --mon-port=21023 --ic-port=15769
2025-11-05 12:21:52,935 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:5587/1, disk_size 68719476736
2025-11-05 12:21:52,936 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/c688/002808/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs"
2025-11-05 12:21:52,936 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/c688/002808/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/c688/002808/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_np0341r1.log --grpc-port=5587 --mon-port=21023 --ic-port=15769
2025-11-05 12:21:52,937 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/c688/002808/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/c688/002808/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/c688/002808/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_np0341r1.log', '--grpc-port=5587', '--mon-port=21023', '--ic-port=15769')' in '/home/runner/.ya/build/build_root/c688/002808/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1' (no wait)
2025-11-05 12:21:52,940 - DEBUG - ya.test - execute: Command pid: 2464714
2025-11-05 12:21:52,941 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:5587/1
2025-11-05 12:21:52,943 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-05 12:21:52,944 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-05 12:21:52,944 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/c688/002808/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-05 12:21:52,945 - DEBUG - ya.test - execute: Command pid: 2464739
2025-11-05 12:21:53,673 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) rc: 0
2025-11-05 12:21:53,673 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) elapsed time (sec): 0.7269878387451172
2025-11-05 12:21:53,674 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) idrss: 0
2025-11-05 12:21:53,674 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) inblock: 0
2025-11-05 12:21:53,674 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) isrss: 0
2025-11-05 12:21:53,675 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) ixrss: 0
2025-11-05 12:21:53,675 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) majflt: 0
2025-11-05 12:21:53,675 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) maxrss: 505016
2025-11-05 12:21:53,676 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) minflt: 45375
2025-11-05 12:21:53,676 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) msgrcv: 0
2025-11-05 12:21:53,676 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) msgsnd: 0
2025-11-05 12:21:53,676 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) nivcsw: 11
2025-11-05 12:21:53,677 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) nsignals: 0
2025-11-05 12:21:53,677 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) nswap: 0
2025-11-05 12:21:53,677 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) nvcsw: 14
2025-11-05 12:21:53,677 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) oublock: 8
2025-11-05 12:21:53,678 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) stime: 0.197894
2025-11-05 12:21:53,678 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) utime: 0.472944
2025-11-05 12:21:53,678 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) wtime: 0.728
2025-11-05 12:21:53,679 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) output:
Git info:
    Commit: bb8d7ee9685d84658f39bc9c7238597c599c6433
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge c59276c0e0e506b21793b800196e5a8048164207 into f38187482f0084fe7bc5ab264dbd7817a983fef9

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-anwhr6cmha
    Host information: 
        Linux ghrun-anwhr6cmha 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-11-05 12:21:53,679 - DEBUG - ya.test - _save_outputs: Command (pid 2464739) errors:

2025-11-05 12:21:53,679 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: bb8d7ee9685d84658f39bc9c7238597c599c6433
    Branch: 
    Author: Pisarenko Grigoriy <grigoriypisar@ydb.tech>
    Summary: Merge c59276c0e0e506b21793b800196e5a8048164207 into f38187482f0084fe7bc5ab264dbd7817a983fef9
2025-11-05 12:21:56,154 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/c688/002808/ydb/apps/ydbd/ydbd
2025-11-05 12:21:56,155 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/c688/002808/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5587', '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: 15769\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-05 12:21:56,156 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-05 12:21:56,157 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-05 12:21:56,157 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/c688/002808/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5587', '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: 15769\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-05 12:21:56,159 - DEBUG - ya.test - execute: Command pid: 2468015
2025-11-05 12:21:57,008 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) rc: 0
2025-11-05 12:21:57,008 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) elapsed time (sec): 0.848548173904419
2025-11-05 12:21:57,009 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) idrss: 0
2025-11-05 12:21:57,009 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) inblock: 0
2025-11-05 12:21:57,009 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) isrss: 0
2025-11-05 12:21:57,010 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) ixrss: 0
2025-11-05 12:21:57,010 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) majflt: 0
2025-11-05 12:21:57,010 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) maxrss: 578660
2025-11-05 12:21:57,011 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) minflt: 51628
2025-11-05 12:21:57,011 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) msgrcv: 0
2025-11-05 12:21:57,011 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) msgsnd: 0
2025-11-05 12:21:57,011 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) nivcsw: 88
2025-11-05 12:21:57,012 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) nsignals: 0
2025-11-05 12:21:57,012 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) nswap: 0
2025-11-05 12:21:57,012 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) nvcsw: 618
2025-11-05 12:21:57,012 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) oublock: 8
2025-11-05 12:21:57,013 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) stime: 0.268022
2025-11-05 12:21:57,013 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) utime: 0.519891
2025-11-05 12:21:57,013 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) wtime: 0.85
2025-11-05 12:21:57,014 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-05 12:21:57,014 - DEBUG - ya.test - _save_outputs: Command (pid 2468015) errors:

2025-11-05 12:21:57,016 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/c688/002808/ydb/apps/ydbd/ydbd
2025-11-05 12:21:57,016 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/c688/002808/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5587', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-11-05 12:21:57,017 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-05 12:21:57,018 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-05 12:21:57,019 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/c688/002808/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:5587', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-05 12:21:57,020 - DEBUG - ya.test - execute: Command pid: 2468932
2025-11-05 12:21:57,815 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) rc: 0
2025-11-05 12:21:57,815 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) elapsed time (sec): 0.7940139770507812
2025-11-05 12:21:57,816 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) idrss: 0
2025-11-05 12:21:57,816 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) inblock: 0
2025-11-05 12:21:57,816 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) isrss: 0
2025-11-05 12:21:57,816 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) ixrss: 0
2025-11-05 12:21:57,817 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) majflt: 0
2025-11-05 12:21:57,817 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) maxrss: 579204
2025-11-05 12:21:57,817 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) minflt: 51529
2025-11-05 12:21:57,817 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) msgrcv: 0
2025-11-05 12:21:57,817 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) msgsnd: 0
2025-11-05 12:21:57,818 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) nivcsw: 68
2025-11-05 12:21:57,818 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) nsignals: 0
2025-11-05 12:21:57,818 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) nswap: 0
2025-11-05 12:21:57,819 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) nvcsw: 741
2025-11-05 12:21:57,819 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) oublock: 8
2025-11-05 12:21:57,819 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) stime: 0.243591
2025-11-05 12:21:57,819 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) utime: 0.49644099999999997
2025-11-05 12:21:57,820 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) wtime: 0.796
2025-11-05 12:21:57,820 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-05 12:21:57,820 - DEBUG - ya.test - _save_outputs: Command (pid 2468932) errors:

2025-11-05 12:21:57,821 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-05 12:21:57,881 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-05 12:21:57,911 - 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-11-05 12:21:57,914 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-05 12:21:57,930 - 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-11-05 12:21:57,944 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-05 12:21:57,945 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 10d112f3-bf3b-4405-97c9-3477b0f1fc9e, localhost:5587): creating call state
2025-11-05 12:21:57,946 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 10d112f3-bf3b-4405-97c9-3477b0f1fc9e, localhost:5587): request = { database: "/Root" }
2025-11-05 12:21:57,960 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 10d112f3-bf3b-4405-97c9-3477b0f1fc9e, localhost:5587): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-anwhr6cmha.auto.internal" port: 5587 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-05 12:21:57,961 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-anwhr6cmha.auto.internal:5587, location 1, ssl: False>]>
2025-11-05 12:21:57,962 - 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-11-05 12:21:57,975 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:5587
2025-11-05 12:21:57,976 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-05 12:21:57,978 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 532b06b5-b0b0-4921-a4ab-e2ee04ca08b9, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:21:57,978 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 532b06b5-b0b0-4921-a4ab-e2ee04ca08b9, ghrun-anwhr6cmha.auto.internal:5587): request = {  }
2025-11-05 12:21:57,985 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 532b06b5-b0b0-4921-a4ab-e2ee04ca08b9, ghrun-anwhr6cmha.auto.internal:5587): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" node_id: 1 }
2025-11-05 12:21:57,986 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, c6d336ec-642e-4d09-9a0c-6989eb2169b9, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:21:57,987 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, c6d336ec-642e-4d09-9a0c-6989eb2169b9, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" }
2025-11-05 12:21:57,989 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, c6d336ec-642e-4d09-9a0c-6989eb2169b9, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:21:57,992 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:21:57,993 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5b938f14-38c9-435a-8d25-d86f1bf5a8a2, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:21:57,994 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5b938f14-38c9-435a-8d25-d86f1bf5a8a2, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/off_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"off\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:21:57,995 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5b938f14-38c9-435a-8d25-d86f1bf5a8a2, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:00,111 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:00,112 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-05 12:22:00,113 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:00,113 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b8549dd8-1b15-482a-b0bc-15371780eaf0, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:00,114 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b8549dd8-1b15-482a-b0bc-15371780eaf0, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:00,116 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b8549dd8-1b15-482a-b0bc-15371780eaf0, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:00,951 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:00,952 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:00,953 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f87e4335-2688-40f8-88f5-e0ed4b148b3e, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:00,954 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f87e4335-2688-40f8-88f5-e0ed4b148b3e, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:00,955 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f87e4335-2688-40f8-88f5-e0ed4b148b3e, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:01,967 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:01,967 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:01,968 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 456b4908-643a-4937-a0c1-e885bcbc1bf1, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:01,969 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 456b4908-643a-4937-a0c1-e885bcbc1bf1, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:01,971 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 456b4908-643a-4937-a0c1-e885bcbc1bf1, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:02,641 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:02,642 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:02,643 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1c533b91-cdca-4163-a098-0c268e95e943, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:02,644 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1c533b91-cdca-4163-a098-0c268e95e943, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:02,645 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1c533b91-cdca-4163-a098-0c268e95e943, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:03,972 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:03,973 - 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-11-05 12:22:03,974 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:03,974 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 70bc423e-a488-4307-872f-7d21b92d6c40, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:03,975 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 70bc423e-a488-4307-872f-7d21b92d6c40, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:03,977 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 70bc423e-a488-4307-872f-7d21b92d6c40, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:04,776 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:04,777 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:04,777 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e3814c8a-8433-4010-9fb1-f23dd36759fb, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:04,778 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e3814c8a-8433-4010-9fb1-f23dd36759fb, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:04,780 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e3814c8a-8433-4010-9fb1-f23dd36759fb, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:04,961 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:04,961 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:04,962 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 651e8921-e95b-4d76-98cc-4b99e68365a8, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:04,963 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 651e8921-e95b-4d76-98cc-4b99e68365a8, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:04,964 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 651e8921-e95b-4d76-98cc-4b99e68365a8, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:05,244 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:05,245 - 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-11-05 12:22:05,245 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:05,246 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 61d23b8d-b358-484c-bf34-e9f983506b8c, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:05,247 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 61d23b8d-b358-484c-bf34-e9f983506b8c, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:05,249 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 61d23b8d-b358-484c-bf34-e9f983506b8c, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:06,080 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:06,080 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:06,081 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9d32959c-9a38-426d-a67f-0173796aa417, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:06,082 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9d32959c-9a38-426d-a67f-0173796aa417, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:06,083 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9d32959c-9a38-426d-a67f-0173796aa417, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:06,308 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:06,309 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:06,309 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 45f65cf6-0ad7-4bcc-95f7-1e6fef182d9e, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:06,310 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 45f65cf6-0ad7-4bcc-95f7-1e6fef182d9e, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:06,311 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 45f65cf6-0ad7-4bcc-95f7-1e6fef182d9e, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:06,650 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:06,651 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 4855296, 'BlobCount': 384}}
2025-11-05 12:22:06,651 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:06,651 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 956b3317-0447-4202-a1da-854439beab27, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:06,652 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 956b3317-0447-4202-a1da-854439beab27, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:06,653 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 956b3317-0447-4202-a1da-854439beab27, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:07,544 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:07,545 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:07,546 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d70a0a48-745d-423a-8489-b0644728fdb5, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:07,547 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d70a0a48-745d-423a-8489-b0644728fdb5, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:07,553 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d70a0a48-745d-423a-8489-b0644728fdb5, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:07,761 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:07,762 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:07,763 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9fe16cba-1807-4551-b238-b9884f4bf636, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:07,764 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9fe16cba-1807-4551-b238-b9884f4bf636, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:07,765 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9fe16cba-1807-4551-b238-b9884f4bf636, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:08,119 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:08,120 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 6473728, 'BlobCount': 512}}
2025-11-05 12:22:08,121 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:08,121 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5efe09ab-ebea-46f1-a226-c2f6c96f1ffc, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:08,123 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5efe09ab-ebea-46f1-a226-c2f6c96f1ffc, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:08,124 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5efe09ab-ebea-46f1-a226-c2f6c96f1ffc, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:09,020 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:09,021 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:09,022 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 92cb1274-cbee-40fb-8e1a-519073f166fd, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:09,023 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 92cb1274-cbee-40fb-8e1a-519073f166fd, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:09,025 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 92cb1274-cbee-40fb-8e1a-519073f166fd, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:09,247 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:09,248 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:09,249 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5ce5b69f-2503-469e-94c7-8498338ad940, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:09,250 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5ce5b69f-2503-469e-94c7-8498338ad940, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:09,251 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5ce5b69f-2503-469e-94c7-8498338ad940, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:09,620 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:09,621 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 8092160, 'BlobCount': 640}}
2025-11-05 12:22:09,621 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:09,622 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb05baf8-11cf-483d-80cc-1339d737bfc7, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:09,623 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb05baf8-11cf-483d-80cc-1339d737bfc7, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:09,625 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb05baf8-11cf-483d-80cc-1339d737bfc7, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:10,503 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:10,504 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:10,504 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1773e1f7-cece-465b-a42f-bff53e80352b, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:10,505 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1773e1f7-cece-465b-a42f-bff53e80352b, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:10,507 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1773e1f7-cece-465b-a42f-bff53e80352b, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:10,718 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:10,718 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:10,719 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, db8cd397-1a3b-4bd0-8b51-8d2398950132, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:10,720 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, db8cd397-1a3b-4bd0-8b51-8d2398950132, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:10,721 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, db8cd397-1a3b-4bd0-8b51-8d2398950132, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:11,180 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:11,181 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 9710592, 'BlobCount': 768}}
2025-11-05 12:22:11,181 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:11,182 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cc2d88d8-8835-4605-b9fa-326d9c6a5745, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:11,183 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cc2d88d8-8835-4605-b9fa-326d9c6a5745, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:11,184 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cc2d88d8-8835-4605-b9fa-326d9c6a5745, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:12,056 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:12,057 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:12,057 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ab1c02fa-6007-4921-98f6-b381f234f212, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:12,058 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ab1c02fa-6007-4921-98f6-b381f234f212, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:12,059 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ab1c02fa-6007-4921-98f6-b381f234f212, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:12,315 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:12,316 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:12,317 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f8fd620e-138b-42c2-a6d9-79e572607e7e, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:12,318 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f8fd620e-138b-42c2-a6d9-79e572607e7e, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:12,319 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f8fd620e-138b-42c2-a6d9-79e572607e7e, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:12,799 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:12,800 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 11329024, 'BlobCount': 896}}
2025-11-05 12:22:12,800 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:12,801 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9f01cc1d-9573-4aa1-a869-dec1cabde2e9, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:12,802 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9f01cc1d-9573-4aa1-a869-dec1cabde2e9, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:12,804 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9f01cc1d-9573-4aa1-a869-dec1cabde2e9, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:13,882 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:13,883 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:13,884 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 25e83ba9-cc1f-473f-a0a4-37b96424a422, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:13,885 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 25e83ba9-cc1f-473f-a0a4-37b96424a422, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:13,887 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 25e83ba9-cc1f-473f-a0a4-37b96424a422, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:14,168 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:14,169 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:14,169 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d8657c96-7d26-4059-8ded-1e9c0614d17a, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:14,171 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d8657c96-7d26-4059-8ded-1e9c0614d17a, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:14,172 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d8657c96-7d26-4059-8ded-1e9c0614d17a, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:14,743 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:14,743 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 12947456, 'BlobCount': 1024}}
2025-11-05 12:22:14,744 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:14,745 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e7a7aceb-e42f-4323-9b98-90be42bcaaab, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:14,745 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e7a7aceb-e42f-4323-9b98-90be42bcaaab, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:14,748 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e7a7aceb-e42f-4323-9b98-90be42bcaaab, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:15,979 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:15,979 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:15,980 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e351654c-1901-43a0-b7c5-83e396906506, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:15,981 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e351654c-1901-43a0-b7c5-83e396906506, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:15,991 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e351654c-1901-43a0-b7c5-83e396906506, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:16,211 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:16,212 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:16,213 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c228f223-ab1d-434c-ba1a-d999c25a4ee9, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:16,213 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c228f223-ab1d-434c-ba1a-d999c25a4ee9, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:16,226 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c228f223-ab1d-434c-ba1a-d999c25a4ee9, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:16,895 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:16,896 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 14565888, 'BlobCount': 1152}}
2025-11-05 12:22:16,897 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:16,898 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 85306cc7-0caa-48e3-ac66-7303865c2d92, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:16,899 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 85306cc7-0caa-48e3-ac66-7303865c2d92, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/off_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:16,900 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 85306cc7-0caa-48e3-ac66-7303865c2d92, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:17,835 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:17,836 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:17,837 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2bce111c-56e6-49f6-939d-0f018fc8049c, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:17,838 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2bce111c-56e6-49f6-939d-0f018fc8049c, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:17,839 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2bce111c-56e6-49f6-939d-0f018fc8049c, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:18,080 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:18,081 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:18,082 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 93195c3c-dd19-442e-8c07-4694249616a9, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:18,083 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 93195c3c-dd19-442e-8c07-4694249616a9, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:18,084 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 93195c3c-dd19-442e-8c07-4694249616a9, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:18,697 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:18,698 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/off_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 16184320, 'BlobCount': 1280}}
2025-11-05 12:22:18,698 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:18,699 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b7ddd52f-8ba7-45a5-8313-c4a24f092ca6, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:18,699 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b7ddd52f-8ba7-45a5-8313-c4a24f092ca6, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/off_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:18,701 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b7ddd52f-8ba7-45a5-8313-c4a24f092ca6, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:19,753 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:19,754 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:19,755 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5deb18a5-1523-4444-af00-d363da9ca472, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:19,755 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5deb18a5-1523-4444-af00-d363da9ca472, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:19,757 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5deb18a5-1523-4444-af00-d363da9ca472, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:19,992 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:19,993 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:19,994 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ae43107f-c58a-4b98-9f78-6f9ceb7f50c2, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:19,995 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ae43107f-c58a-4b98-9f78-6f9ceb7f50c2, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:19,996 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ae43107f-c58a-4b98-9f78-6f9ceb7f50c2, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:20,260 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:20,261 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:20,261 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7281e6cb-78fa-4b23-b66a-18aa74e4d430, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:20,262 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7281e6cb-78fa-4b23-b66a-18aa74e4d430, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:20,263 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7281e6cb-78fa-4b23-b66a-18aa74e4d430, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:24,003 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:34,081 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:34,082 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 10d1210d-b837-4d1f-adad-efd50c393077, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:34,083 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 10d1210d-b837-4d1f-adad-efd50c393077, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:34,084 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 10d1210d-b837-4d1f-adad-efd50c393077, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:37,003 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:37,043 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-05 12:22:37,044 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:37,045 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 87b0993f-b418-48bb-a064-8854eaa496ac, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:37,045 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 87b0993f-b418-48bb-a064-8854eaa496ac, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:37,047 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 87b0993f-b418-48bb-a064-8854eaa496ac, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:40,029 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:50,070 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:50,071 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 08d4e2a6-4a7e-41a0-a477-f90096b478c8, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:50,072 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 08d4e2a6-4a7e-41a0-a477-f90096b478c8, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:50,073 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 08d4e2a6-4a7e-41a0-a477-f90096b478c8, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:54,287 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:54,335 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-05 12:22:54,336 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:54,336 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d3d24c3-75be-4b56-90fa-6c2a61798a2c, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:54,337 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d3d24c3-75be-4b56-90fa-6c2a61798a2c, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/off_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:54,340 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d3d24c3-75be-4b56-90fa-6c2a61798a2c, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:54,662 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:54,666 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_18_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 18])
2025-11-05 12:22:54,667 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:54,668 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6d30f89b-1212-4d92-acf3-0067d9d8b275, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:54,669 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6d30f89b-1212-4d92-acf3-0067d9d8b275, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_18_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 18)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:54,675 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6d30f89b-1212-4d92-acf3-0067d9d8b275, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:58,695 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:58,695 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_18_compression created
2025-11-05 12:22:58,696 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:58,697 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a8a801eb-7536-4b1a-8809-3c047f30d8cb, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:58,697 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a8a801eb-7536-4b1a-8809-3c047f30d8cb, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_18_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:58,700 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a8a801eb-7536-4b1a-8809-3c047f30d8cb, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:22:59,612 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:59,612 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:22:59,613 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f4597f4f-f229-4506-9ec6-9bc930409765, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:22:59,614 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f4597f4f-f229-4506-9ec6-9bc930409765, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:22:59,615 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f4597f4f-f229-4506-9ec6-9bc930409765, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:06,680 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:06,681 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:06,682 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6bba743c-20b3-4dbd-8781-42d8cf6ee387, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:06,683 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6bba743c-20b3-4dbd-8781-42d8cf6ee387, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:06,685 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6bba743c-20b3-4dbd-8781-42d8cf6ee387, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:08,954 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:08,955 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:08,956 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4fbf2132-806b-4d6a-9e23-afc00a397f49, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:08,957 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4fbf2132-806b-4d6a-9e23-afc00a397f49, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:08,966 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4fbf2132-806b-4d6a-9e23-afc00a397f49, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:11,862 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:11,863 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-11-05 12:23:11,863 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:11,865 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 88cda0e4-e80e-4071-9fbe-72322e0ea830, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:11,865 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 88cda0e4-e80e-4071-9fbe-72322e0ea830, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:11,867 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 88cda0e4-e80e-4071-9fbe-72322e0ea830, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:18,995 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:18,996 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:18,997 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e9e2ade-d011-492e-85da-eb511fbb6cfb, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:18,998 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e9e2ade-d011-492e-85da-eb511fbb6cfb, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:18,999 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e9e2ade-d011-492e-85da-eb511fbb6cfb, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:19,630 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:19,633 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:19,633 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 99575e16-d037-47f7-b46e-fe96e9f9c741, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:19,634 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 99575e16-d037-47f7-b46e-fe96e9f9c741, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:19,637 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 99575e16-d037-47f7-b46e-fe96e9f9c741, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:20,332 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:20,333 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-11-05 12:23:20,334 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:20,335 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 57351a55-b370-42d5-a081-fd40adf966dd, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:20,336 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 57351a55-b370-42d5-a081-fd40adf966dd, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:20,337 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 57351a55-b370-42d5-a081-fd40adf966dd, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:28,315 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:28,316 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:28,317 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 163c7945-a394-48b8-896a-d9578a60a015, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:28,318 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 163c7945-a394-48b8-896a-d9578a60a015, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:28,320 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 163c7945-a394-48b8-896a-d9578a60a015, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:28,929 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:28,930 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:28,931 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 189a7041-bc0c-47cf-84eb-b93eef8ca479, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:28,932 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 189a7041-bc0c-47cf-84eb-b93eef8ca479, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:28,934 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 189a7041-bc0c-47cf-84eb-b93eef8ca479, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:30,355 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:30,356 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-11-05 12:23:30,357 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:30,358 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9056878f-c58f-4bac-a4b8-6579f840e26f, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:30,370 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9056878f-c58f-4bac-a4b8-6579f840e26f, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:30,372 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9056878f-c58f-4bac-a4b8-6579f840e26f, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:38,771 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:38,771 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:38,772 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 99bc1079-3d60-4dbb-99b6-33923e227b6c, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:38,773 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 99bc1079-3d60-4dbb-99b6-33923e227b6c, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:38,776 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 99bc1079-3d60-4dbb-99b6-33923e227b6c, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:39,382 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:39,383 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:39,384 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 79269647-f7a9-476e-afd4-9e83630d669b, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:39,385 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 79269647-f7a9-476e-afd4-9e83630d669b, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:39,386 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 79269647-f7a9-476e-afd4-9e83630d669b, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:40,308 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:40,309 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-11-05 12:23:40,309 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:40,310 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ef72d553-9c47-414a-8f94-38db46fa196a, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:40,311 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ef72d553-9c47-414a-8f94-38db46fa196a, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:40,313 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ef72d553-9c47-414a-8f94-38db46fa196a, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:48,816 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:48,817 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:48,818 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 32240bf7-b8ec-4d3b-981a-2f38c694c5f2, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:48,819 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 32240bf7-b8ec-4d3b-981a-2f38c694c5f2, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:48,821 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 32240bf7-b8ec-4d3b-981a-2f38c694c5f2, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:49,479 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:49,480 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:49,481 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 53346a18-0d16-4691-9643-899d6c47e281, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:49,482 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 53346a18-0d16-4691-9643-899d6c47e281, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:49,484 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 53346a18-0d16-4691-9643-899d6c47e281, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:50,695 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-05 12:23:50,695 - 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-11-05 12:23:50,705 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:50,706 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-11-05 12:23:50,706 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:50,707 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d70ba655-3faa-4f32-b103-33d7ff4ac3cb, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:50,708 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d70ba655-3faa-4f32-b103-33d7ff4ac3cb, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:50,710 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d70ba655-3faa-4f32-b103-33d7ff4ac3cb, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:50,724 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-05 12:23:50,725 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 968945e1-7cdc-46b7-ba11-f76364e24695, localhost:5587): creating call state
2025-11-05 12:23:50,725 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 968945e1-7cdc-46b7-ba11-f76364e24695, localhost:5587): request = { database: "/Root" }
2025-11-05 12:23:50,737 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 968945e1-7cdc-46b7-ba11-f76364e24695, localhost:5587): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-anwhr6cmha.auto.internal" port: 5587 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-05 12:23:50,738 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-anwhr6cmha.auto.internal:5587, location 1, ssl: False>]>
2025-11-05 12:23:50,739 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:5587
2025-11-05 12:23:57,408 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:57,409 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:57,410 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 43275f89-eb5e-4c14-981c-d14faf5093ec, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:57,411 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 43275f89-eb5e-4c14-981c-d14faf5093ec, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:57,414 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 43275f89-eb5e-4c14-981c-d14faf5093ec, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:23:58,008 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:58,009 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:23:58,010 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4e218c96-7c79-45a7-b1a8-019264660f17, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:23:58,011 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4e218c96-7c79-45a7-b1a8-019264660f17, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:23:58,024 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4e218c96-7c79-45a7-b1a8-019264660f17, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:00,053 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:00,054 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-11-05 12:24:00,054 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:00,055 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6709cbc8-cbfd-4c40-90aa-28c548ad0244, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:00,056 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6709cbc8-cbfd-4c40-90aa-28c548ad0244, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:00,066 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6709cbc8-cbfd-4c40-90aa-28c548ad0244, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:08,467 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:08,468 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:08,469 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0b1d345a-5511-4c84-a93c-192bde471010, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:08,470 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0b1d345a-5511-4c84-a93c-192bde471010, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:08,475 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0b1d345a-5511-4c84-a93c-192bde471010, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:09,195 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:09,196 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:09,197 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 02b536b7-fa41-45b8-8e4b-6cd01eb45c91, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:09,198 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 02b536b7-fa41-45b8-8e4b-6cd01eb45c91, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:09,201 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 02b536b7-fa41-45b8-8e4b-6cd01eb45c91, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:10,203 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:10,204 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-11-05 12:24:10,204 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:10,205 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f77619f2-fcb9-4d10-910d-9d53fc4a09bf, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:10,206 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f77619f2-fcb9-4d10-910d-9d53fc4a09bf, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:10,207 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f77619f2-fcb9-4d10-910d-9d53fc4a09bf, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:17,114 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:17,114 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:17,115 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 58e3212d-89ba-4bb0-a7dc-102e775c5b26, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:17,116 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 58e3212d-89ba-4bb0-a7dc-102e775c5b26, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:17,117 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 58e3212d-89ba-4bb0-a7dc-102e775c5b26, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:18,011 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:18,012 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:18,013 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7f4c84b8-1e80-4743-8450-4c815a72d609, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:18,014 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7f4c84b8-1e80-4743-8450-4c815a72d609, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:18,015 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7f4c84b8-1e80-4743-8450-4c815a72d609, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:19,022 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:19,023 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-11-05 12:24:19,024 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:19,025 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3b366639-5f5a-4a3d-ba66-8fb40578ecb4, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:19,026 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3b366639-5f5a-4a3d-ba66-8fb40578ecb4, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:19,028 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3b366639-5f5a-4a3d-ba66-8fb40578ecb4, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:25,421 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:25,422 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:25,422 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 96ca8888-e644-40f1-be8f-c81bae5ca46f, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:25,423 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 96ca8888-e644-40f1-be8f-c81bae5ca46f, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:25,425 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 96ca8888-e644-40f1-be8f-c81bae5ca46f, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:25,901 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:25,901 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:25,902 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ed55b84b-83da-41b5-b624-2db900975c39, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:25,903 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ed55b84b-83da-41b5-b624-2db900975c39, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:25,915 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ed55b84b-83da-41b5-b624-2db900975c39, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:27,005 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:27,006 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-11-05 12:24:27,006 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:27,007 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 32873e65-ede5-4f47-b5a0-c1d6000522a6, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:27,008 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 32873e65-ede5-4f47-b5a0-c1d6000522a6, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:27,010 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 32873e65-ede5-4f47-b5a0-c1d6000522a6, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:33,388 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:33,388 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:33,389 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1a4f7be3-c158-4d8b-ba16-53fa37755c98, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:33,390 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1a4f7be3-c158-4d8b-ba16-53fa37755c98, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:33,392 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1a4f7be3-c158-4d8b-ba16-53fa37755c98, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:34,058 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:34,059 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:34,060 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 17727368-8899-4fd3-aa53-baf2ee932ada, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:34,061 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 17727368-8899-4fd3-aa53-baf2ee932ada, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:34,063 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 17727368-8899-4fd3-aa53-baf2ee932ada, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:35,159 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:35,160 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-11-05 12:24:35,160 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:35,161 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8cd6de3e-5deb-4c22-b4f5-d5bbf1b4902d, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:35,162 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8cd6de3e-5deb-4c22-b4f5-d5bbf1b4902d, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_18_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:35,163 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8cd6de3e-5deb-4c22-b4f5-d5bbf1b4902d, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:36,823 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:36,824 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:36,825 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 44607ad4-ccc8-453e-a68b-d022d40d8ce3, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:36,826 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 44607ad4-ccc8-453e-a68b-d022d40d8ce3, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:36,828 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 44607ad4-ccc8-453e-a68b-d022d40d8ce3, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:37,500 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:37,501 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:37,501 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f6162c82-a36e-4cfa-bcc7-4dc8ec3d4c15, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:37,502 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f6162c82-a36e-4cfa-bcc7-4dc8ec3d4c15, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:37,504 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f6162c82-a36e-4cfa-bcc7-4dc8ec3d4c15, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:38,216 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:38,217 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:38,218 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aad182af-0c46-4b8c-8cf5-1ed870e34b8e, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:38,219 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aad182af-0c46-4b8c-8cf5-1ed870e34b8e, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:38,220 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aad182af-0c46-4b8c-8cf5-1ed870e34b8e, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:24:46,095 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:56,178 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:24:56,179 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c8e6e6d3-f0b8-4d83-9e1c-1953da9b5ad0, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:24:56,180 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c8e6e6d3-f0b8-4d83-9e1c-1953da9b5ad0, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:24:56,182 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c8e6e6d3-f0b8-4d83-9e1c-1953da9b5ad0, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:01,598 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:25:01,667 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_18_compression`, volumes `value` (8000000, 1594176)
2025-11-05 12:25:01,667 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:25:01,668 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f7dbd6b4-21dd-4a1e-b518-a3be23beb174, ghrun-anwhr6cmha.auto.internal:5587): creating call state
2025-11-05 12:25:01,669 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f7dbd6b4-21dd-4a1e-b518-a3be23beb174, ghrun-anwhr6cmha.auto.internal:5587): request = { session_id: "ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=" 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_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-05 12:25:01,672 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f7dbd6b4-21dd-4a1e-b518-a3be23beb174, ghrun-anwhr6cmha.auto.internal:5587): response = { <_MultiThreadedRendezvous object> }
2025-11-05 12:25:02,310 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjgwMmYwNDgtMjY1ODI0MDQtN2IxOTNiZWYtMTMzNTQyM2Q=
2025-11-05 12:25:03,366 - ERROR - ya.test - logreport: ydb/tests/olap/column_family/compression/alter_compression.py:194: in test_all_supported_compression
    assert table.get_portion_stat_by_tier()['__DEFAULT']['Rows'] == expected_raw // 8
E   assert 1015614 == (8000000 // 8)
2025-11-05 12:25:03,369 - INFO - ya.test - pytest_runtest_teardown: Test teardown
