2025-11-06 10:13:39,268 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-06 10:13:39,269 - INFO - ya.test - pytest_runtest_setup: test_all_supported_compression[zstd_compression-COMPRESSION = "zstd"]
2025-11-06 10:13:39,269 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-06 10:13:39,270 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-06 10:13:39,272 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-06 10:13:39,272 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-06 10:13:39,273 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/2dji/004544/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-06 10:13:39,275 - DEBUG - ya.test - execute: Command pid: 487230
2025-11-06 10:13:40,075 - DEBUG - ya.test - _save_outputs: Command (pid 487230) rc: 0
2025-11-06 10:13:40,076 - DEBUG - ya.test - _save_outputs: Command (pid 487230) elapsed time (sec): 0.7988905906677246
2025-11-06 10:13:40,076 - DEBUG - ya.test - _save_outputs: Command (pid 487230) idrss: 0
2025-11-06 10:13:40,076 - DEBUG - ya.test - _save_outputs: Command (pid 487230) inblock: 0
2025-11-06 10:13:40,076 - DEBUG - ya.test - _save_outputs: Command (pid 487230) isrss: 0
2025-11-06 10:13:40,077 - DEBUG - ya.test - _save_outputs: Command (pid 487230) ixrss: 0
2025-11-06 10:13:40,077 - DEBUG - ya.test - _save_outputs: Command (pid 487230) majflt: 0
2025-11-06 10:13:40,077 - DEBUG - ya.test - _save_outputs: Command (pid 487230) maxrss: 492960
2025-11-06 10:13:40,078 - DEBUG - ya.test - _save_outputs: Command (pid 487230) minflt: 45481
2025-11-06 10:13:40,078 - DEBUG - ya.test - _save_outputs: Command (pid 487230) msgrcv: 0
2025-11-06 10:13:40,078 - DEBUG - ya.test - _save_outputs: Command (pid 487230) msgsnd: 0
2025-11-06 10:13:40,079 - DEBUG - ya.test - _save_outputs: Command (pid 487230) nivcsw: 91
2025-11-06 10:13:40,079 - DEBUG - ya.test - _save_outputs: Command (pid 487230) nsignals: 0
2025-11-06 10:13:40,079 - DEBUG - ya.test - _save_outputs: Command (pid 487230) nswap: 0
2025-11-06 10:13:40,079 - DEBUG - ya.test - _save_outputs: Command (pid 487230) nvcsw: 14
2025-11-06 10:13:40,080 - DEBUG - ya.test - _save_outputs: Command (pid 487230) oublock: 8
2025-11-06 10:13:40,080 - DEBUG - ya.test - _save_outputs: Command (pid 487230) stime: 0.185414
2025-11-06 10:13:40,080 - DEBUG - ya.test - _save_outputs: Command (pid 487230) utime: 0.5475059999999999
2025-11-06 10:13:40,081 - DEBUG - ya.test - _save_outputs: Command (pid 487230) wtime: 0.801
2025-11-06 10:13:40,081 - DEBUG - ya.test - _save_outputs: Command (pid 487230) output:
Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ew6xquenpy
    Host information: 
        Linux ghrun-ew6xquenpy 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-06 10:13:40,081 - DEBUG - ya.test - _save_outputs: Command (pid 487230) errors:

2025-11-06 10:13:40,082 - INFO - __tests__.base - _setup_ydb: Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ew6xquenpy
    Host information: 
        Linux ghrun-ew6xquenpy 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-06 10:13:40,195 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/2dji/004544/ydb/apps/ydbd/ydbd
2025-11-06 10:13:40,197 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28139
2025-11-06 10:13:40,200 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/2dji/004544/r3tmp/kikimr_cluster_4rr6zhx1
2025-11-06 10:13:40,244 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/2dji/004544/ydb/apps/ydbd/ydbd
2025-11-06 10:13:40,245 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/11291
2025-11-06 10:13:40,245 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4927
2025-11-06 10:13:40,246 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/2256
2025-11-06 10:13:40,247 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26713
2025-11-06 10:13:40,248 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/2dji/004544/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/kikimr_configs"
2025-11-06 10:13:40,248 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/2dji/004544/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/2dji/004544/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/node_1/logfile_zb1nviyu.log --grpc-port=11291 --mon-port=4927 --ic-port=28139
2025-11-06 10:13:40,249 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:11291/1, disk_size 68719476736
2025-11-06 10:13:40,249 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/2dji/004544/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/kikimr_configs"
2025-11-06 10:13:40,250 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/2dji/004544/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/2dji/004544/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/node_1/logfile_zb1nviyu.log --grpc-port=11291 --mon-port=4927 --ic-port=28139
2025-11-06 10:13:40,251 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/2dji/004544/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/2dji/004544/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/2dji/004544/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/node_1/logfile_zb1nviyu.log', '--grpc-port=11291', '--mon-port=4927', '--ic-port=28139')' in '/home/runner/.ya/build/build_root/2dji/004544/ydb/tests/olap/column_family/compression/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/alter_compression.py.TestAllCompression.test_all_supported_compression.zstd_compression-COMPRESSION.=.zstd/cluster/node_1' (no wait)
2025-11-06 10:13:40,256 - DEBUG - ya.test - execute: Command pid: 487874
2025-11-06 10:13:40,256 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:11291/1
2025-11-06 10:13:40,258 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-06 10:13:40,259 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-06 10:13:40,259 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/2dji/004544/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-06 10:13:40,261 - DEBUG - ya.test - execute: Command pid: 487877
2025-11-06 10:13:41,089 - DEBUG - ya.test - _save_outputs: Command (pid 487877) rc: 0
2025-11-06 10:13:41,089 - DEBUG - ya.test - _save_outputs: Command (pid 487877) elapsed time (sec): 0.8270931243896484
2025-11-06 10:13:41,090 - DEBUG - ya.test - _save_outputs: Command (pid 487877) idrss: 0
2025-11-06 10:13:41,090 - DEBUG - ya.test - _save_outputs: Command (pid 487877) inblock: 0
2025-11-06 10:13:41,090 - DEBUG - ya.test - _save_outputs: Command (pid 487877) isrss: 0
2025-11-06 10:13:41,090 - DEBUG - ya.test - _save_outputs: Command (pid 487877) ixrss: 0
2025-11-06 10:13:41,091 - DEBUG - ya.test - _save_outputs: Command (pid 487877) majflt: 0
2025-11-06 10:13:41,091 - DEBUG - ya.test - _save_outputs: Command (pid 487877) maxrss: 499040
2025-11-06 10:13:41,091 - DEBUG - ya.test - _save_outputs: Command (pid 487877) minflt: 45435
2025-11-06 10:13:41,092 - DEBUG - ya.test - _save_outputs: Command (pid 487877) msgrcv: 0
2025-11-06 10:13:41,092 - DEBUG - ya.test - _save_outputs: Command (pid 487877) msgsnd: 0
2025-11-06 10:13:41,092 - DEBUG - ya.test - _save_outputs: Command (pid 487877) nivcsw: 114
2025-11-06 10:13:41,092 - DEBUG - ya.test - _save_outputs: Command (pid 487877) nsignals: 0
2025-11-06 10:13:41,093 - DEBUG - ya.test - _save_outputs: Command (pid 487877) nswap: 0
2025-11-06 10:13:41,093 - DEBUG - ya.test - _save_outputs: Command (pid 487877) nvcsw: 15
2025-11-06 10:13:41,093 - DEBUG - ya.test - _save_outputs: Command (pid 487877) oublock: 8
2025-11-06 10:13:41,093 - DEBUG - ya.test - _save_outputs: Command (pid 487877) stime: 0.171572
2025-11-06 10:13:41,094 - DEBUG - ya.test - _save_outputs: Command (pid 487877) utime: 0.485353
2025-11-06 10:13:41,094 - DEBUG - ya.test - _save_outputs: Command (pid 487877) wtime: 0.829
2025-11-06 10:13:41,094 - DEBUG - ya.test - _save_outputs: Command (pid 487877) output:
Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-ew6xquenpy
    Host information: 
        Linux ghrun-ew6xquenpy 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-06 10:13:41,095 - DEBUG - ya.test - _save_outputs: Command (pid 487877) errors:

2025-11-06 10:13:41,095 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 609b3b9504bd064e40414654c1f6284791b6a66c
    Branch: 
    Author: Alek5andr-Kotov <akotov@ydb.tech>
    Summary: Merge 5af363c204fa18a6cbd8ab64fd547c3e99f1eee6 into 5ddc18e8b9ca9bdb9f539a0a3efaaf4e958f00d3
2025-11-06 10:13:46,244 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/2dji/004544/ydb/apps/ydbd/ydbd
2025-11-06 10:13:46,245 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/2dji/004544/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:11291', '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: 28139\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-06 10:13:46,246 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-06 10:13:46,256 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-06 10:13:46,256 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/2dji/004544/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:11291', '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: 28139\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-06 10:13:46,269 - DEBUG - ya.test - execute: Command pid: 490926
2025-11-06 10:13:47,620 - DEBUG - ya.test - _save_outputs: Command (pid 490926) rc: 0
2025-11-06 10:13:47,621 - DEBUG - ya.test - _save_outputs: Command (pid 490926) elapsed time (sec): 1.3502466678619385
2025-11-06 10:13:47,621 - DEBUG - ya.test - _save_outputs: Command (pid 490926) idrss: 0
2025-11-06 10:13:47,622 - DEBUG - ya.test - _save_outputs: Command (pid 490926) inblock: 0
2025-11-06 10:13:47,622 - DEBUG - ya.test - _save_outputs: Command (pid 490926) isrss: 0
2025-11-06 10:13:47,622 - DEBUG - ya.test - _save_outputs: Command (pid 490926) ixrss: 0
2025-11-06 10:13:47,622 - DEBUG - ya.test - _save_outputs: Command (pid 490926) majflt: 0
2025-11-06 10:13:47,623 - DEBUG - ya.test - _save_outputs: Command (pid 490926) maxrss: 594360
2025-11-06 10:13:47,623 - DEBUG - ya.test - _save_outputs: Command (pid 490926) minflt: 51701
2025-11-06 10:13:47,623 - DEBUG - ya.test - _save_outputs: Command (pid 490926) msgrcv: 0
2025-11-06 10:13:47,624 - DEBUG - ya.test - _save_outputs: Command (pid 490926) msgsnd: 0
2025-11-06 10:13:47,624 - DEBUG - ya.test - _save_outputs: Command (pid 490926) nivcsw: 524
2025-11-06 10:13:47,627 - DEBUG - ya.test - _save_outputs: Command (pid 490926) nsignals: 0
2025-11-06 10:13:47,627 - DEBUG - ya.test - _save_outputs: Command (pid 490926) nswap: 0
2025-11-06 10:13:47,627 - DEBUG - ya.test - _save_outputs: Command (pid 490926) nvcsw: 565
2025-11-06 10:13:47,628 - DEBUG - ya.test - _save_outputs: Command (pid 490926) oublock: 8
2025-11-06 10:13:47,628 - DEBUG - ya.test - _save_outputs: Command (pid 490926) stime: 0.251627
2025-11-06 10:13:47,628 - DEBUG - ya.test - _save_outputs: Command (pid 490926) utime: 0.5986509999999999
2025-11-06 10:13:47,628 - DEBUG - ya.test - _save_outputs: Command (pid 490926) wtime: 1.363
2025-11-06 10:13:47,629 - DEBUG - ya.test - _save_outputs: Command (pid 490926) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-06 10:13:47,629 - DEBUG - ya.test - _save_outputs: Command (pid 490926) errors:

2025-11-06 10:13:47,632 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/2dji/004544/ydb/apps/ydbd/ydbd
2025-11-06 10:13:47,633 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/2dji/004544/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:11291', '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-06 10:13:47,634 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-06 10:13:47,635 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-06 10:13:47,637 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/2dji/004544/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:11291', '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-06 10:13:47,639 - DEBUG - ya.test - execute: Command pid: 491596
2025-11-06 10:13:48,685 - DEBUG - ya.test - _save_outputs: Command (pid 491596) rc: 0
2025-11-06 10:13:48,685 - DEBUG - ya.test - _save_outputs: Command (pid 491596) elapsed time (sec): 1.044814109802246
2025-11-06 10:13:48,686 - DEBUG - ya.test - _save_outputs: Command (pid 491596) idrss: 0
2025-11-06 10:13:48,687 - DEBUG - ya.test - _save_outputs: Command (pid 491596) inblock: 0
2025-11-06 10:13:48,687 - DEBUG - ya.test - _save_outputs: Command (pid 491596) isrss: 0
2025-11-06 10:13:48,687 - DEBUG - ya.test - _save_outputs: Command (pid 491596) ixrss: 0
2025-11-06 10:13:48,687 - DEBUG - ya.test - _save_outputs: Command (pid 491596) majflt: 0
2025-11-06 10:13:48,688 - DEBUG - ya.test - _save_outputs: Command (pid 491596) maxrss: 594392
2025-11-06 10:13:48,688 - DEBUG - ya.test - _save_outputs: Command (pid 491596) minflt: 51648
2025-11-06 10:13:48,688 - DEBUG - ya.test - _save_outputs: Command (pid 491596) msgrcv: 0
2025-11-06 10:13:48,688 - DEBUG - ya.test - _save_outputs: Command (pid 491596) msgsnd: 0
2025-11-06 10:13:48,689 - DEBUG - ya.test - _save_outputs: Command (pid 491596) nivcsw: 218
2025-11-06 10:13:48,689 - DEBUG - ya.test - _save_outputs: Command (pid 491596) nsignals: 0
2025-11-06 10:13:48,689 - DEBUG - ya.test - _save_outputs: Command (pid 491596) nswap: 0
2025-11-06 10:13:48,689 - DEBUG - ya.test - _save_outputs: Command (pid 491596) nvcsw: 678
2025-11-06 10:13:48,690 - DEBUG - ya.test - _save_outputs: Command (pid 491596) oublock: 8
2025-11-06 10:13:48,690 - DEBUG - ya.test - _save_outputs: Command (pid 491596) stime: 0.27914
2025-11-06 10:13:48,690 - DEBUG - ya.test - _save_outputs: Command (pid 491596) utime: 0.5438879999999999
2025-11-06 10:13:48,690 - DEBUG - ya.test - _save_outputs: Command (pid 491596) wtime: 1.047
2025-11-06 10:13:48,691 - DEBUG - ya.test - _save_outputs: Command (pid 491596) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-11-06 10:13:48,691 - DEBUG - ya.test - _save_outputs: Command (pid 491596) errors:

2025-11-06 10:13:48,692 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-06 10:13:48,802 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-06 10:13:48,837 - 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-06 10:13:48,841 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-06 10:13:48,844 - 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-06 10:13:48,864 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-06 10:13:48,865 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, c9669589-ecbf-4fb1-a160-07d90d26d4ca, localhost:11291): creating call state
2025-11-06 10:13:48,866 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, c9669589-ecbf-4fb1-a160-07d90d26d4ca, localhost:11291): request = { database: "/Root" }
2025-11-06 10:13:48,874 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, c9669589-ecbf-4fb1-a160-07d90d26d4ca, localhost:11291): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ew6xquenpy.auto.internal" port: 11291 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-06 10:13:48,875 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ew6xquenpy.auto.internal:11291, location 1, ssl: False>]>
2025-11-06 10:13:48,876 - 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-06 10:13:48,890 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:11291
2025-11-06 10:13:48,891 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-06 10:13:48,892 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a40f3055-1aed-44e3-a349-170dab23cefc, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:13:48,893 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a40f3055-1aed-44e3-a349-170dab23cefc, ghrun-ew6xquenpy.auto.internal:11291): request = {  }
2025-11-06 10:13:48,900 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a40f3055-1aed-44e3-a349-170dab23cefc, ghrun-ew6xquenpy.auto.internal:11291): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" node_id: 1 }
2025-11-06 10:13:48,901 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, f9ba1a88-28b7-4d70-97bd-71182df994f2, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:13:48,902 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, f9ba1a88-28b7-4d70-97bd-71182df994f2, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" }
2025-11-06 10:13:48,904 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, f9ba1a88-28b7-4d70-97bd-71182df994f2, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:48,907 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:48,908 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 63635c07-d48d-4eec-a32a-a75195af86be, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:13:48,909 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 63635c07-d48d-4eec-a32a-a75195af86be, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:13:48,910 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 63635c07-d48d-4eec-a32a-a75195af86be, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:51,418 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:51,418 - INFO - __tests__.alter_compression - create_table_without_compression: Table /Root/all_compression/all_supported_compression/off_compression created
2025-11-06 10:13:51,419 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:51,419 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4bce0879-956e-4fae-8af4-0730cd813899, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:13:51,420 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4bce0879-956e-4fae-8af4-0730cd813899, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:13:51,422 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4bce0879-956e-4fae-8af4-0730cd813899, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:52,471 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:52,472 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:52,473 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fca71f4b-7fda-4841-b4ed-c9f346515bd5, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:13:52,474 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fca71f4b-7fda-4841-b4ed-c9f346515bd5, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:13:52,479 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fca71f4b-7fda-4841-b4ed-c9f346515bd5, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:53,688 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:53,689 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:53,690 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9abfb82f-24be-48dc-a653-c6bafbbef533, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:13:53,691 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9abfb82f-24be-48dc-a653-c6bafbbef533, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:13:53,693 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9abfb82f-24be-48dc-a653-c6bafbbef533, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:54,550 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:54,551 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:54,552 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0613be80-5c07-46ef-9935-1fd3e0b2cf89, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:13:54,553 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0613be80-5c07-46ef-9935-1fd3e0b2cf89, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:13:54,554 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0613be80-5c07-46ef-9935-1fd3e0b2cf89, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:56,187 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:56,188 - 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-06 10:13:56,188 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:56,189 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b864ec7b-6a77-49a8-a0a8-57819d319c02, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:13:56,190 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b864ec7b-6a77-49a8-a0a8-57819d319c02, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:13:56,192 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b864ec7b-6a77-49a8-a0a8-57819d319c02, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:57,163 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:57,164 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:57,165 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4bce92e0-70d2-4378-8d6f-8bb7ba399d4a, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:13:57,166 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4bce92e0-70d2-4378-8d6f-8bb7ba399d4a, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:13:57,167 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4bce92e0-70d2-4378-8d6f-8bb7ba399d4a, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:57,507 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:57,508 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:57,508 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8c416860-dfc4-4180-9128-804c911f6159, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:13:57,509 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8c416860-dfc4-4180-9128-804c911f6159, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:13:57,513 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8c416860-dfc4-4180-9128-804c911f6159, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:57,908 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:57,909 - 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-06 10:13:57,909 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:57,910 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 63628b8a-00a9-4d2f-a9ba-2349790cd242, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:13:57,911 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 63628b8a-00a9-4d2f-a9ba-2349790cd242, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:13:57,912 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 63628b8a-00a9-4d2f-a9ba-2349790cd242, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:59,133 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:59,134 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:59,135 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 39bf94de-e600-4290-8254-3c5402cd9449, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:13:59,136 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 39bf94de-e600-4290-8254-3c5402cd9449, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:13:59,137 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 39bf94de-e600-4290-8254-3c5402cd9449, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:59,367 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:59,368 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:59,368 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 38b9e7a9-f011-447a-9bca-a40f61f6becf, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:13:59,369 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 38b9e7a9-f011-447a-9bca-a40f61f6becf, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:13:59,372 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 38b9e7a9-f011-447a-9bca-a40f61f6becf, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:13:59,721 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:59,722 - 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-06 10:13:59,722 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:13:59,723 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9867769e-d677-419f-af81-24a4e537ce63, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:13:59,724 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9867769e-d677-419f-af81-24a4e537ce63, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:13:59,725 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9867769e-d677-419f-af81-24a4e537ce63, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:00,684 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:00,684 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:00,685 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 53899d31-8552-4fe2-98e5-f54b30cd2a75, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:00,686 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 53899d31-8552-4fe2-98e5-f54b30cd2a75, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:00,687 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 53899d31-8552-4fe2-98e5-f54b30cd2a75, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:01,102 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:01,103 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:01,103 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ed7ae0d5-d1b4-446a-9d19-80c4d080d71e, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:01,111 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ed7ae0d5-d1b4-446a-9d19-80c4d080d71e, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:01,113 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ed7ae0d5-d1b4-446a-9d19-80c4d080d71e, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:01,683 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:01,684 - 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-06 10:14:01,684 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:01,685 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6ec384e7-9cb4-4a96-aff5-08bac5e2c728, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:01,686 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6ec384e7-9cb4-4a96-aff5-08bac5e2c728, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:01,691 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6ec384e7-9cb4-4a96-aff5-08bac5e2c728, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:03,075 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:03,076 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:03,077 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 13f1dc61-f4de-42f3-aa73-a06be78003b0, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:03,078 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 13f1dc61-f4de-42f3-aa73-a06be78003b0, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:03,080 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 13f1dc61-f4de-42f3-aa73-a06be78003b0, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:03,967 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:03,968 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:03,968 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a9575170-a124-4ef7-a7be-eee07bb5cd3a, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:03,969 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a9575170-a124-4ef7-a7be-eee07bb5cd3a, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:03,972 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a9575170-a124-4ef7-a7be-eee07bb5cd3a, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:04,488 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:04,489 - 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-06 10:14:04,489 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:04,490 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb671c0f-e922-4624-8a3f-2e2330a38178, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:04,491 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb671c0f-e922-4624-8a3f-2e2330a38178, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:04,499 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb671c0f-e922-4624-8a3f-2e2330a38178, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:05,651 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:05,651 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:05,652 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8d38e6b5-f4f8-4b29-b623-6364b97fb60f, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:05,653 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8d38e6b5-f4f8-4b29-b623-6364b97fb60f, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:05,654 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8d38e6b5-f4f8-4b29-b623-6364b97fb60f, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:06,049 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:06,050 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:06,051 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 81c5dac8-170d-469d-95b5-3d626b351fc5, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:06,052 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 81c5dac8-170d-469d-95b5-3d626b351fc5, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:06,053 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 81c5dac8-170d-469d-95b5-3d626b351fc5, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:06,631 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:06,632 - 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-06 10:14:06,632 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:06,633 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 77c0128e-e326-4ef3-9e3d-02299ca5887b, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:06,634 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 77c0128e-e326-4ef3-9e3d-02299ca5887b, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:06,636 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 77c0128e-e326-4ef3-9e3d-02299ca5887b, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:07,664 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:07,665 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:07,665 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 39aff9bd-bbb4-4221-b66b-778f3005421a, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:07,666 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 39aff9bd-bbb4-4221-b66b-778f3005421a, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:07,667 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 39aff9bd-bbb4-4221-b66b-778f3005421a, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:08,008 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:08,008 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:08,009 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 45ac4f60-b699-428a-a688-73452f9a3017, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:08,010 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 45ac4f60-b699-428a-a688-73452f9a3017, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:08,011 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 45ac4f60-b699-428a-a688-73452f9a3017, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:08,846 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:08,847 - 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-06 10:14:08,847 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:08,848 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 57323c24-30c3-44fc-87a8-3b850a339ea6, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:08,849 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 57323c24-30c3-44fc-87a8-3b850a339ea6, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:08,851 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 57323c24-30c3-44fc-87a8-3b850a339ea6, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:09,995 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:09,996 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:09,996 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d9b5dc53-7b4c-434d-a14b-889b88c881bb, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:09,997 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d9b5dc53-7b4c-434d-a14b-889b88c881bb, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:09,998 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d9b5dc53-7b4c-434d-a14b-889b88c881bb, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:10,296 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:10,297 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:10,298 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 10a80cbc-f76d-4f18-8b31-ead16fe25ac9, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:10,299 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 10a80cbc-f76d-4f18-8b31-ead16fe25ac9, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:10,305 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 10a80cbc-f76d-4f18-8b31-ead16fe25ac9, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:11,136 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:11,137 - 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-06 10:14:11,137 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:11,138 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9415866e-6c85-4144-a058-fc23b7b53a69, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:11,139 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9415866e-6c85-4144-a058-fc23b7b53a69, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:11,141 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9415866e-6c85-4144-a058-fc23b7b53a69, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:12,444 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:12,445 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:12,446 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e3420923-f356-404c-ac20-60744e5d922f, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:12,449 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e3420923-f356-404c-ac20-60744e5d922f, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:12,451 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e3420923-f356-404c-ac20-60744e5d922f, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:12,807 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:12,808 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:12,809 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0862dea8-d488-46cd-add8-13719ec0f3f4, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:12,809 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0862dea8-d488-46cd-add8-13719ec0f3f4, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:12,811 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0862dea8-d488-46cd-add8-13719ec0f3f4, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:13,535 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:13,536 - 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-06 10:14:13,536 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:13,537 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b5f1a15f-1050-4ff8-9163-872ff22ca3b8, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:13,538 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b5f1a15f-1050-4ff8-9163-872ff22ca3b8, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:13,540 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b5f1a15f-1050-4ff8-9163-872ff22ca3b8, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:14,844 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:14,845 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:14,845 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 849dc33e-2e94-476d-8699-d43c6cd7403a, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:14,847 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 849dc33e-2e94-476d-8699-d43c6cd7403a, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:14,849 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 849dc33e-2e94-476d-8699-d43c6cd7403a, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:15,176 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:15,177 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:15,177 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d01a0c2c-f822-4c09-bb5e-155442dc58ed, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:15,179 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d01a0c2c-f822-4c09-bb5e-155442dc58ed, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:15,180 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d01a0c2c-f822-4c09-bb5e-155442dc58ed, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:16,010 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:16,011 - 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-06 10:14:16,011 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:16,012 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bf43e54d-7024-4e3f-9ac9-7dab554593f9, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:16,013 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bf43e54d-7024-4e3f-9ac9-7dab554593f9, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:16,015 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bf43e54d-7024-4e3f-9ac9-7dab554593f9, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:17,183 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:17,184 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:17,185 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8f8c351b-93d6-4c3a-a9e1-c05bee475e9a, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:17,186 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8f8c351b-93d6-4c3a-a9e1-c05bee475e9a, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:17,188 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8f8c351b-93d6-4c3a-a9e1-c05bee475e9a, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:17,507 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:17,508 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:17,509 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6af2832a-2c51-414a-9623-21a3361dfbf6, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:17,510 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6af2832a-2c51-414a-9623-21a3361dfbf6, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:17,511 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6af2832a-2c51-414a-9623-21a3361dfbf6, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:17,879 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:17,880 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:17,881 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5ae65d8c-cbd4-4db5-aba6-e4f80f26726f, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:17,882 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5ae65d8c-cbd4-4db5-aba6-e4f80f26726f, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:17,883 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5ae65d8c-cbd4-4db5-aba6-e4f80f26726f, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:21,595 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:31,631 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:31,632 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b5721375-7897-4300-8449-624624758134, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:31,633 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b5721375-7897-4300-8449-624624758134, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:31,635 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b5721375-7897-4300-8449-624624758134, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:35,288 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:35,325 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8092160)
2025-11-06 10:14:35,325 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:35,326 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 93fb44a4-3c6b-4422-91a8-b9ed1dce231b, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:35,327 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 93fb44a4-3c6b-4422-91a8-b9ed1dce231b, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:35,329 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 93fb44a4-3c6b-4422-91a8-b9ed1dce231b, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:38,399 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:48,447 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:14:48,448 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 54992724-c6b4-44a4-8ccc-c343191b4169, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:14:48,449 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 54992724-c6b4-44a4-8ccc-c343191b4169, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:14:48,454 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 54992724-c6b4-44a4-8ccc-c343191b4169, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:14:52,764 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:00,706 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-06 10:15:00,707 - 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-06 10:15:00,748 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-06 10:15:00,749 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 36a4fe93-590d-489f-ae52-cbfd4c546601, localhost:11291): creating call state
2025-11-06 10:15:00,750 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 36a4fe93-590d-489f-ae52-cbfd4c546601, localhost:11291): request = { database: "/Root" }
2025-11-06 10:15:00,764 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 36a4fe93-590d-489f-ae52-cbfd4c546601, localhost:11291): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ew6xquenpy.auto.internal" port: 11291 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-06 10:15:00,765 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ew6xquenpy.auto.internal:11291, location 1, ssl: False>]>
2025-11-06 10:15:00,766 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:11291
2025-11-06 10:15:02,803 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:02,805 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b9336fdc-96fd-4906-b0e4-186f55f3cf71, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:02,806 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b9336fdc-96fd-4906-b0e4-186f55f3cf71, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:15:02,808 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b9336fdc-96fd-4906-b0e4-186f55f3cf71, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:03,585 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:13,590 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:13,591 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a14eae5c-6f48-453e-9964-be8d46e94093, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:13,592 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a14eae5c-6f48-453e-9964-be8d46e94093, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:15:13,594 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a14eae5c-6f48-453e-9964-be8d46e94093, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:14,413 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:14,417 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/off_compression`, volumes `value` (8000000, 8009216)
2025-11-06 10:15:14,418 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:14,419 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4b6a9cde-b1a1-4823-8570-294c18f9a7ed, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:14,420 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4b6a9cde-b1a1-4823-8570-294c18f9a7ed, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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-06 10:15:14,421 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4b6a9cde-b1a1-4823-8570-294c18f9a7ed, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:14,763 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:14,767 - INFO - ya.test - pytest_runtest_call: Test call (class_name: alter_compression.py::TestAllCompression, test_name: test_all_supported_compression[zstd_compression-COMPRESSION = "zstd"])
2025-11-06 10:15:14,768 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:14,769 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bd6985dd-e65a-4b8c-b0a2-339a2b995294, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:14,775 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bd6985dd-e65a-4b8c-b0a2-339a2b995294, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "\n                CREATE TABLE `/Root/all_compression/all_supported_compression/zstd_compression` (\n                    value Uint64 NOT NULL,\n                    value1 Uint64,\n                    PRIMARY KEY(value),\n                    FAMILY default (COMPRESSION = \"zstd\")\n                )\n                WITH (STORE = COLUMN)\n                " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:14,777 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bd6985dd-e65a-4b8c-b0a2-339a2b995294, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:18,541 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:18,541 - INFO - __tests__.alter_compression - test_all_supported_compression: Table /Root/all_compression/all_supported_compression/zstd_compression created
2025-11-06 10:15:18,542 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:18,543 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ddcf81b1-f865-41c5-98c3-eaf6cc63edcc, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:18,543 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ddcf81b1-f865-41c5-98c3-eaf6cc63edcc, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:18,545 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ddcf81b1-f865-41c5-98c3-eaf6cc63edcc, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:19,659 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:19,660 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:19,661 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 76a00d30-5af2-4b57-b39b-d8a0ef6eeb54, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:19,662 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 76a00d30-5af2-4b57-b39b-d8a0ef6eeb54, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:19,663 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 76a00d30-5af2-4b57-b39b-d8a0ef6eeb54, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:21,431 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:21,432 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:21,432 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 26a494a9-cdbd-4d50-9c96-a832fcb59233, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:21,433 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 26a494a9-cdbd-4d50-9c96-a832fcb59233, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:21,435 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 26a494a9-cdbd-4d50-9c96-a832fcb59233, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:22,383 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:22,384 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:22,385 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 50f9e522-3c95-446d-ad05-04d40db27203, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:22,386 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 50f9e522-3c95-446d-ad05-04d40db27203, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:22,402 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 50f9e522-3c95-446d-ad05-04d40db27203, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:24,375 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:24,376 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 100000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 100000, 'Portions': 64}}, blobs: {'__DEFAULT': {'Portions': 64, 'BlobSize': 318880, 'BlobCount': 128}}
2025-11-06 10:15:24,376 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:24,377 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 684ad440-49b6-4146-9175-b775a15e4a03, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:24,378 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 684ad440-49b6-4146-9175-b775a15e4a03, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:24,382 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 684ad440-49b6-4146-9175-b775a15e4a03, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:25,812 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:25,813 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:25,813 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ce5869cb-20ba-4606-b892-aebbb605af96, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:25,814 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ce5869cb-20ba-4606-b892-aebbb605af96, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:25,816 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ce5869cb-20ba-4606-b892-aebbb605af96, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:26,208 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:26,209 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:26,209 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3e74118f-b08e-4bc5-a3b9-73d5c0a24a2f, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:26,210 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3e74118f-b08e-4bc5-a3b9-73d5c0a24a2f, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:26,212 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3e74118f-b08e-4bc5-a3b9-73d5c0a24a2f, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:26,649 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:26,650 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 200000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 200000, 'Portions': 128}}, blobs: {'__DEFAULT': {'Portions': 128, 'BlobSize': 638384, 'BlobCount': 256}}
2025-11-06 10:15:26,650 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:26,651 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 363e8aff-e9db-463c-8342-e655abf1be45, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:26,652 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 363e8aff-e9db-463c-8342-e655abf1be45, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:26,654 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 363e8aff-e9db-463c-8342-e655abf1be45, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:28,186 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:28,187 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:28,189 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f3fbc364-ef08-4c3f-a41e-03ed72f6d055, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:28,190 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f3fbc364-ef08-4c3f-a41e-03ed72f6d055, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:28,192 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f3fbc364-ef08-4c3f-a41e-03ed72f6d055, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:28,548 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:28,549 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:28,549 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 77abaadd-0486-410b-a6e4-812b30df5915, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:28,550 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 77abaadd-0486-410b-a6e4-812b30df5915, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:28,552 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 77abaadd-0486-410b-a6e4-812b30df5915, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:29,208 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:29,209 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 300000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 300000, 'Portions': 192}}, blobs: {'__DEFAULT': {'Portions': 192, 'BlobSize': 957552, 'BlobCount': 384}}
2025-11-06 10:15:29,210 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:29,210 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 19451b48-7c6c-4c0f-b124-3caa4ad04aac, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:29,211 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 19451b48-7c6c-4c0f-b124-3caa4ad04aac, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:29,213 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 19451b48-7c6c-4c0f-b124-3caa4ad04aac, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:30,347 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:30,348 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:30,348 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 30366095-56e2-4cec-987b-c65079385ac8, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:30,349 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 30366095-56e2-4cec-987b-c65079385ac8, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:30,353 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 30366095-56e2-4cec-987b-c65079385ac8, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:30,685 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:30,686 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:30,687 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bb7c766e-c32d-4495-89f2-eb7c8fde1794, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:30,688 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bb7c766e-c32d-4495-89f2-eb7c8fde1794, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:30,691 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bb7c766e-c32d-4495-89f2-eb7c8fde1794, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:31,318 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:31,319 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 400000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 400000, 'Portions': 256}}, blobs: {'__DEFAULT': {'Portions': 256, 'BlobSize': 1277056, 'BlobCount': 512}}
2025-11-06 10:15:31,320 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:31,321 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cd13e3dd-11f7-4fd0-9177-8d4872f71beb, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:31,322 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cd13e3dd-11f7-4fd0-9177-8d4872f71beb, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:31,327 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cd13e3dd-11f7-4fd0-9177-8d4872f71beb, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:32,615 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:32,616 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:32,617 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 77e72dec-f65b-401a-b8fe-c6b102898589, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:32,617 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 77e72dec-f65b-401a-b8fe-c6b102898589, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:32,619 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 77e72dec-f65b-401a-b8fe-c6b102898589, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:32,935 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:32,936 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:32,937 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 720b51a5-452a-4018-93bd-370407b1c7e9, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:32,938 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 720b51a5-452a-4018-93bd-370407b1c7e9, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:32,939 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 720b51a5-452a-4018-93bd-370407b1c7e9, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:33,511 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:33,511 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 500000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 500000, 'Portions': 320}}, blobs: {'__DEFAULT': {'Portions': 320, 'BlobSize': 1596256, 'BlobCount': 640}}
2025-11-06 10:15:33,512 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:33,512 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f447b218-f8f9-4bf3-9754-13d56239b6c8, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:33,513 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f447b218-f8f9-4bf3-9754-13d56239b6c8, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:33,515 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f447b218-f8f9-4bf3-9754-13d56239b6c8, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:34,863 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:34,865 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:34,865 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 707de947-7779-45f2-8fd9-c95e4e5c7ff4, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:34,866 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 707de947-7779-45f2-8fd9-c95e4e5c7ff4, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:34,868 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 707de947-7779-45f2-8fd9-c95e4e5c7ff4, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:35,408 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:35,428 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:35,429 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 59fcb78f-7913-449e-b84a-e329f58fdda2, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:35,430 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 59fcb78f-7913-449e-b84a-e329f58fdda2, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:35,431 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 59fcb78f-7913-449e-b84a-e329f58fdda2, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:36,239 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:36,240 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 600000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 600000, 'Portions': 384}}, blobs: {'__DEFAULT': {'Portions': 384, 'BlobSize': 1915600, 'BlobCount': 768}}
2025-11-06 10:15:36,240 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:36,241 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, df89f1d7-2fdc-48af-8333-d29734a4acaa, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:36,242 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, df89f1d7-2fdc-48af-8333-d29734a4acaa, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:36,243 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, df89f1d7-2fdc-48af-8333-d29734a4acaa, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:37,508 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:37,509 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:37,510 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fb51aa99-057d-4784-8ea6-0e232711ac91, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:37,510 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fb51aa99-057d-4784-8ea6-0e232711ac91, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:37,512 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fb51aa99-057d-4784-8ea6-0e232711ac91, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:37,792 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:37,793 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:37,793 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 17473dea-b342-48cc-8ae1-cf73e785ee52, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:37,794 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 17473dea-b342-48cc-8ae1-cf73e785ee52, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:37,796 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 17473dea-b342-48cc-8ae1-cf73e785ee52, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:38,454 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:38,455 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 700000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 700000, 'Portions': 448}}, blobs: {'__DEFAULT': {'Portions': 448, 'BlobSize': 2234896, 'BlobCount': 896}}
2025-11-06 10:15:38,455 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:38,455 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b6fcd1c9-ee59-4aef-b9f1-47a7522e73c4, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:38,456 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b6fcd1c9-ee59-4aef-b9f1-47a7522e73c4, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:38,457 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b6fcd1c9-ee59-4aef-b9f1-47a7522e73c4, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:39,687 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:39,688 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:39,688 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 03b1b705-e522-44f2-8163-d53569189cac, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:39,689 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 03b1b705-e522-44f2-8163-d53569189cac, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:39,691 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 03b1b705-e522-44f2-8163-d53569189cac, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:40,107 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:40,108 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:40,109 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bb0560c8-6d0d-44c7-b87d-c1610ad51317, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:40,110 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bb0560c8-6d0d-44c7-b87d-c1610ad51317, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:40,111 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bb0560c8-6d0d-44c7-b87d-c1610ad51317, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:40,967 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:40,968 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 800000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 800000, 'Portions': 512}}, blobs: {'__DEFAULT': {'Portions': 512, 'BlobSize': 2554464, 'BlobCount': 1024}}
2025-11-06 10:15:40,968 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:40,969 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c88bb7ae-c28b-4f92-a742-58ec3df6eccc, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:40,970 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c88bb7ae-c28b-4f92-a742-58ec3df6eccc, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:40,971 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c88bb7ae-c28b-4f92-a742-58ec3df6eccc, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:42,239 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:42,240 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:42,240 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 39569951-bb11-482d-969f-5d724379e7ac, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:42,241 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 39569951-bb11-482d-969f-5d724379e7ac, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:42,243 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 39569951-bb11-482d-969f-5d724379e7ac, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:42,615 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:42,616 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:42,616 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 58dd1fc5-966e-4cb3-8518-31ff516fd177, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:42,617 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 58dd1fc5-966e-4cb3-8518-31ff516fd177, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:42,619 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 58dd1fc5-966e-4cb3-8518-31ff516fd177, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:43,399 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:43,400 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 900000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 900000, 'Portions': 576}}, blobs: {'__DEFAULT': {'Portions': 576, 'BlobSize': 2873568, 'BlobCount': 1152}}
2025-11-06 10:15:43,400 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:43,401 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, daf75bd9-eda0-4831-bedb-de32203039ff, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:43,402 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, daf75bd9-eda0-4831-bedb-de32203039ff, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression`\n                SELECT * FROM AS_TABLE($rows);\n            " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:43,403 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, daf75bd9-eda0-4831-bedb-de32203039ff, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:44,596 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:44,597 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:44,598 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8cc911a1-c1a4-4b9a-a390-2ee60b715ef6, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:44,598 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8cc911a1-c1a4-4b9a-a390-2ee60b715ef6, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:44,600 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8cc911a1-c1a4-4b9a-a390-2ee60b715ef6, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:44,888 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:44,889 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:44,890 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb35ab24-07d3-4f31-a0da-7e9c18bd7f44, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:44,890 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb35ab24-07d3-4f31-a0da-7e9c18bd7f44, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_stats` group by TabletId, PortionId, TierName\n            ) group by TierName\n        " } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:44,907 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb35ab24-07d3-4f31-a0da-7e9c18bd7f44, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:45,789 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:45,789 - INFO - __tests__.alter_compression - upsert_and_wait_portions: 1000000 rows in /Root/all_compression/all_supported_compression/zstd_compression. portions: {'__DEFAULT': {'Rows': 1000000, 'Portions': 640}}, blobs: {'__DEFAULT': {'Portions': 640, 'BlobSize': 3193216, 'BlobCount': 1280}}
2025-11-06 10:15:45,790 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:45,791 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 22f6ce9a-f35d-464e-8cd1-dc90e0bd48ae, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:45,791 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 22f6ce9a-f35d-464e-8cd1-dc90e0bd48ae, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) AS Rows FROM `/Root/all_compression/all_supported_compression/zstd_compression`" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:45,794 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 22f6ce9a-f35d-464e-8cd1-dc90e0bd48ae, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:47,186 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:47,187 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:47,188 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8eb387c2-39ae-40ae-8381-98766514ec59, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:47,189 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8eb387c2-39ae-40ae-8381-98766514ec59, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:47,190 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8eb387c2-39ae-40ae-8381-98766514ec59, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:47,543 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:47,544 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:47,545 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, af1c8d1a-2471-493d-9a83-322f9772fe16, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:47,546 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, af1c8d1a-2471-493d-9a83-322f9772fe16, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:47,550 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, af1c8d1a-2471-493d-9a83-322f9772fe16, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:47,948 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:47,949 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:15:47,949 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 270d7e38-bc27-4a45-8351-68e3ca33e2db, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:15:47,950 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 270d7e38-bc27-4a45-8351-68e3ca33e2db, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:15:47,951 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 270d7e38-bc27-4a45-8351-68e3ca33e2db, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:15:51,885 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:16:01,919 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:16:01,920 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8e0e8d92-ffd7-4428-9a87-dd53a877681a, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:16:01,921 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8e0e8d92-ffd7-4428-9a87-dd53a877681a, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:01,922 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8e0e8d92-ffd7-4428-9a87-dd53a877681a, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:06,008 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:16:06,059 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_compression`, volumes `value` (8000000, 1596608)
2025-11-06 10:16:06,059 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:16:06,060 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0ba253bf-0e1b-4bc0-beec-6452057d1252, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:16:06,061 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0ba253bf-0e1b-4bc0-beec-6452057d1252, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" 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_compression/.sys/primary_index_portion_stats` group by TierName" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:06,067 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0ba253bf-0e1b-4bc0-beec-6452057d1252, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:06,404 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:16:06,404 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:16:06,405 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cf846f96-bdba-4a1e-a644-85f6d2b65221, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:16:06,406 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cf846f96-bdba-4a1e-a644-85f6d2b65221, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:06,407 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cf846f96-bdba-4a1e-a644-85f6d2b65221, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:10,313 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:16:20,355 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:16:20,356 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9695a46b-153b-4fcd-948c-f18b3ba4a2cd, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:16:20,357 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9695a46b-153b-4fcd-948c-f18b3ba4a2cd, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:20,358 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9695a46b-153b-4fcd-948c-f18b3ba4a2cd, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:23,310 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:16:33,335 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:16:33,336 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9508285a-b0ee-4ef6-84e6-9e67870d1c29, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:16:33,336 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9508285a-b0ee-4ef6-84e6-9e67870d1c29, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:33,338 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9508285a-b0ee-4ef6-84e6-9e67870d1c29, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:34,018 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:16:36,874 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-06 10:16:36,874 - 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-06 10:16:36,890 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-06 10:16:36,891 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 04bc7fb8-2f9a-4ae2-9cf4-2d33fc6b4a84, localhost:11291): creating call state
2025-11-06 10:16:36,891 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 04bc7fb8-2f9a-4ae2-9cf4-2d33fc6b4a84, localhost:11291): request = { database: "/Root" }
2025-11-06 10:16:36,897 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 04bc7fb8-2f9a-4ae2-9cf4-2d33fc6b4a84, localhost:11291): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-ew6xquenpy.auto.internal" port: 11291 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-06 10:16:36,898 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-ew6xquenpy.auto.internal:11291, location 1, ssl: False>]>
2025-11-06 10:16:36,898 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:11291
2025-11-06 10:16:44,022 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:16:44,023 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 92076b55-0f07-4c98-841d-0b07c40928bf, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:16:44,024 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 92076b55-0f07-4c98-841d-0b07c40928bf, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `/Root/all_compression/all_supported_compression/zstd_compression/.sys/primary_index_stats` WHERE Activity == 1 AND EntityName = \"value\"" } stats_mode: STATS_MODE_NONE }
2025-11-06 10:16:44,025 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 92076b55-0f07-4c98-841d-0b07c40928bf, ghrun-ew6xquenpy.auto.internal:11291): response = { <_MultiThreadedRendezvous object> }
2025-11-06 10:16:44,698 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=
2025-11-06 10:16:44,703 - INFO - root - get_volumes_column: Table `/Root/all_compression/all_supported_compression/zstd_compression`, volumes `value` (8000000, 1465272)
2025-11-06 10:16:44,704 - INFO - root - test_all_supported_compression: compression in `/Root/all_compression/all_supported_compression/zstd_compression` 8092160 / 1465272: 5.522633340430992
2025-11-06 10:16:44,706 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-06 10:16:44,708 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 4f02b938-8f22-4820-bf76-1a910452e55e, ghrun-ew6xquenpy.auto.internal:11291): creating call state
2025-11-06 10:16:44,709 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 4f02b938-8f22-4820-bf76-1a910452e55e, ghrun-ew6xquenpy.auto.internal:11291): request = { session_id: "ydb://session/3?node_id=1&id=NTQzMmU4Y2MtNGQwNTJhZWEtNDkzM2YxNGQtZDlhYjRhZjA=" }
2025-11-06 10:16:44,713 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 4f02b938-8f22-4820-bf76-1a910452e55e, ghrun-ew6xquenpy.auto.internal:11291): response = { status: SUCCESS }
2025-11-06 10:16:44,715 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-06 10:16:44,715 - DEBUG - ydb.topic - close: Close topic client
2025-11-06 10:16:44,716 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-06 10:16:44,716 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:11291
2025-11-06 10:16:44,717 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-ew6xquenpy.auto.internal:11291
2025-11-06 10:16:44,721 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-06 10:16:44,718 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, f9ba1a88-28b7-4d70-97bd-71182df994f2, ghrun-ew6xquenpy.auto.internal:11291): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.CANCELLED
	details = "Locally cancelled by application!"
	debug_error_string = "None"
>
2025-11-06 10:16:56,746 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:11291/1
2025-11-06 10:16:56,747 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26713
2025-11-06 10:16:56,748 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2256
2025-11-06 10:16:56,749 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4927
2025-11-06 10:16:56,751 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11291
2025-11-06 10:16:56,751 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28139
