2025-11-01 16:15:19,996 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:15:19,997 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_18_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 18]
2025-11-01 16:15:19,997 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-01 16:15:19,997 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-01 16:15:20,003 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-01 16:15:20,004 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-01 16:15:20,004 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ewjv/002541/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:15:20,006 - DEBUG - ya.test - execute: Command pid: 286799
2025-11-01 16:15:20,823 - DEBUG - ya.test - _save_outputs: Command (pid 286799) rc: 0
2025-11-01 16:15:20,823 - DEBUG - ya.test - _save_outputs: Command (pid 286799) elapsed time (sec): 0.8076930046081543
2025-11-01 16:15:20,824 - DEBUG - ya.test - _save_outputs: Command (pid 286799) idrss: 0
2025-11-01 16:15:20,824 - DEBUG - ya.test - _save_outputs: Command (pid 286799) inblock: 0
2025-11-01 16:15:20,824 - DEBUG - ya.test - _save_outputs: Command (pid 286799) isrss: 0
2025-11-01 16:15:20,824 - DEBUG - ya.test - _save_outputs: Command (pid 286799) ixrss: 0
2025-11-01 16:15:20,825 - DEBUG - ya.test - _save_outputs: Command (pid 286799) majflt: 0
2025-11-01 16:15:20,825 - DEBUG - ya.test - _save_outputs: Command (pid 286799) maxrss: 494640
2025-11-01 16:15:20,825 - DEBUG - ya.test - _save_outputs: Command (pid 286799) minflt: 45335
2025-11-01 16:15:20,826 - DEBUG - ya.test - _save_outputs: Command (pid 286799) msgrcv: 0
2025-11-01 16:15:20,826 - DEBUG - ya.test - _save_outputs: Command (pid 286799) msgsnd: 0
2025-11-01 16:15:20,826 - DEBUG - ya.test - _save_outputs: Command (pid 286799) nivcsw: 91
2025-11-01 16:15:20,827 - DEBUG - ya.test - _save_outputs: Command (pid 286799) nsignals: 0
2025-11-01 16:15:20,827 - DEBUG - ya.test - _save_outputs: Command (pid 286799) nswap: 0
2025-11-01 16:15:20,827 - DEBUG - ya.test - _save_outputs: Command (pid 286799) nvcsw: 14
2025-11-01 16:15:20,828 - DEBUG - ya.test - _save_outputs: Command (pid 286799) oublock: 8
2025-11-01 16:15:20,828 - DEBUG - ya.test - _save_outputs: Command (pid 286799) stime: 0.20379699999999998
2025-11-01 16:15:20,828 - DEBUG - ya.test - _save_outputs: Command (pid 286799) utime: 0.45027799999999996
2025-11-01 16:15:20,828 - DEBUG - ya.test - _save_outputs: Command (pid 286799) wtime: 0.81
2025-11-01 16:15:20,829 - DEBUG - ya.test - _save_outputs: Command (pid 286799) output:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jzwpcuvpwq
    Host information: 
        Linux ghrun-jzwpcuvpwq 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 16:15:20,829 - DEBUG - ya.test - _save_outputs: Command (pid 286799) errors:

2025-11-01 16:15:20,830 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jzwpcuvpwq
    Host information: 
        Linux ghrun-jzwpcuvpwq 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 16:15:20,931 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ewjv/002541/ydb/apps/ydbd/ydbd
2025-11-01 16:15:20,933 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/25746
2025-11-01 16:15:20,935 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/ewjv/002541/r3tmp/kikimr_cluster_mjymqdyo
2025-11-01 16:15:20,969 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ewjv/002541/ydb/apps/ydbd/ydbd
2025-11-01 16:15:20,970 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6520
2025-11-01 16:15:20,971 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19892
2025-11-01 16:15:20,971 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7828
2025-11-01 16:15:20,972 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/32504
2025-11-01 16:15:20,973 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ewjv/002541/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs"
2025-11-01 16:15:20,973 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/ewjv/002541/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/ewjv/002541/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_vwx1l465.log --grpc-port=6520 --mon-port=19892 --ic-port=25746
2025-11-01 16:15:20,974 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:6520/1, disk_size 68719476736
2025-11-01 16:15:20,974 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/ewjv/002541/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs"
2025-11-01 16:15:20,975 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/ewjv/002541/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/ewjv/002541/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_vwx1l465.log --grpc-port=6520 --mon-port=19892 --ic-port=25746
2025-11-01 16:15:20,976 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/ewjv/002541/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/ewjv/002541/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/ewjv/002541/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1/logfile_vwx1l465.log', '--grpc-port=6520', '--mon-port=19892', '--ic-port=25746')' in '/home/runner/.ya/build/build_root/ewjv/002541/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_18_compression-COMPRESSION.=.zstd.,.COMPRESSION_LEVEL.=.18/cluster/node_1' (no wait)
2025-11-01 16:15:20,980 - DEBUG - ya.test - execute: Command pid: 287007
2025-11-01 16:15:20,981 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:6520/1
2025-11-01 16:15:20,983 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-01 16:15:20,983 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-01 16:15:20,984 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ewjv/002541/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-01 16:15:20,986 - DEBUG - ya.test - execute: Command pid: 287008
2025-11-01 16:15:21,731 - DEBUG - ya.test - _save_outputs: Command (pid 287008) rc: 0
2025-11-01 16:15:21,732 - DEBUG - ya.test - _save_outputs: Command (pid 287008) elapsed time (sec): 0.7444889545440674
2025-11-01 16:15:21,732 - DEBUG - ya.test - _save_outputs: Command (pid 287008) idrss: 0
2025-11-01 16:15:21,732 - DEBUG - ya.test - _save_outputs: Command (pid 287008) inblock: 0
2025-11-01 16:15:21,733 - DEBUG - ya.test - _save_outputs: Command (pid 287008) isrss: 0
2025-11-01 16:15:21,733 - DEBUG - ya.test - _save_outputs: Command (pid 287008) ixrss: 0
2025-11-01 16:15:21,733 - DEBUG - ya.test - _save_outputs: Command (pid 287008) majflt: 0
2025-11-01 16:15:21,733 - DEBUG - ya.test - _save_outputs: Command (pid 287008) maxrss: 497408
2025-11-01 16:15:21,734 - DEBUG - ya.test - _save_outputs: Command (pid 287008) minflt: 45400
2025-11-01 16:15:21,734 - DEBUG - ya.test - _save_outputs: Command (pid 287008) msgrcv: 0
2025-11-01 16:15:21,734 - DEBUG - ya.test - _save_outputs: Command (pid 287008) msgsnd: 0
2025-11-01 16:15:21,734 - DEBUG - ya.test - _save_outputs: Command (pid 287008) nivcsw: 66
2025-11-01 16:15:21,735 - DEBUG - ya.test - _save_outputs: Command (pid 287008) nsignals: 0
2025-11-01 16:15:21,735 - DEBUG - ya.test - _save_outputs: Command (pid 287008) nswap: 0
2025-11-01 16:15:21,735 - DEBUG - ya.test - _save_outputs: Command (pid 287008) nvcsw: 16
2025-11-01 16:15:21,735 - DEBUG - ya.test - _save_outputs: Command (pid 287008) oublock: 8
2025-11-01 16:15:21,736 - DEBUG - ya.test - _save_outputs: Command (pid 287008) stime: 0.20610699999999998
2025-11-01 16:15:21,736 - DEBUG - ya.test - _save_outputs: Command (pid 287008) utime: 0.469524
2025-11-01 16:15:21,736 - DEBUG - ya.test - _save_outputs: Command (pid 287008) wtime: 0.747
2025-11-01 16:15:21,737 - DEBUG - ya.test - _save_outputs: Command (pid 287008) output:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-jzwpcuvpwq
    Host information: 
        Linux ghrun-jzwpcuvpwq 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 16:15:21,737 - DEBUG - ya.test - _save_outputs: Command (pid 287008) errors:

2025-11-01 16:15:21,737 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 34ddad56c8a7bb530ecf731d374c04f98d3d2aca
    Branch: 
    Author: azevaykin <145343289+azevaykin@users.noreply.github.com>
    Summary: Merge f53a17d638f4ee7a8f08354cd76c33a88c4925c5 into 5dbd26ce2068671edb74bb9f072505a44ea73d1e
2025-11-01 16:15:28,040 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ewjv/002541/ydb/apps/ydbd/ydbd
2025-11-01 16:15:28,041 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/ewjv/002541/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6520', '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: 25746\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-01 16:15:28,042 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-01 16:15:28,043 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-01 16:15:28,044 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ewjv/002541/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6520', '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: 25746\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-01 16:15:28,059 - DEBUG - ya.test - execute: Command pid: 290706
2025-11-01 16:15:29,622 - DEBUG - ya.test - _save_outputs: Command (pid 290706) rc: 0
2025-11-01 16:15:29,623 - DEBUG - ya.test - _save_outputs: Command (pid 290706) elapsed time (sec): 1.561091423034668
2025-11-01 16:15:29,623 - DEBUG - ya.test - _save_outputs: Command (pid 290706) idrss: 0
2025-11-01 16:15:29,623 - DEBUG - ya.test - _save_outputs: Command (pid 290706) inblock: 0
2025-11-01 16:15:29,624 - DEBUG - ya.test - _save_outputs: Command (pid 290706) isrss: 0
2025-11-01 16:15:29,624 - DEBUG - ya.test - _save_outputs: Command (pid 290706) ixrss: 0
2025-11-01 16:15:29,624 - DEBUG - ya.test - _save_outputs: Command (pid 290706) majflt: 0
2025-11-01 16:15:29,625 - DEBUG - ya.test - _save_outputs: Command (pid 290706) maxrss: 593232
2025-11-01 16:15:29,625 - DEBUG - ya.test - _save_outputs: Command (pid 290706) minflt: 51876
2025-11-01 16:15:29,625 - DEBUG - ya.test - _save_outputs: Command (pid 290706) msgrcv: 0
2025-11-01 16:15:29,625 - DEBUG - ya.test - _save_outputs: Command (pid 290706) msgsnd: 0
2025-11-01 16:15:29,626 - DEBUG - ya.test - _save_outputs: Command (pid 290706) nivcsw: 213
2025-11-01 16:15:29,626 - DEBUG - ya.test - _save_outputs: Command (pid 290706) nsignals: 0
2025-11-01 16:15:29,626 - DEBUG - ya.test - _save_outputs: Command (pid 290706) nswap: 0
2025-11-01 16:15:29,627 - DEBUG - ya.test - _save_outputs: Command (pid 290706) nvcsw: 341
2025-11-01 16:15:29,632 - DEBUG - ya.test - _save_outputs: Command (pid 290706) oublock: 8
2025-11-01 16:15:29,633 - DEBUG - ya.test - _save_outputs: Command (pid 290706) stime: 0.29023299999999996
2025-11-01 16:15:29,633 - DEBUG - ya.test - _save_outputs: Command (pid 290706) utime: 0.535907
2025-11-01 16:15:29,633 - DEBUG - ya.test - _save_outputs: Command (pid 290706) wtime: 1.577
2025-11-01 16:15:29,634 - DEBUG - ya.test - _save_outputs: Command (pid 290706) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-01 16:15:29,634 - DEBUG - ya.test - _save_outputs: Command (pid 290706) errors:

2025-11-01 16:15:29,636 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/ewjv/002541/ydb/apps/ydbd/ydbd
2025-11-01 16:15:29,636 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/ewjv/002541/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6520', '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 16:15:29,650 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-01 16:15:29,652 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-01 16:15:29,652 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/ewjv/002541/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6520', '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 16:15:29,663 - DEBUG - ya.test - execute: Command pid: 291218
2025-11-01 16:15:31,237 - DEBUG - ya.test - _save_outputs: Command (pid 291218) rc: 0
2025-11-01 16:15:31,238 - DEBUG - ya.test - _save_outputs: Command (pid 291218) elapsed time (sec): 1.5726544857025146
2025-11-01 16:15:31,238 - DEBUG - ya.test - _save_outputs: Command (pid 291218) idrss: 0
2025-11-01 16:15:31,239 - DEBUG - ya.test - _save_outputs: Command (pid 291218) inblock: 0
2025-11-01 16:15:31,239 - DEBUG - ya.test - _save_outputs: Command (pid 291218) isrss: 0
2025-11-01 16:15:31,239 - DEBUG - ya.test - _save_outputs: Command (pid 291218) ixrss: 0
2025-11-01 16:15:31,239 - DEBUG - ya.test - _save_outputs: Command (pid 291218) majflt: 0
2025-11-01 16:15:31,240 - DEBUG - ya.test - _save_outputs: Command (pid 291218) maxrss: 593308
2025-11-01 16:15:31,240 - DEBUG - ya.test - _save_outputs: Command (pid 291218) minflt: 51646
2025-11-01 16:15:31,240 - DEBUG - ya.test - _save_outputs: Command (pid 291218) msgrcv: 0
2025-11-01 16:15:31,240 - DEBUG - ya.test - _save_outputs: Command (pid 291218) msgsnd: 0
2025-11-01 16:15:31,241 - DEBUG - ya.test - _save_outputs: Command (pid 291218) nivcsw: 246
2025-11-01 16:15:31,241 - DEBUG - ya.test - _save_outputs: Command (pid 291218) nsignals: 0
2025-11-01 16:15:31,241 - DEBUG - ya.test - _save_outputs: Command (pid 291218) nswap: 0
2025-11-01 16:15:31,242 - DEBUG - ya.test - _save_outputs: Command (pid 291218) nvcsw: 360
2025-11-01 16:15:31,242 - DEBUG - ya.test - _save_outputs: Command (pid 291218) oublock: 8
2025-11-01 16:15:31,244 - DEBUG - ya.test - _save_outputs: Command (pid 291218) stime: 0.295831
2025-11-01 16:15:31,244 - DEBUG - ya.test - _save_outputs: Command (pid 291218) utime: 0.607799
2025-11-01 16:15:31,245 - DEBUG - ya.test - _save_outputs: Command (pid 291218) wtime: 1.584
2025-11-01 16:15:31,245 - DEBUG - ya.test - _save_outputs: Command (pid 291218) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-01 16:15:31,245 - DEBUG - ya.test - _save_outputs: Command (pid 291218) errors:

2025-11-01 16:15:31,246 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-01 16:15:31,468 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-01 16:15:31,589 - 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 16:15:31,603 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:15:31,604 - 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 16:15:31,663 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:15:31,664 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, a6fd2db3-493f-4a45-9e79-687bdaa66186, localhost:6520): creating call state
2025-11-01 16:15:31,665 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, a6fd2db3-493f-4a45-9e79-687bdaa66186, localhost:6520): request = { database: "/Root" }
2025-11-01 16:15:31,677 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, a6fd2db3-493f-4a45-9e79-687bdaa66186, localhost:6520): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jzwpcuvpwq.auto.internal" port: 6520 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 16:15:31,679 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jzwpcuvpwq.auto.internal:6520, location 1, ssl: False>]>
2025-11-01 16:15:31,684 - 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 16:15:31,735 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6520
2025-11-01 16:15:31,736 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-01 16:15:31,738 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, c678e28f-c30c-4678-b43d-f389bb129ba0, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:31,738 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, c678e28f-c30c-4678-b43d-f389bb129ba0, ghrun-jzwpcuvpwq.auto.internal:6520): request = {  }
2025-11-01 16:15:31,749 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, c678e28f-c30c-4678-b43d-f389bb129ba0, ghrun-jzwpcuvpwq.auto.internal:6520): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" node_id: 1 }
2025-11-01 16:15:31,750 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 1a9e0a1e-7322-4bf5-ada9-bb21f01c584c, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:31,750 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 1a9e0a1e-7322-4bf5-ada9-bb21f01c584c, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" }
2025-11-01 16:15:31,756 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 1a9e0a1e-7322-4bf5-ada9-bb21f01c584c, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:31,798 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:31,799 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 73e50e9f-f788-452b-b31a-6ee3ad326251, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:31,800 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 73e50e9f-f788-452b-b31a-6ee3ad326251, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:31,802 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 73e50e9f-f788-452b-b31a-6ee3ad326251, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:36,399 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:36,400 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-01 16:15:36,401 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:36,402 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fc8a00be-dc64-448e-b9e3-429477c134d4, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:36,403 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fc8a00be-dc64-448e-b9e3-429477c134d4, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:36,404 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fc8a00be-dc64-448e-b9e3-429477c134d4, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:37,416 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:37,417 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:37,418 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 26302cdf-fe54-4d18-83fe-eda8298b9537, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:37,419 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 26302cdf-fe54-4d18-83fe-eda8298b9537, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:37,427 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 26302cdf-fe54-4d18-83fe-eda8298b9537, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:38,918 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:38,919 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:38,920 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1b215315-9d91-4833-8126-8b8c04de6ea3, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:38,921 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1b215315-9d91-4833-8126-8b8c04de6ea3, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:38,938 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1b215315-9d91-4833-8126-8b8c04de6ea3, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:40,208 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:40,209 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:40,210 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0ec36099-7763-4a28-8481-b5fc720a6530, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:40,211 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0ec36099-7763-4a28-8481-b5fc720a6530, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:40,213 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0ec36099-7763-4a28-8481-b5fc720a6530, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:41,892 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:41,893 - 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 16:15:41,893 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:41,894 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 432254f7-041a-4671-82ea-67482cb1ea34, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:41,896 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 432254f7-041a-4671-82ea-67482cb1ea34, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:41,898 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 432254f7-041a-4671-82ea-67482cb1ea34, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:43,006 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:43,007 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:43,008 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c62ace18-ef23-4202-8039-68cc457e1f37, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:43,009 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c62ace18-ef23-4202-8039-68cc457e1f37, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:43,010 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c62ace18-ef23-4202-8039-68cc457e1f37, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:43,445 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:43,446 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:43,447 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 83e04c2e-982b-4b65-8b14-09eb7d005c6a, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:43,448 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 83e04c2e-982b-4b65-8b14-09eb7d005c6a, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:43,450 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 83e04c2e-982b-4b65-8b14-09eb7d005c6a, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:44,032 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:44,033 - 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 16:15:44,034 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:44,035 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dceee815-72c1-487c-88aa-b7d7077cc250, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:44,036 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dceee815-72c1-487c-88aa-b7d7077cc250, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:44,038 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dceee815-72c1-487c-88aa-b7d7077cc250, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:45,325 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:45,326 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:45,327 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 42ba5e67-69f1-4357-a3c7-535aa3cde2e8, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:45,328 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 42ba5e67-69f1-4357-a3c7-535aa3cde2e8, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:45,329 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 42ba5e67-69f1-4357-a3c7-535aa3cde2e8, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:45,764 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:45,765 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:45,766 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a89c5c7c-c8e0-477a-b2be-54ec85bdfe15, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:45,767 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a89c5c7c-c8e0-477a-b2be-54ec85bdfe15, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:45,769 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a89c5c7c-c8e0-477a-b2be-54ec85bdfe15, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:46,524 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:46,525 - 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 16:15:46,525 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:46,526 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f04fdba8-cbfb-4dda-a2f7-20bda9f9bc6f, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:46,527 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f04fdba8-cbfb-4dda-a2f7-20bda9f9bc6f, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:46,529 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f04fdba8-cbfb-4dda-a2f7-20bda9f9bc6f, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:47,781 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:47,782 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:47,783 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b79aa811-8f10-466d-aa57-ac39a2ef0a27, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:47,784 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b79aa811-8f10-466d-aa57-ac39a2ef0a27, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:47,787 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b79aa811-8f10-466d-aa57-ac39a2ef0a27, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:48,508 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:48,509 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:48,510 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 717c496a-ef02-45d6-8afd-e793e89770af, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:48,511 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 717c496a-ef02-45d6-8afd-e793e89770af, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:48,513 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 717c496a-ef02-45d6-8afd-e793e89770af, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:49,472 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:49,473 - 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 16:15:49,473 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:49,474 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 34979e81-e2ed-47c6-9c73-0c2a6f66e9db, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:49,474 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 34979e81-e2ed-47c6-9c73-0c2a6f66e9db, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:49,479 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 34979e81-e2ed-47c6-9c73-0c2a6f66e9db, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:50,927 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:50,928 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:50,929 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e14d24e-7e70-4a35-8f72-e04628e7db9a, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:50,930 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e14d24e-7e70-4a35-8f72-e04628e7db9a, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:50,931 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e14d24e-7e70-4a35-8f72-e04628e7db9a, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:51,437 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:51,438 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:51,439 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e53aa54b-b7aa-4e48-a072-31092f334ff7, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:51,440 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e53aa54b-b7aa-4e48-a072-31092f334ff7, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:51,443 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e53aa54b-b7aa-4e48-a072-31092f334ff7, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:52,189 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:52,190 - 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 16:15:52,191 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:52,192 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b56cc24c-85fe-4b88-8334-7191a07d80f5, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:52,193 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b56cc24c-85fe-4b88-8334-7191a07d80f5, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:52,195 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b56cc24c-85fe-4b88-8334-7191a07d80f5, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:53,560 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:53,561 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:53,562 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c480e014-d58f-42cf-992a-435fdbfb78a3, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:53,562 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c480e014-d58f-42cf-992a-435fdbfb78a3, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:53,564 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c480e014-d58f-42cf-992a-435fdbfb78a3, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:54,251 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:54,252 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:54,253 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cb199c3d-7022-4d06-92a0-9dea24323a3d, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:54,254 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cb199c3d-7022-4d06-92a0-9dea24323a3d, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:54,266 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cb199c3d-7022-4d06-92a0-9dea24323a3d, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:54,969 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:54,970 - 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 16:15:54,970 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:54,971 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 97a0d614-557d-4741-8b6a-dcea946e637c, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:54,972 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 97a0d614-557d-4741-8b6a-dcea946e637c, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:54,977 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 97a0d614-557d-4741-8b6a-dcea946e637c, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:56,581 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:56,581 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:56,582 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b87bac96-513d-4417-91dd-1aba27791c8e, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:56,583 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b87bac96-513d-4417-91dd-1aba27791c8e, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:56,585 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b87bac96-513d-4417-91dd-1aba27791c8e, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:57,155 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:57,156 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:57,157 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 695dbdc4-3da3-422d-95e6-12642178b8e9, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:57,158 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 695dbdc4-3da3-422d-95e6-12642178b8e9, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:57,177 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 695dbdc4-3da3-422d-95e6-12642178b8e9, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:58,515 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:58,515 - 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 16:15:58,516 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:58,517 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4eae2fda-7daf-4e24-911d-e60cc327314b, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:58,518 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4eae2fda-7daf-4e24-911d-e60cc327314b, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:58,523 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4eae2fda-7daf-4e24-911d-e60cc327314b, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:15:59,949 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:59,949 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:15:59,950 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5d74af7a-a6d9-43cb-9795-324d309e9c28, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:15:59,951 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5d74af7a-a6d9-43cb-9795-324d309e9c28, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:15:59,957 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5d74af7a-a6d9-43cb-9795-324d309e9c28, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:00,699 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:00,700 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:00,700 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1409727c-5748-458d-a9c1-3de72775f3c0, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:16:00,701 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1409727c-5748-458d-a9c1-3de72775f3c0, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:16:00,703 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1409727c-5748-458d-a9c1-3de72775f3c0, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:01,784 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:01,785 - 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 16:16:01,785 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:01,786 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, afb368da-39b7-4373-a8e4-e4ff2a7566fc, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:16:01,789 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, afb368da-39b7-4373-a8e4-e4ff2a7566fc, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:16:01,790 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, afb368da-39b7-4373-a8e4-e4ff2a7566fc, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:03,382 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:03,383 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:03,384 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5d4c1074-f81b-49ae-8b4f-3995edece2fe, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:16:03,385 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5d4c1074-f81b-49ae-8b4f-3995edece2fe, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:16:03,387 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5d4c1074-f81b-49ae-8b4f-3995edece2fe, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:03,832 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:03,833 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:03,833 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f072c0fc-0c74-4bfb-a111-5e4df40f845e, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:16:03,834 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f072c0fc-0c74-4bfb-a111-5e4df40f845e, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:16:03,836 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f072c0fc-0c74-4bfb-a111-5e4df40f845e, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:04,825 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:04,826 - 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 16:16:04,827 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:04,828 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 04e4e8af-6902-4c74-be51-7dc29c6c229a, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:16:04,829 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 04e4e8af-6902-4c74-be51-7dc29c6c229a, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:16:04,830 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 04e4e8af-6902-4c74-be51-7dc29c6c229a, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:06,446 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:06,447 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:06,448 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cbe1c9ef-cde2-4c4f-9f96-2fca44513b32, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:16:06,449 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cbe1c9ef-cde2-4c4f-9f96-2fca44513b32, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:16:06,455 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cbe1c9ef-cde2-4c4f-9f96-2fca44513b32, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:06,940 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:06,941 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:06,942 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5756b657-5e56-40d0-93cd-6db5829e035a, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:16:06,943 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5756b657-5e56-40d0-93cd-6db5829e035a, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:16:06,957 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5756b657-5e56-40d0-93cd-6db5829e035a, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:07,695 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:07,696 - 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 16:16:07,697 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:07,698 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0898a95d-30e1-4c53-965c-9f326c325b8c, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:16:07,699 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0898a95d-30e1-4c53-965c-9f326c325b8c, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:16:07,708 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0898a95d-30e1-4c53-965c-9f326c325b8c, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:10,059 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:10,060 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:10,061 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a8248273-2ee1-4bc3-8439-b3de9a490b17, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:16:10,062 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a8248273-2ee1-4bc3-8439-b3de9a490b17, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:16:10,072 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a8248273-2ee1-4bc3-8439-b3de9a490b17, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:10,633 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:10,634 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:10,635 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 22e0cf5d-3be3-4c32-abd4-a7028dfe5978, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:16:10,636 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 22e0cf5d-3be3-4c32-abd4-a7028dfe5978, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:16:10,637 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 22e0cf5d-3be3-4c32-abd4-a7028dfe5978, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:11,167 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:11,168 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:11,168 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 979ac00f-481a-4180-b4f1-a59406284325, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:16:11,169 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 979ac00f-481a-4180-b4f1-a59406284325, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:16:11,173 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 979ac00f-481a-4180-b4f1-a59406284325, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:15,930 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:25,975 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:25,977 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b8949c26-3755-4375-a372-69ed322ca593, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:16:25,978 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b8949c26-3755-4375-a372-69ed322ca593, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:16:25,979 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b8949c26-3755-4375-a372-69ed322ca593, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:33,358 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:33,479 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-01 16:16:33,480 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:33,481 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 07fe81d8-af3d-4579-a987-a1142ab448e7, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:16:33,482 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 07fe81d8-af3d-4579-a987-a1142ab448e7, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:16:33,491 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 07fe81d8-af3d-4579-a987-a1142ab448e7, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:34,975 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:16:34,976 - 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 16:16:35,028 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:16:35,028 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, fc7b1785-bcd5-49ea-8339-1856d26c979d, localhost:6520): creating call state
2025-11-01 16:16:35,029 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, fc7b1785-bcd5-49ea-8339-1856d26c979d, localhost:6520): request = { database: "/Root" }
2025-11-01 16:16:35,159 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, fc7b1785-bcd5-49ea-8339-1856d26c979d, localhost:6520): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jzwpcuvpwq.auto.internal" port: 6520 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 16:16:35,160 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jzwpcuvpwq.auto.internal:6520, location 1, ssl: False>]>
2025-11-01 16:16:35,161 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6520
2025-11-01 16:16:42,624 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:52,737 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:16:52,738 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f4102751-86a6-45fd-9a45-28cf9d867412, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:16:52,739 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f4102751-86a6-45fd-9a45-28cf9d867412, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:16:52,741 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f4102751-86a6-45fd-9a45-28cf9d867412, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:16:54,050 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:04,055 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:04,056 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a3db2069-799f-4873-92f8-912b097e27cd, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:04,057 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a3db2069-799f-4873-92f8-912b097e27cd, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:17:04,058 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a3db2069-799f-4873-92f8-912b097e27cd, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:05,384 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:05,398 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-11-01 16:17:05,399 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:05,400 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9007b3b5-a591-4d19-b7c3-30216435d960, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:05,408 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9007b3b5-a591-4d19-b7c3-30216435d960, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" 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 16:17:05,410 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9007b3b5-a591-4d19-b7c3-30216435d960, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:05,972 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:05,978 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_18_compression-COMPRESSION = "zstd", COMPRESSION_LEVEL = 18])
2025-11-01 16:17:05,979 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:05,979 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a88ba486-213f-42fa-9d69-5540dac62765, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:05,980 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a88ba486-213f-42fa-9d69-5540dac62765, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_18_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\", COMPRESSION_LEVEL = 18)\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:05,982 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a88ba486-213f-42fa-9d69-5540dac62765, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:12,166 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:12,166 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_18_compression created
2025-11-01 16:17:12,167 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:12,168 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ead34dfc-5149-47e2-94cb-a91960a80155, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:12,169 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ead34dfc-5149-47e2-94cb-a91960a80155, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_18_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:12,170 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ead34dfc-5149-47e2-94cb-a91960a80155, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:13,577 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:13,578 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:13,579 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ce40716b-6892-46e5-9159-c158cacb1779, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:13,580 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ce40716b-6892-46e5-9159-c158cacb1779, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 0;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:13,593 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ce40716b-6892-46e5-9159-c158cacb1779, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:20,884 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:20,885 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:20,885 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a91734d0-ac28-4a99-83a8-3854d5e4dda3, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:20,886 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a91734d0-ac28-4a99-83a8-3854d5e4dda3, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:20,891 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a91734d0-ac28-4a99-83a8-3854d5e4dda3, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:22,104 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:22,105 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:22,106 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 01e7fc42-9cd0-41c6-9b7a-ce20232e60ab, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:22,107 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 01e7fc42-9cd0-41c6-9b7a-ce20232e60ab, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:22,112 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 01e7fc42-9cd0-41c6-9b7a-ce20232e60ab, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:24,167 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:24,168 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318528, 'BlobCount': 128}}
2025-11-01 16:17:24,169 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:24,169 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ed7143b1-ae6e-4143-a058-767400c7fed0, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:24,170 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ed7143b1-ae6e-4143-a058-767400c7fed0, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 100000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:24,182 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ed7143b1-ae6e-4143-a058-767400c7fed0, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:30,962 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:30,963 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:30,979 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e8b87717-2064-4ef6-99c3-08c26d66fd70, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:30,980 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e8b87717-2064-4ef6-99c3-08c26d66fd70, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:30,981 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e8b87717-2064-4ef6-99c3-08c26d66fd70, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:31,371 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:31,372 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:31,373 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3fb2b618-8254-4ba1-8863-7d9c3d200772, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:31,374 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3fb2b618-8254-4ba1-8863-7d9c3d200772, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:31,376 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3fb2b618-8254-4ba1-8863-7d9c3d200772, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:31,891 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:31,892 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 637920, 'BlobCount': 256}}
2025-11-01 16:17:31,893 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:31,893 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 62c551c8-8cc6-4a8d-b19d-08d9d30786c9, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:31,894 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 62c551c8-8cc6-4a8d-b19d-08d9d30786c9, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 200000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:31,896 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 62c551c8-8cc6-4a8d-b19d-08d9d30786c9, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:37,828 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:37,829 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:37,830 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4b432953-280b-49e9-aa46-c542fc5f26f2, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:37,831 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4b432953-280b-49e9-aa46-c542fc5f26f2, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:37,832 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4b432953-280b-49e9-aa46-c542fc5f26f2, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:38,277 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:38,278 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:38,278 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ca4882b4-bf91-4f52-9d59-aa678d27b5d5, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:38,279 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ca4882b4-bf91-4f52-9d59-aa678d27b5d5, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:38,292 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ca4882b4-bf91-4f52-9d59-aa678d27b5d5, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:38,917 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:38,918 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 956816, 'BlobCount': 384}}
2025-11-01 16:17:38,919 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:38,919 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 544564a8-57f2-4d09-a60d-ab402a579f27, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:38,920 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 544564a8-57f2-4d09-a60d-ab402a579f27, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 300000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:38,922 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 544564a8-57f2-4d09-a60d-ab402a579f27, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:44,904 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:44,905 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:44,906 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bdaaf0c8-0609-4439-b14a-b3c63b40ff5e, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:44,907 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bdaaf0c8-0609-4439-b14a-b3c63b40ff5e, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:44,908 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bdaaf0c8-0609-4439-b14a-b3c63b40ff5e, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:45,479 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:45,479 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:45,480 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 26259e73-3c21-487d-b858-7bee488aad04, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:45,481 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 26259e73-3c21-487d-b858-7bee488aad04, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:45,483 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 26259e73-3c21-487d-b858-7bee488aad04, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:46,205 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:46,205 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1276240, 'BlobCount': 512}}
2025-11-01 16:17:46,206 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:46,207 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0e30e8b3-92f9-49e0-9c50-3b10da310059, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:46,208 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0e30e8b3-92f9-49e0-9c50-3b10da310059, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 400000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:46,211 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0e30e8b3-92f9-49e0-9c50-3b10da310059, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:53,632 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:53,633 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:53,634 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c2ab6c21-3e29-4fe0-8605-68ce4c5ec9ea, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:53,635 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c2ab6c21-3e29-4fe0-8605-68ce4c5ec9ea, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:53,644 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c2ab6c21-3e29-4fe0-8605-68ce4c5ec9ea, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:54,037 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:54,038 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:54,038 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 81caf2f5-f84b-41a0-83ef-b95e3845606f, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:54,044 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 81caf2f5-f84b-41a0-83ef-b95e3845606f, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:54,046 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 81caf2f5-f84b-41a0-83ef-b95e3845606f, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:17:55,112 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:55,112 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1595136, 'BlobCount': 640}}
2025-11-01 16:17:55,113 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:17:55,114 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3bc3ae45-c411-441d-bf69-713b74451614, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:17:55,115 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3bc3ae45-c411-441d-bf69-713b74451614, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 500000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:17:55,125 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3bc3ae45-c411-441d-bf69-713b74451614, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:02,317 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:02,318 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:02,319 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1c380bba-cb94-42c5-b106-7c7d0faedba5, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:02,320 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1c380bba-cb94-42c5-b106-7c7d0faedba5, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:02,323 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1c380bba-cb94-42c5-b106-7c7d0faedba5, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:02,761 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:02,762 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:02,762 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a36e18a1-53e7-4ade-a762-acab3f4c0328, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:02,763 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a36e18a1-53e7-4ade-a762-acab3f4c0328, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:02,775 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a36e18a1-53e7-4ade-a762-acab3f4c0328, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:03,669 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:03,670 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1914416, 'BlobCount': 768}}
2025-11-01 16:18:03,670 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:03,671 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 32ab58ff-09e9-4d92-aeab-e5e5523c74f2, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:03,672 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 32ab58ff-09e9-4d92-aeab-e5e5523c74f2, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 600000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:03,677 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 32ab58ff-09e9-4d92-aeab-e5e5523c74f2, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:11,919 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:11,920 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:11,921 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 66d22fda-ca1f-431f-85cc-fa06c6a528dc, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:11,921 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 66d22fda-ca1f-431f-85cc-fa06c6a528dc, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:11,923 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 66d22fda-ca1f-431f-85cc-fa06c6a528dc, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:12,333 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:12,334 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:12,335 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e377946a-88a7-44a7-9a0b-147420dae61f, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:12,336 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e377946a-88a7-44a7-9a0b-147420dae61f, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:12,340 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e377946a-88a7-44a7-9a0b-147420dae61f, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:13,220 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:13,221 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2233456, 'BlobCount': 896}}
2025-11-01 16:18:13,221 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:13,222 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 41dee8a1-1e24-4708-b7c5-04afd28afe21, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:13,224 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 41dee8a1-1e24-4708-b7c5-04afd28afe21, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 700000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:13,225 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 41dee8a1-1e24-4708-b7c5-04afd28afe21, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:21,279 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:21,280 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:21,281 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0f8c8fe5-40a4-4f20-a880-a8ccb69aaa5f, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:21,281 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0f8c8fe5-40a4-4f20-a880-a8ccb69aaa5f, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:21,283 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0f8c8fe5-40a4-4f20-a880-a8ccb69aaa5f, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:22,027 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:22,028 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:22,029 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1ab37139-e67b-46dd-ba69-e56282838da8, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:22,030 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1ab37139-e67b-46dd-ba69-e56282838da8, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:22,031 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1ab37139-e67b-46dd-ba69-e56282838da8, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:22,860 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:22,861 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2552960, 'BlobCount': 1024}}
2025-11-01 16:18:22,861 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:22,862 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 145a1d63-a31d-42db-8814-fc888c020431, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:22,867 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 145a1d63-a31d-42db-8814-fc888c020431, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 800000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:22,869 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 145a1d63-a31d-42db-8814-fc888c020431, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:27,856 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:27,857 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:27,858 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8f66e15d-9f87-4330-8f89-957e7cfcd199, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:27,859 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8f66e15d-9f87-4330-8f89-957e7cfcd199, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:27,867 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8f66e15d-9f87-4330-8f89-957e7cfcd199, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:28,536 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:28,537 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:28,538 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8680b720-4374-43a3-9fdb-fd4beebcbd13, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:28,539 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8680b720-4374-43a3-9fdb-fd4beebcbd13, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:28,541 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8680b720-4374-43a3-9fdb-fd4beebcbd13, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:29,348 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:29,349 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2871840, 'BlobCount': 1152}}
2025-11-01 16:18:29,349 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:29,350 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 46cd65a4-029f-4d1a-9538-8426b8c6245f, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:29,355 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 46cd65a4-029f-4d1a-9538-8426b8c6245f, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                $row_count = 100000;\n                $prev_count = 900000;\n                $rows= ListMap(ListFromRange(0, $row_count), ($i) -> {\n                    return <|\n                        value: $i + $prev_count,\n                        value1: $i + $prev_count,\n                    |>;\n                });\n                UPSERT INTO `/Root/all_compression/all_supported_compression/zstd_18_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:29,357 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 46cd65a4-029f-4d1a-9538-8426b8c6245f, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:29,641 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-01 16:18:29,642 - 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 16:18:29,656 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-01 16:18:29,657 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 7752086c-38c0-42c3-ac76-c1587f9dbbbd, localhost:6520): creating call state
2025-11-01 16:18:29,657 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 7752086c-38c0-42c3-ac76-c1587f9dbbbd, localhost:6520): request = { database: "/Root" }
2025-11-01 16:18:29,662 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 7752086c-38c0-42c3-ac76-c1587f9dbbbd, localhost:6520): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-jzwpcuvpwq.auto.internal" port: 6520 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 16:18:29,663 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-jzwpcuvpwq.auto.internal:6520, location 1, ssl: False>]>
2025-11-01 16:18:29,664 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6520
2025-11-01 16:18:33,781 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:33,782 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:33,782 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a54e5409-17c7-4ed6-8b26-11c41468a4c0, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:33,783 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a54e5409-17c7-4ed6-8b26-11c41468a4c0, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:33,786 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a54e5409-17c7-4ed6-8b26-11c41468a4c0, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:34,189 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:34,190 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:34,191 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c06a1044-7c5a-4156-8d6f-705536ac6797, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:34,192 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c06a1044-7c5a-4156-8d6f-705536ac6797, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n            select TierName, count(*) as Portions, sum(BlobSize) as BlobSize, sum(BlobCount) as BlobCount from (\n                select TabletId, PortionId, TierName, sum(BlobRangeSize) as BlobSize, count(*) as BlobCount from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:34,193 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c06a1044-7c5a-4156-8d6f-705536ac6797, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:35,005 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:35,006 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_18_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3191408, 'BlobCount': 1280}}
2025-11-01 16:18:35,006 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:35,007 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b317936b-febf-4ebc-9a59-e2084e70a14e, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:35,008 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b317936b-febf-4ebc-9a59-e2084e70a14e, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_18_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:35,010 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b317936b-febf-4ebc-9a59-e2084e70a14e, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:36,635 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:36,636 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:36,636 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f86ca83c-6ab9-4396-b6c1-aa07454f14c1, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:36,637 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f86ca83c-6ab9-4396-b6c1-aa07454f14c1, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:36,639 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f86ca83c-6ab9-4396-b6c1-aa07454f14c1, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:37,134 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:37,135 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:37,136 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 748b68f3-7a70-443f-8691-a3c02ee86272, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:37,137 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 748b68f3-7a70-443f-8691-a3c02ee86272, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:37,138 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 748b68f3-7a70-443f-8691-a3c02ee86272, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:37,579 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:37,580 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:37,580 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c89ddc00-e5ef-4fd4-9c3e-adc549583d7f, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:37,581 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c89ddc00-e5ef-4fd4-9c3e-adc549583d7f, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:37,583 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c89ddc00-e5ef-4fd4-9c3e-adc549583d7f, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:42,120 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:52,173 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:52,173 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b3ae066c-c358-4f47-b786-3b7425705086, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:52,174 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b3ae066c-c358-4f47-b786-3b7425705086, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:52,175 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b3ae066c-c358-4f47-b786-3b7425705086, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:56,006 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:56,034 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_18_compression`, volumes `value` (8000000, 1595704)
2025-11-01 16:18:56,036 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:56,036 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 86ba3e4c-9a02-4dcd-85e0-da616b6f6648, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:56,037 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 86ba3e4c-9a02-4dcd-85e0-da616b6f6648, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select TierName, sum(Rows) as Rows, count(*) as Portions from `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:56,038 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 86ba3e4c-9a02-4dcd-85e0-da616b6f6648, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:18:56,318 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:56,318 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:18:56,340 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a17eb321-c3bf-4906-8699-42a7de5bf269, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:18:56,348 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a17eb321-c3bf-4906-8699-42a7de5bf269, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:18:56,349 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a17eb321-c3bf-4906-8699-42a7de5bf269, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:00,036 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:19:10,075 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:19:10,076 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 40dd19dc-8396-489a-a00f-691504bf77ed, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:19:10,077 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 40dd19dc-8396-489a-a00f-691504bf77ed, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:10,078 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 40dd19dc-8396-489a-a00f-691504bf77ed, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:13,317 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:19:23,354 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:19:23,355 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 18465f30-b922-4a06-bf82-8e32e4582c07, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:19:23,368 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 18465f30-b922-4a06-bf82-8e32e4582c07, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:23,369 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 18465f30-b922-4a06-bf82-8e32e4582c07, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:25,321 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:19:35,351 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:19:35,352 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e07fff0b-53cd-4c3a-a1b6-f8acbe9cc39f, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:19:35,353 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e07fff0b-53cd-4c3a-a1b6-f8acbe9cc39f, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:35,354 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e07fff0b-53cd-4c3a-a1b6-f8acbe9cc39f, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:36,108 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:19:46,115 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:19:46,116 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2ddbdb8a-5436-475e-8602-ad2861e5e36b, ghrun-jzwpcuvpwq.auto.internal:6520): creating call state
2025-11-01 16:19:46,116 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2ddbdb8a-5436-475e-8602-ad2861e5e36b, ghrun-jzwpcuvpwq.auto.internal:6520): request = { session_id: "ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_18_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-01 16:19:46,118 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2ddbdb8a-5436-475e-8602-ad2861e5e36b, ghrun-jzwpcuvpwq.auto.internal:6520): response = { <_MultiThreadedRendezvous object> }
2025-11-01 16:19:46,665 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTE3N2IwMjUtYjE1MDBkYTktNzU3ZTI3MTQtY2M4Mzg1NDQ=
2025-11-01 16:19:46,669 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_18_compression`, volumes `value` (8000000, 1497984)
2025-11-01 16:19:46,669 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_18_compression` 8092160 / 1497984: 5.402033666581218
2025-11-01 16:19:46,672 - INFO - ya.test - pytest_runtest_teardown: Test teardown
