2025-11-01 20:18:25,830 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 20:18:25,831 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_2_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 2]
2025-11-01 20:18:25,831 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 20:18:25,831 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 20:18:25,834 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-01 20:18:25,835 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-01 20:18:25,835 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/4xuc/0024da/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 20:18:25,837 - DEBUG - ya.test - execute: Command pid: 916685
2025-11-01 20:18:26,755 - DEBUG - ya.test - _save_outputs: Command (pid 916685) rc: 0
2025-11-01 20:18:26,755 - DEBUG - ya.test - _save_outputs: Command (pid 916685) elapsed time (sec): 0.9163556098937988
2025-11-01 20:18:26,756 - DEBUG - ya.test - _save_outputs: Command (pid 916685) idrss: 0
2025-11-01 20:18:26,756 - DEBUG - ya.test - _save_outputs: Command (pid 916685) inblock: 0
2025-11-01 20:18:26,756 - DEBUG - ya.test - _save_outputs: Command (pid 916685) isrss: 0
2025-11-01 20:18:26,757 - DEBUG - ya.test - _save_outputs: Command (pid 916685) ixrss: 0
2025-11-01 20:18:26,757 - DEBUG - ya.test - _save_outputs: Command (pid 916685) majflt: 0
2025-11-01 20:18:26,757 - DEBUG - ya.test - _save_outputs: Command (pid 916685) maxrss: 492692
2025-11-01 20:18:26,758 - DEBUG - ya.test - _save_outputs: Command (pid 916685) minflt: 45320
2025-11-01 20:18:26,758 - DEBUG - ya.test - _save_outputs: Command (pid 916685) msgrcv: 0
2025-11-01 20:18:26,759 - DEBUG - ya.test - _save_outputs: Command (pid 916685) msgsnd: 0
2025-11-01 20:18:26,759 - DEBUG - ya.test - _save_outputs: Command (pid 916685) nivcsw: 118
2025-11-01 20:18:26,759 - DEBUG - ya.test - _save_outputs: Command (pid 916685) nsignals: 0
2025-11-01 20:18:26,759 - DEBUG - ya.test - _save_outputs: Command (pid 916685) nswap: 0
2025-11-01 20:18:26,760 - DEBUG - ya.test - _save_outputs: Command (pid 916685) nvcsw: 13
2025-11-01 20:18:26,760 - DEBUG - ya.test - _save_outputs: Command (pid 916685) oublock: 8
2025-11-01 20:18:26,760 - DEBUG - ya.test - _save_outputs: Command (pid 916685) stime: 0.260772
2025-11-01 20:18:26,761 - DEBUG - ya.test - _save_outputs: Command (pid 916685) utime: 0.5305799999999999
2025-11-01 20:18:26,761 - DEBUG - ya.test - _save_outputs: Command (pid 916685) wtime: 0.919
2025-11-01 20:18:26,761 - DEBUG - ya.test - _save_outputs: Command (pid 916685) output:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-govtdgmox4
    Host information: 
        Linux ghrun-govtdgmox4 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-01 20:18:26,762 - DEBUG - ya.test - _save_outputs: Command (pid 916685) errors:

2025-11-01 20:18:26,762 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-govtdgmox4
    Host information: 
        Linux ghrun-govtdgmox4 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-01 20:18:26,893 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/4xuc/0024da/ydb/apps/ydbd/ydbd
2025-11-01 20:18:26,895 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27046
2025-11-01 20:18:26,897 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/4xuc/0024da/r3tmp/kikimr_cluster_inrtiwuw
2025-11-01 20:18:26,941 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/4xuc/0024da/ydb/apps/ydbd/ydbd
2025-11-01 20:18:26,941 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14442
2025-11-01 20:18:26,942 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63064
2025-11-01 20:18:26,943 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10905
2025-11-01 20:18:26,943 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1123
2025-11-01 20:18:26,944 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4xuc/0024da/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs"
2025-11-01 20:18:26,945 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/4xuc/0024da/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/4xuc/0024da/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_zzd6qggg.log --grpc-port=14442 --mon-port=63064 --ic-port=27046
2025-11-01 20:18:26,946 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:14442/1, disk_size 68719476736
2025-11-01 20:18:26,946 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/4xuc/0024da/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs"
2025-11-01 20:18:26,947 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/4xuc/0024da/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/4xuc/0024da/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_zzd6qggg.log --grpc-port=14442 --mon-port=63064 --ic-port=27046
2025-11-01 20:18:26,948 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/4xuc/0024da/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/4xuc/0024da/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/4xuc/0024da/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1/logfile_zzd6qggg.log', '--grpc-port=14442', '--mon-port=63064', '--ic-port=27046')' in '/home/runner/.ya/build/build_root/4xuc/0024da/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk5/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_2_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.2/cluster/node_1' (no wait)
2025-11-01 20:18:26,967 - DEBUG - ya.test - execute: Command pid: 916852
2025-11-01 20:18:26,968 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:14442/1
2025-11-01 20:18:26,970 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-01 20:18:26,971 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-01 20:18:26,971 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/4xuc/0024da/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 20:18:26,979 - DEBUG - ya.test - execute: Command pid: 916853
2025-11-01 20:18:27,885 - DEBUG - ya.test - _save_outputs: Command (pid 916853) rc: 0
2025-11-01 20:18:27,885 - DEBUG - ya.test - _save_outputs: Command (pid 916853) elapsed time (sec): 0.9048914909362793
2025-11-01 20:18:27,886 - DEBUG - ya.test - _save_outputs: Command (pid 916853) idrss: 0
2025-11-01 20:18:27,886 - DEBUG - ya.test - _save_outputs: Command (pid 916853) inblock: 0
2025-11-01 20:18:27,886 - DEBUG - ya.test - _save_outputs: Command (pid 916853) isrss: 0
2025-11-01 20:18:27,887 - DEBUG - ya.test - _save_outputs: Command (pid 916853) ixrss: 0
2025-11-01 20:18:27,887 - DEBUG - ya.test - _save_outputs: Command (pid 916853) majflt: 0
2025-11-01 20:18:27,887 - DEBUG - ya.test - _save_outputs: Command (pid 916853) maxrss: 493456
2025-11-01 20:18:27,888 - DEBUG - ya.test - _save_outputs: Command (pid 916853) minflt: 45321
2025-11-01 20:18:27,888 - DEBUG - ya.test - _save_outputs: Command (pid 916853) msgrcv: 0
2025-11-01 20:18:27,888 - DEBUG - ya.test - _save_outputs: Command (pid 916853) msgsnd: 0
2025-11-01 20:18:27,888 - DEBUG - ya.test - _save_outputs: Command (pid 916853) nivcsw: 70
2025-11-01 20:18:27,889 - DEBUG - ya.test - _save_outputs: Command (pid 916853) nsignals: 0
2025-11-01 20:18:27,889 - DEBUG - ya.test - _save_outputs: Command (pid 916853) nswap: 0
2025-11-01 20:18:27,889 - DEBUG - ya.test - _save_outputs: Command (pid 916853) nvcsw: 14
2025-11-01 20:18:27,890 - DEBUG - ya.test - _save_outputs: Command (pid 916853) oublock: 8
2025-11-01 20:18:27,890 - DEBUG - ya.test - _save_outputs: Command (pid 916853) stime: 0.20305199999999998
2025-11-01 20:18:27,891 - DEBUG - ya.test - _save_outputs: Command (pid 916853) utime: 0.588814
2025-11-01 20:18:27,891 - DEBUG - ya.test - _save_outputs: Command (pid 916853) wtime: 0.913
2025-11-01 20:18:27,892 - DEBUG - ya.test - _save_outputs: Command (pid 916853) output:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-govtdgmox4
    Host information: 
        Linux ghrun-govtdgmox4 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-01 20:18:27,892 - DEBUG - ya.test - _save_outputs: Command (pid 916853) errors:

2025-11-01 20:18:27,893 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 05d0b84db63541076afbd98f86145cdc7d321216
    Branch: 
    Author: Bulat <bylatgr@gmail.com>
    Summary: Merge 178566593d70f1bd1201a183576bb9dac2870947 into d673dcc87a894a66c7dc98770ee098d1af022f54
2025-11-01 20:18:32,881 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/4xuc/0024da/ydb/apps/ydbd/ydbd
2025-11-01 20:18:32,881 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/4xuc/0024da/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:14442', '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: 27046\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 20:18:32,883 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-01 20:18:32,884 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-01 20:18:32,885 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/4xuc/0024da/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:14442', '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: 27046\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 20:18:32,887 - DEBUG - ya.test - execute: Command pid: 918014
2025-11-01 20:18:34,055 - DEBUG - ya.test - _save_outputs: Command (pid 918014) rc: 0
2025-11-01 20:18:34,056 - DEBUG - ya.test - _save_outputs: Command (pid 918014) elapsed time (sec): 1.1668925285339355
2025-11-01 20:18:34,056 - DEBUG - ya.test - _save_outputs: Command (pid 918014) idrss: 0
2025-11-01 20:18:34,056 - DEBUG - ya.test - _save_outputs: Command (pid 918014) inblock: 0
2025-11-01 20:18:34,057 - DEBUG - ya.test - _save_outputs: Command (pid 918014) isrss: 0
2025-11-01 20:18:34,057 - DEBUG - ya.test - _save_outputs: Command (pid 918014) ixrss: 0
2025-11-01 20:18:34,057 - DEBUG - ya.test - _save_outputs: Command (pid 918014) majflt: 0
2025-11-01 20:18:34,058 - DEBUG - ya.test - _save_outputs: Command (pid 918014) maxrss: 585396
2025-11-01 20:18:34,058 - DEBUG - ya.test - _save_outputs: Command (pid 918014) minflt: 51701
2025-11-01 20:18:34,058 - DEBUG - ya.test - _save_outputs: Command (pid 918014) msgrcv: 0
2025-11-01 20:18:34,059 - DEBUG - ya.test - _save_outputs: Command (pid 918014) msgsnd: 0
2025-11-01 20:18:34,059 - DEBUG - ya.test - _save_outputs: Command (pid 918014) nivcsw: 215
2025-11-01 20:18:34,059 - DEBUG - ya.test - _save_outputs: Command (pid 918014) nsignals: 0
2025-11-01 20:18:34,060 - DEBUG - ya.test - _save_outputs: Command (pid 918014) nswap: 0
2025-11-01 20:18:34,060 - DEBUG - ya.test - _save_outputs: Command (pid 918014) nvcsw: 714
2025-11-01 20:18:34,060 - DEBUG - ya.test - _save_outputs: Command (pid 918014) oublock: 8
2025-11-01 20:18:34,061 - DEBUG - ya.test - _save_outputs: Command (pid 918014) stime: 0.346724
2025-11-01 20:18:34,061 - DEBUG - ya.test - _save_outputs: Command (pid 918014) utime: 0.561586
2025-11-01 20:18:34,061 - DEBUG - ya.test - _save_outputs: Command (pid 918014) wtime: 1.169
2025-11-01 20:18:34,062 - DEBUG - ya.test - _save_outputs: Command (pid 918014) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 20:18:34,062 - DEBUG - ya.test - _save_outputs: Command (pid 918014) errors:

2025-11-01 20:18:34,070 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/4xuc/0024da/ydb/apps/ydbd/ydbd
2025-11-01 20:18:34,071 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/4xuc/0024da/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:14442', '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-01 20:18:34,073 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-01 20:18:34,074 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-01 20:18:34,074 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/4xuc/0024da/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:14442', '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-01 20:18:34,089 - DEBUG - ya.test - execute: Command pid: 918224
2025-11-01 20:18:35,125 - DEBUG - ya.test - _save_outputs: Command (pid 918224) rc: 0
2025-11-01 20:18:35,125 - DEBUG - ya.test - _save_outputs: Command (pid 918224) elapsed time (sec): 1.0345840454101562
2025-11-01 20:18:35,126 - DEBUG - ya.test - _save_outputs: Command (pid 918224) idrss: 0
2025-11-01 20:18:35,126 - DEBUG - ya.test - _save_outputs: Command (pid 918224) inblock: 0
2025-11-01 20:18:35,126 - DEBUG - ya.test - _save_outputs: Command (pid 918224) isrss: 0
2025-11-01 20:18:35,127 - DEBUG - ya.test - _save_outputs: Command (pid 918224) ixrss: 0
2025-11-01 20:18:35,127 - DEBUG - ya.test - _save_outputs: Command (pid 918224) majflt: 0
2025-11-01 20:18:35,127 - DEBUG - ya.test - _save_outputs: Command (pid 918224) maxrss: 585624
2025-11-01 20:18:35,128 - DEBUG - ya.test - _save_outputs: Command (pid 918224) minflt: 51708
2025-11-01 20:18:35,128 - DEBUG - ya.test - _save_outputs: Command (pid 918224) msgrcv: 0
2025-11-01 20:18:35,128 - DEBUG - ya.test - _save_outputs: Command (pid 918224) msgsnd: 0
2025-11-01 20:18:35,128 - DEBUG - ya.test - _save_outputs: Command (pid 918224) nivcsw: 181
2025-11-01 20:18:35,129 - DEBUG - ya.test - _save_outputs: Command (pid 918224) nsignals: 0
2025-11-01 20:18:35,129 - DEBUG - ya.test - _save_outputs: Command (pid 918224) nswap: 0
2025-11-01 20:18:35,129 - DEBUG - ya.test - _save_outputs: Command (pid 918224) nvcsw: 622
2025-11-01 20:18:35,130 - DEBUG - ya.test - _save_outputs: Command (pid 918224) oublock: 8
2025-11-01 20:18:35,130 - DEBUG - ya.test - _save_outputs: Command (pid 918224) stime: 0.32567999999999997
2025-11-01 20:18:35,130 - DEBUG - ya.test - _save_outputs: Command (pid 918224) utime: 0.576859
2025-11-01 20:18:35,131 - DEBUG - ya.test - _save_outputs: Command (pid 918224) wtime: 1.041
2025-11-01 20:18:35,131 - DEBUG - ya.test - _save_outputs: Command (pid 918224) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-01 20:18:35,131 - DEBUG - ya.test - _save_outputs: Command (pid 918224) errors:

2025-11-01 20:18:35,132 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 20:18:35,209 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 20:18:35,261 - 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-01 20:18:35,266 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 20:18:35,267 - 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-01 20:18:35,322 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 20:18:35,323 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 2cf41b61-f4f2-469c-adcb-ae34c18992e2, localhost:14442): creating call state
2025-11-01 20:18:35,324 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 2cf41b61-f4f2-469c-adcb-ae34c18992e2, localhost:14442): request = { database: "/Root" }
2025-11-01 20:18:35,369 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 2cf41b61-f4f2-469c-adcb-ae34c18992e2, localhost:14442): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-govtdgmox4.auto.internal" port: 14442 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-01 20:18:35,371 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-govtdgmox4.auto.internal:14442, location 1, ssl: False>]>
2025-11-01 20:18:35,372 - 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-01 20:18:35,389 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:14442
2025-11-01 20:18:35,391 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-01 20:18:35,393 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, e3e20d33-feb2-40be-adca-4ef7ee58f612, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:35,394 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, e3e20d33-feb2-40be-adca-4ef7ee58f612, ghrun-govtdgmox4.auto.internal:14442): request = {  }
2025-11-01 20:18:35,410 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, e3e20d33-feb2-40be-adca-4ef7ee58f612, ghrun-govtdgmox4.auto.internal:14442): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" node_id: 1 }
2025-11-01 20:18:35,411 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, fb72fe22-6560-45ca-a73f-58f70fb20dd8, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:35,412 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, fb72fe22-6560-45ca-a73f-58f70fb20dd8, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" }
2025-11-01 20:18:35,423 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, fb72fe22-6560-45ca-a73f-58f70fb20dd8, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:35,447 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:35,448 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ad4f8ef9-f248-4d13-82a0-9544b4de4666, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:35,449 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ad4f8ef9-f248-4d13-82a0-9544b4de4666, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:35,452 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ad4f8ef9-f248-4d13-82a0-9544b4de4666, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:38,660 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:38,661 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-01 20:18:38,662 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:38,663 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f9fc648e-9394-458e-b6cb-dcafefb6f4f3, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:38,664 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f9fc648e-9394-458e-b6cb-dcafefb6f4f3, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:38,666 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f9fc648e-9394-458e-b6cb-dcafefb6f4f3, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:39,660 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:39,661 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:39,662 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3a848415-2be0-48f2-83c7-fe2a6fd759b9, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:39,663 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3a848415-2be0-48f2-83c7-fe2a6fd759b9, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:39,671 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3a848415-2be0-48f2-83c7-fe2a6fd759b9, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:40,951 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:40,952 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:40,952 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 01c2de43-ea40-450f-b10a-5ffbb9c83563, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:40,953 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 01c2de43-ea40-450f-b10a-5ffbb9c83563, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:40,956 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 01c2de43-ea40-450f-b10a-5ffbb9c83563, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:41,934 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:41,935 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:41,936 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 39478c0b-69d2-44ca-90ea-5a2d3c3af330, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:41,937 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 39478c0b-69d2-44ca-90ea-5a2d3c3af330, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:41,940 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 39478c0b-69d2-44ca-90ea-5a2d3c3af330, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:43,511 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:43,511 - 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-01 20:18:43,512 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:43,513 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ec1284c2-8070-48dc-8ab3-b93e5b4a2b59, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:43,513 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ec1284c2-8070-48dc-8ab3-b93e5b4a2b59, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:43,515 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ec1284c2-8070-48dc-8ab3-b93e5b4a2b59, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:44,731 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:44,732 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:44,732 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 636d3607-22e6-473f-a51a-5b5bc721aee8, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:44,733 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 636d3607-22e6-473f-a51a-5b5bc721aee8, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:44,735 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 636d3607-22e6-473f-a51a-5b5bc721aee8, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:44,980 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:44,981 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:44,982 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6da16591-880a-4fc0-b8a4-1ab1473603a3, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:44,984 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6da16591-880a-4fc0-b8a4-1ab1473603a3, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:44,985 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6da16591-880a-4fc0-b8a4-1ab1473603a3, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:45,387 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:45,388 - 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-01 20:18:45,389 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:45,390 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0283a545-8f36-41c8-bdbf-c9be846e6a1b, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:45,392 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0283a545-8f36-41c8-bdbf-c9be846e6a1b, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:45,400 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0283a545-8f36-41c8-bdbf-c9be846e6a1b, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:46,687 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:46,688 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:46,689 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 11d75ae0-afc2-492d-8398-a236bf1e3498, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:46,690 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 11d75ae0-afc2-492d-8398-a236bf1e3498, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:46,692 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 11d75ae0-afc2-492d-8398-a236bf1e3498, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:46,992 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:46,994 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:46,995 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aebd41c7-4a0a-4bd4-a897-affbca769e12, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:46,996 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aebd41c7-4a0a-4bd4-a897-affbca769e12, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:47,003 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aebd41c7-4a0a-4bd4-a897-affbca769e12, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:47,464 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:47,465 - 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-01 20:18:47,466 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:47,467 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a8bec7da-b258-45c5-ae22-26e366990355, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:47,468 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a8bec7da-b258-45c5-ae22-26e366990355, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:47,472 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a8bec7da-b258-45c5-ae22-26e366990355, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:48,619 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:48,620 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:48,620 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dee6b005-238a-49ad-9ebb-006cb916b147, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:48,621 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dee6b005-238a-49ad-9ebb-006cb916b147, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:48,623 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dee6b005-238a-49ad-9ebb-006cb916b147, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:48,970 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:48,971 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:48,972 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 74d55a69-94eb-4fdc-a6ae-55ea6ac6e43b, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:48,973 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 74d55a69-94eb-4fdc-a6ae-55ea6ac6e43b, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:48,975 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 74d55a69-94eb-4fdc-a6ae-55ea6ac6e43b, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:49,383 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:49,384 - 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-01 20:18:49,385 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:49,386 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d5b29c26-95c1-4a40-88a6-0bf230ff8407, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:49,388 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d5b29c26-95c1-4a40-88a6-0bf230ff8407, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:49,390 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d5b29c26-95c1-4a40-88a6-0bf230ff8407, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:50,652 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:50,653 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:50,653 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8724a7c7-c54b-4ff0-9f09-7439fc75b63e, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:50,654 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8724a7c7-c54b-4ff0-9f09-7439fc75b63e, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:50,656 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8724a7c7-c54b-4ff0-9f09-7439fc75b63e, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:50,987 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:50,988 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:50,988 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 96fd03dd-7383-4859-a634-27ca2c12871b, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:50,989 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 96fd03dd-7383-4859-a634-27ca2c12871b, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:50,991 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 96fd03dd-7383-4859-a634-27ca2c12871b, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:51,445 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:51,446 - 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-01 20:18:51,447 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:51,448 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b7a596ad-67e4-4f9a-bce3-d096a7a5ee0a, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:51,449 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b7a596ad-67e4-4f9a-bce3-d096a7a5ee0a, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:51,452 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b7a596ad-67e4-4f9a-bce3-d096a7a5ee0a, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:52,783 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:52,784 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:52,785 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a4ee8e57-cc7a-472d-9337-7055acc38b58, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:52,786 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a4ee8e57-cc7a-472d-9337-7055acc38b58, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:52,788 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a4ee8e57-cc7a-472d-9337-7055acc38b58, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:53,120 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:53,121 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:53,122 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d72ed61f-4dfe-4a80-81b6-b9ec6d27c8b4, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:53,123 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d72ed61f-4dfe-4a80-81b6-b9ec6d27c8b4, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:53,124 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d72ed61f-4dfe-4a80-81b6-b9ec6d27c8b4, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:53,642 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:53,643 - 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-01 20:18:53,643 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:53,644 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a33c22ba-5df7-4f63-a95a-87d83a0546a3, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:53,645 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a33c22ba-5df7-4f63-a95a-87d83a0546a3, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:53,646 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a33c22ba-5df7-4f63-a95a-87d83a0546a3, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:54,920 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:54,921 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:54,922 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 05222609-8ef1-4bfb-95a5-8d2981210703, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:54,923 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 05222609-8ef1-4bfb-95a5-8d2981210703, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:54,926 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 05222609-8ef1-4bfb-95a5-8d2981210703, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:55,324 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:55,325 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:55,326 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8283538a-7559-419e-892d-e1218fc015a9, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:55,327 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8283538a-7559-419e-892d-e1218fc015a9, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:55,329 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8283538a-7559-419e-892d-e1218fc015a9, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:55,811 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:55,812 - 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-01 20:18:55,813 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:55,814 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ae5189dd-8086-42c5-a54b-f8884bca8842, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:55,815 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ae5189dd-8086-42c5-a54b-f8884bca8842, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:55,817 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ae5189dd-8086-42c5-a54b-f8884bca8842, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:57,000 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:57,001 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:57,002 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a35fc165-ec15-4ee6-9c7c-2948d6f075ba, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:57,003 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a35fc165-ec15-4ee6-9c7c-2948d6f075ba, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:57,005 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a35fc165-ec15-4ee6-9c7c-2948d6f075ba, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:57,357 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:57,358 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:57,360 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ca423e75-efea-4af8-b0dd-37afd6e48922, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:57,361 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ca423e75-efea-4af8-b0dd-37afd6e48922, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:57,364 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ca423e75-efea-4af8-b0dd-37afd6e48922, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:57,979 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:57,980 - 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-01 20:18:57,980 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:57,981 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a2803e7f-4e26-47b5-ab5f-c10ec174f568, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:57,982 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a2803e7f-4e26-47b5-ab5f-c10ec174f568, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:57,983 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a2803e7f-4e26-47b5-ab5f-c10ec174f568, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:59,478 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:59,479 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:59,480 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 09d1bf1e-615f-4c4b-9e1d-4bd5f7ee5ad7, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:59,481 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 09d1bf1e-615f-4c4b-9e1d-4bd5f7ee5ad7, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:59,484 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 09d1bf1e-615f-4c4b-9e1d-4bd5f7ee5ad7, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:18:59,884 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:59,885 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:18:59,886 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, da025d8e-f5e6-41f1-bce9-d6f23f321036, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:18:59,887 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, da025d8e-f5e6-41f1-bce9-d6f23f321036, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:18:59,889 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, da025d8e-f5e6-41f1-bce9-d6f23f321036, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:19:00,536 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:00,537 - 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-01 20:19:00,538 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:00,538 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f8eac4f2-671e-4bdc-9d70-27d4a8657296, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:19:00,539 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f8eac4f2-671e-4bdc-9d70-27d4a8657296, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:19:00,541 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f8eac4f2-671e-4bdc-9d70-27d4a8657296, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:19:01,867 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:01,867 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:01,868 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 692eec89-91d2-48d9-90e6-d4794d483286, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:19:01,869 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 692eec89-91d2-48d9-90e6-d4794d483286, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:19:01,870 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 692eec89-91d2-48d9-90e6-d4794d483286, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:19:02,127 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:02,128 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:02,129 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9d0c480d-a338-490e-9cb1-4480e9018b5e, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:19:02,131 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9d0c480d-a338-490e-9cb1-4480e9018b5e, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:19:02,133 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9d0c480d-a338-490e-9cb1-4480e9018b5e, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:19:02,876 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:02,877 - 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-01 20:19:02,878 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:02,879 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b98355b5-8bd9-4ef1-9a09-44bd12975e79, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:19:02,880 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b98355b5-8bd9-4ef1-9a09-44bd12975e79, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:19:02,883 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b98355b5-8bd9-4ef1-9a09-44bd12975e79, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:19:04,211 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:04,212 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:04,213 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d0cc06c6-d853-4839-9e34-e109c260117c, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:19:04,214 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d0cc06c6-d853-4839-9e34-e109c260117c, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:19:04,220 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d0cc06c6-d853-4839-9e34-e109c260117c, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:19:04,614 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:04,615 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:04,616 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 549c553a-7bca-432c-9e90-2a7ae068c05f, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:19:04,617 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 549c553a-7bca-432c-9e90-2a7ae068c05f, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:19:04,620 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 549c553a-7bca-432c-9e90-2a7ae068c05f, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:19:05,015 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:05,016 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:05,017 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a1130094-118e-4e7c-940d-ed00cb11089e, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:19:05,018 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a1130094-118e-4e7c-940d-ed00cb11089e, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:19:05,020 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a1130094-118e-4e7c-940d-ed00cb11089e, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:19:09,738 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:19,779 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:19,780 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b1267656-baca-4d69-840e-f2ff6bd7adfb, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:19:19,781 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b1267656-baca-4d69-840e-f2ff6bd7adfb, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:19:19,782 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b1267656-baca-4d69-840e-f2ff6bd7adfb, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:19:23,895 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:23,938 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-01 20:19:23,938 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:23,939 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a2b45834-8d5c-4838-8655-df26ea260697, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:19:23,940 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a2b45834-8d5c-4838-8655-df26ea260697, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:19:23,941 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a2b45834-8d5c-4838-8655-df26ea260697, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:19:27,708 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:37,755 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:37,756 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7fb877a1-b802-4ad8-86de-9a5ce5baf78e, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:19:37,757 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7fb877a1-b802-4ad8-86de-9a5ce5baf78e, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:19:37,758 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7fb877a1-b802-4ad8-86de-9a5ce5baf78e, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:19:41,868 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:51,914 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:19:51,915 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cda7a035-b608-4b05-b6d5-0bd44fd11450, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:19:51,916 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cda7a035-b608-4b05-b6d5-0bd44fd11450, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:19:51,918 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cda7a035-b608-4b05-b6d5-0bd44fd11450, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:19:52,903 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:02,910 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:02,911 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 003f2c2d-09f4-4c59-a21c-f83ef17917f6, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:02,912 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 003f2c2d-09f4-4c59-a21c-f83ef17917f6, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:20:02,913 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 003f2c2d-09f4-4c59-a21c-f83ef17917f6, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:03,669 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:03,672 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-11-01 20:20:03,673 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:03,674 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 58678ba8-4dee-4dda-a7f5-2b0e89757af1, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:03,674 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 58678ba8-4dee-4dda-a7f5-2b0e89757af1, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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-01 20:20:03,676 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 58678ba8-4dee-4dda-a7f5-2b0e89757af1, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:03,948 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:03,954 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_2_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 2])
2025-11-01 20:20:03,955 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:03,956 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9e50d16a-bb65-428c-94f4-6ffa85a34c70, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:03,957 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9e50d16a-bb65-428c-94f4-6ffa85a34c70, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_2_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 2)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:03,960 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9e50d16a-bb65-428c-94f4-6ffa85a34c70, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:07,283 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:07,284 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_2_compression created
2025-11-01 20:20:07,285 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:07,286 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6fd74a09-e69c-4766-8bcd-600044df7498, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:07,287 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6fd74a09-e69c-4766-8bcd-600044df7498, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_2_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:07,289 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6fd74a09-e69c-4766-8bcd-600044df7498, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:08,310 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:08,311 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:08,312 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0cefba20-3251-4444-81c4-05a31d4d9a36, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:08,313 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0cefba20-3251-4444-81c4-05a31d4d9a36, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:08,315 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0cefba20-3251-4444-81c4-05a31d4d9a36, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:09,963 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:09,964 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:09,964 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6c7b901d-3667-4dd4-9b95-640410245092, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:09,966 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6c7b901d-3667-4dd4-9b95-640410245092, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:09,967 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6c7b901d-3667-4dd4-9b95-640410245092, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:11,111 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:11,112 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:11,113 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 243c9f70-091c-4d0e-b5d2-0ff8e6276ec3, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:11,114 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 243c9f70-091c-4d0e-b5d2-0ff8e6276ec3, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:11,115 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 243c9f70-091c-4d0e-b5d2-0ff8e6276ec3, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:13,082 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 20:20:13,083 - 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-01 20:20:13,112 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 20:20:13,113 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 367174af-2dd0-4fd5-84b4-a87190702080, localhost:14442): creating call state
2025-11-01 20:20:13,113 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 367174af-2dd0-4fd5-84b4-a87190702080, localhost:14442): request = { database: "/Root" }
2025-11-01 20:20:13,148 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 367174af-2dd0-4fd5-84b4-a87190702080, localhost:14442): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-govtdgmox4.auto.internal" port: 14442 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-01 20:20:13,149 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-govtdgmox4.auto.internal:14442, location 1, ssl: False>]>
2025-11-01 20:20:13,150 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:14442
2025-11-01 20:20:13,175 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:13,176 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318880, 'BlobCount': 128}}
2025-11-01 20:20:13,176 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:13,177 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2fc23e61-b72c-4078-b6ef-cc6673788d99, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:13,178 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2fc23e61-b72c-4078-b6ef-cc6673788d99, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:13,180 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2fc23e61-b72c-4078-b6ef-cc6673788d99, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:14,488 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:14,488 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:14,489 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 15cb33d4-9060-49db-8aac-960c42f3f371, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:14,490 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 15cb33d4-9060-49db-8aac-960c42f3f371, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:14,492 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 15cb33d4-9060-49db-8aac-960c42f3f371, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:14,908 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:14,909 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:14,909 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f73c5baf-6e3f-4235-9601-b620d9750622, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:14,911 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f73c5baf-6e3f-4235-9601-b620d9750622, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:14,912 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f73c5baf-6e3f-4235-9601-b620d9750622, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:15,370 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:15,371 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638656, 'BlobCount': 256}}
2025-11-01 20:20:15,371 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:15,372 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 52331b41-7edf-44ba-a362-435152451dbe, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:15,373 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 52331b41-7edf-44ba-a362-435152451dbe, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:15,375 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 52331b41-7edf-44ba-a362-435152451dbe, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:16,861 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:16,862 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:16,863 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d1d8ed9a-9527-45d8-b614-dac559448418, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:16,864 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d1d8ed9a-9527-45d8-b614-dac559448418, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:16,866 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d1d8ed9a-9527-45d8-b614-dac559448418, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:17,206 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:17,207 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:17,208 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d8f0821c-4d7f-4e4e-b667-cb6ca6398abd, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:17,209 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d8f0821c-4d7f-4e4e-b667-cb6ca6398abd, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:17,211 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d8f0821c-4d7f-4e4e-b667-cb6ca6398abd, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:17,683 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:17,684 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957984, 'BlobCount': 384}}
2025-11-01 20:20:17,684 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:17,685 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ee5b4bf5-4e2f-4ddf-a47d-3eab39230125, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:17,686 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ee5b4bf5-4e2f-4ddf-a47d-3eab39230125, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:17,688 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ee5b4bf5-4e2f-4ddf-a47d-3eab39230125, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:19,281 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:19,282 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:19,283 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aa409180-3c91-44e7-a951-c48dd91a968c, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:19,285 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aa409180-3c91-44e7-a951-c48dd91a968c, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:19,287 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aa409180-3c91-44e7-a951-c48dd91a968c, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:19,629 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:19,630 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:19,631 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 92a6cd48-d2a9-4f03-b6b8-128ba745e06e, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:19,632 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 92a6cd48-d2a9-4f03-b6b8-128ba745e06e, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:19,634 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 92a6cd48-d2a9-4f03-b6b8-128ba745e06e, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:20,147 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:20,148 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1277728, 'BlobCount': 512}}
2025-11-01 20:20:20,149 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:20,150 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f756bee3-60c7-410b-888f-997ce5033840, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:20,151 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f756bee3-60c7-410b-888f-997ce5033840, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:20,153 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f756bee3-60c7-410b-888f-997ce5033840, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:21,622 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:21,623 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:21,624 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2fe6ed26-ae5d-491c-a37d-33a541f69b96, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:21,625 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2fe6ed26-ae5d-491c-a37d-33a541f69b96, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:21,627 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2fe6ed26-ae5d-491c-a37d-33a541f69b96, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:21,961 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:21,961 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:21,962 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ea3496d4-a754-422c-91fe-2943d7f068ac, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:21,963 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ea3496d4-a754-422c-91fe-2943d7f068ac, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:21,965 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ea3496d4-a754-422c-91fe-2943d7f068ac, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:22,532 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:22,533 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1597184, 'BlobCount': 640}}
2025-11-01 20:20:22,533 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:22,534 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be80ddea-bb1f-4ded-96f5-5942667de109, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:22,535 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be80ddea-bb1f-4ded-96f5-5942667de109, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:22,537 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be80ddea-bb1f-4ded-96f5-5942667de109, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:23,979 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:23,980 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:23,980 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a8cd9d25-656a-45f2-a1ed-3353b8c00f1f, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:23,981 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a8cd9d25-656a-45f2-a1ed-3353b8c00f1f, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:23,983 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a8cd9d25-656a-45f2-a1ed-3353b8c00f1f, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:24,339 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:24,340 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:24,340 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a6f2835c-35db-467f-9578-a9324ef2f738, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:24,341 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a6f2835c-35db-467f-9578-a9324ef2f738, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:24,343 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a6f2835c-35db-467f-9578-a9324ef2f738, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:25,015 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:25,016 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1916800, 'BlobCount': 768}}
2025-11-01 20:20:25,016 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:25,017 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aefd6819-1b0e-4ae9-a26e-de57d52e34c6, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:25,018 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aefd6819-1b0e-4ae9-a26e-de57d52e34c6, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:25,020 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aefd6819-1b0e-4ae9-a26e-de57d52e34c6, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:26,522 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:26,523 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:26,524 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6c00212d-fc6a-4cab-91e1-96f7f1d7b429, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:26,525 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6c00212d-fc6a-4cab-91e1-96f7f1d7b429, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:26,529 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6c00212d-fc6a-4cab-91e1-96f7f1d7b429, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:26,808 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:26,809 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:26,810 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c4af5aa2-a90c-4c5a-a145-221ffbd86738, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:26,811 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c4af5aa2-a90c-4c5a-a145-221ffbd86738, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:26,813 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c4af5aa2-a90c-4c5a-a145-221ffbd86738, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:27,372 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:27,373 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2236496, 'BlobCount': 896}}
2025-11-01 20:20:27,374 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:27,375 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 12e6a152-97d6-424e-81af-c9bcf0e8765e, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:27,376 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 12e6a152-97d6-424e-81af-c9bcf0e8765e, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:27,378 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 12e6a152-97d6-424e-81af-c9bcf0e8765e, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:28,933 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:28,934 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:28,935 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d7cdef92-040f-4bf9-a377-f89854141da9, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:28,936 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d7cdef92-040f-4bf9-a377-f89854141da9, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:28,937 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d7cdef92-040f-4bf9-a377-f89854141da9, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:29,293 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:29,294 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:29,294 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be33c60b-9e8d-470a-a8cf-0d54e971e597, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:29,295 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be33c60b-9e8d-470a-a8cf-0d54e971e597, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:29,297 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be33c60b-9e8d-470a-a8cf-0d54e971e597, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:29,972 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:29,972 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2556272, 'BlobCount': 1024}}
2025-11-01 20:20:29,973 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:29,973 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 158ad0e8-d125-4bb0-94c9-9a3c1e4cbfb9, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:29,974 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 158ad0e8-d125-4bb0-94c9-9a3c1e4cbfb9, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:29,976 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 158ad0e8-d125-4bb0-94c9-9a3c1e4cbfb9, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:31,467 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:31,468 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:31,469 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2acbf3c0-abe3-44e8-abde-95226d81b99a, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:31,470 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2acbf3c0-abe3-44e8-abde-95226d81b99a, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:31,473 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2acbf3c0-abe3-44e8-abde-95226d81b99a, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:31,887 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:31,887 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:31,888 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c965bdc5-416f-4d47-a339-6d57ebd24844, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:31,889 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c965bdc5-416f-4d47-a339-6d57ebd24844, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:31,890 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c965bdc5-416f-4d47-a339-6d57ebd24844, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:32,697 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:32,698 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2875584, 'BlobCount': 1152}}
2025-11-01 20:20:32,699 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:32,700 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f32deee6-97d0-4812-a1d7-fc205f8a7506, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:32,701 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f32deee6-97d0-4812-a1d7-fc205f8a7506, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:32,703 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f32deee6-97d0-4812-a1d7-fc205f8a7506, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:34,224 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:34,225 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:34,226 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ce7a99b6-a2fc-476b-ae40-1a1593d0d143, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:34,227 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ce7a99b6-a2fc-476b-ae40-1a1593d0d143, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:34,229 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ce7a99b6-a2fc-476b-ae40-1a1593d0d143, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:34,709 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:34,710 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:34,711 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fa7bf08d-d3cc-4a4b-9b32-aacd77827f68, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:34,712 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fa7bf08d-d3cc-4a4b-9b32-aacd77827f68, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:34,741 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fa7bf08d-d3cc-4a4b-9b32-aacd77827f68, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:35,554 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:35,555 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_2_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3195568, 'BlobCount': 1280}}
2025-11-01 20:20:35,556 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:35,557 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1e7a0ee0-2be5-4aeb-b715-d84cd2c779ff, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:35,558 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1e7a0ee0-2be5-4aeb-b715-d84cd2c779ff, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_2_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:35,560 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1e7a0ee0-2be5-4aeb-b715-d84cd2c779ff, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:37,059 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:37,060 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:37,061 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f216c684-3868-4e45-a342-e05b7e2ee2b0, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:37,062 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f216c684-3868-4e45-a342-e05b7e2ee2b0, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:37,064 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f216c684-3868-4e45-a342-e05b7e2ee2b0, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:37,517 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:37,518 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:37,518 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7dd02c8b-c108-47cb-8a55-0b17b0d7e48c, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:37,519 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7dd02c8b-c108-47cb-8a55-0b17b0d7e48c, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:37,520 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7dd02c8b-c108-47cb-8a55-0b17b0d7e48c, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:38,016 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:38,016 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:38,017 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a2331bc0-531e-494f-874f-df988ed46306, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:38,018 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a2331bc0-531e-494f-874f-df988ed46306, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:38,020 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a2331bc0-531e-494f-874f-df988ed46306, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:42,812 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:52,859 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:52,860 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d8fc544f-7a84-4ff6-9004-598f5b3c7bab, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:52,861 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d8fc544f-7a84-4ff6-9004-598f5b3c7bab, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:52,863 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d8fc544f-7a84-4ff6-9004-598f5b3c7bab, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:57,561 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:57,607 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_2_compression`, volumes `value` (8000000, 1597784)
2025-11-01 20:20:57,608 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:57,609 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fda5b1a2-be49-4ea7-9cfc-124eda3ba61c, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:57,610 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fda5b1a2-be49-4ea7-9cfc-124eda3ba61c, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" 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_2_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:57,611 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fda5b1a2-be49-4ea7-9cfc-124eda3ba61c, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:20:58,138 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:58,139 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:20:58,140 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 295f1cb9-47d3-40fb-bd82-42a4325b6ae2, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:20:58,140 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 295f1cb9-47d3-40fb-bd82-42a4325b6ae2, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:20:58,142 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 295f1cb9-47d3-40fb-bd82-42a4325b6ae2, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:21:03,558 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:21:13,606 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:21:13,608 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1471bf07-aecd-4b3b-885a-2725670fde77, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:21:13,609 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1471bf07-aecd-4b3b-885a-2725670fde77, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:21:13,612 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1471bf07-aecd-4b3b-885a-2725670fde77, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:21:16,338 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:21:26,369 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:21:26,371 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c9d841f2-0ce6-4602-9c01-c5b4b0e3a625, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:21:26,372 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c9d841f2-0ce6-4602-9c01-c5b4b0e3a625, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:21:26,380 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c9d841f2-0ce6-4602-9c01-c5b4b0e3a625, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:21:27,223 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:21:37,228 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:21:37,230 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cf15c83d-99f8-40c5-9e92-0d65cd5b5d26, ghrun-govtdgmox4.auto.internal:14442): creating call state
2025-11-01 20:21:37,231 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cf15c83d-99f8-40c5-9e92-0d65cd5b5d26, ghrun-govtdgmox4.auto.internal:14442): request = { session_id: "ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_2_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 20:21:37,233 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cf15c83d-99f8-40c5-9e92-0d65cd5b5d26, ghrun-govtdgmox4.auto.internal:14442): response = { <_MultiThreadedRendezvous object> }
2025-11-01 20:21:38,338 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTIxMmNhZmUtMzRlMDkwNjYtZDQwMTNmZTEtZjMzNWFiYzI=
2025-11-01 20:21:38,343 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_2_compression`, volumes `value` (8000000, 1465320)
2025-11-01 20:21:38,344 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_2_compression` 8092160 / 1465320: 5.522452433598122
2025-11-01 20:21:38,347 - INFO - ya.test - pytest_runtest_teardown: Test teardown
