2025-11-10 21:36:45,168 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-10 21:36:45,169 - INFO - ya.test - pytest_runtest_setup: test_log_uniform
2025-11-10 21:36:45,169 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-10 21:36:45,169 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-10 21:36:45,171 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-10 21:36:45,171 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-10 21:36:45,171 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-10 21:36:45,172 - DEBUG - ya.test - execute: Command pid: 3889037
2025-11-10 21:36:45,625 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) rc: 0
2025-11-10 21:36:45,625 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) elapsed time (sec): 0.4521012306213379
2025-11-10 21:36:45,625 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) idrss: 0
2025-11-10 21:36:45,626 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) inblock: 0
2025-11-10 21:36:45,626 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) isrss: 0
2025-11-10 21:36:45,626 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) ixrss: 0
2025-11-10 21:36:45,626 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) majflt: 0
2025-11-10 21:36:45,626 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) maxrss: 541136
2025-11-10 21:36:45,626 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) minflt: 16166
2025-11-10 21:36:45,626 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) msgrcv: 0
2025-11-10 21:36:45,626 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) msgsnd: 0
2025-11-10 21:36:45,626 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) nivcsw: 0
2025-11-10 21:36:45,626 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) nsignals: 0
2025-11-10 21:36:45,627 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) nswap: 0
2025-11-10 21:36:45,627 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) nvcsw: 13
2025-11-10 21:36:45,627 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) oublock: 8
2025-11-10 21:36:45,627 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) stime: 0.300041
2025-11-10 21:36:45,627 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) utime: 0.104014
2025-11-10 21:36:45,627 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) wtime: 0.453
2025-11-10 21:36:45,627 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) output:
Git info:
    Commit: d951e6677c38ebdd6569e6affda038b7ec0dcd73
    Branch: 
    Author: Nikolay Shestakov <n.shestakov@gmail.com>
    Summary: Merge c2bda7a48744aababd08d376c19d40e30411b1c2 into 4e0685800275111225607954e1f1bf0783794f3b

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-k6w43pqfnq
    Host information: 
        Linux ghrun-k6w43pqfnq 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 -UNDEBUG -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 -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -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-10 21:36:45,627 - DEBUG - ya.test - _save_outputs: Command (pid 3889037) errors:

2025-11-10 21:36:45,628 - INFO - __tests__.test_log_scenario - _setup_ydb: Git info:
    Commit: d951e6677c38ebdd6569e6affda038b7ec0dcd73
    Branch: 
    Author: Nikolay Shestakov <n.shestakov@gmail.com>
    Summary: Merge c2bda7a48744aababd08d376c19d40e30411b1c2 into 4e0685800275111225607954e1f1bf0783794f3b

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-k6w43pqfnq
    Host information: 
        Linux ghrun-k6w43pqfnq 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 -UNDEBUG -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 -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -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-10 21:36:45,740 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydbd/ydbd
2025-11-10 21:36:45,748 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28932
2025-11-10 21:36:45,749 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/0mg8/0002cc/r3tmp/kikimr_cluster_caqb1xky
2025-11-10 21:36:45,794 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydbd/ydbd
2025-11-10 21:36:45,795 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10846
2025-11-10 21:36:45,795 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17076
2025-11-10 21:36:45,795 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6385
2025-11-10 21:36:45,796 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/64126
2025-11-10 21:36:45,796 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/kikimr_configs"
2025-11-10 21:36:45,797 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/0mg8/0002cc/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/0mg8/0002cc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/logfile_5xa64u5q.log --grpc-port=10846 --mon-port=17076 --ic-port=28932
2025-11-10 21:36:45,797 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:10846/1, disk_size 68719476736
2025-11-10 21:36:45,797 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/kikimr_configs"
2025-11-10 21:36:45,797 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/0mg8/0002cc/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/0mg8/0002cc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/logfile_5xa64u5q.log --grpc-port=10846 --mon-port=17076 --ic-port=28932
2025-11-10 21:36:45,798 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/logfile_5xa64u5q.log', '--grpc-port=10846', '--mon-port=17076', '--ic-port=28932')' in '/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1' (no wait)
2025-11-10 21:36:45,800 - DEBUG - ya.test - execute: Command pid: 3889468
2025-11-10 21:36:45,800 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:10846/1
2025-11-10 21:36:45,801 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-10 21:36:45,802 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-10 21:36:45,802 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-10 21:36:45,803 - DEBUG - ya.test - execute: Command pid: 3889469
2025-11-10 21:36:46,208 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) rc: 0
2025-11-10 21:36:46,208 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) elapsed time (sec): 0.4044992923736572
2025-11-10 21:36:46,208 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) idrss: 0
2025-11-10 21:36:46,208 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) inblock: 0
2025-11-10 21:36:46,209 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) isrss: 0
2025-11-10 21:36:46,209 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) ixrss: 0
2025-11-10 21:36:46,209 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) majflt: 0
2025-11-10 21:36:46,209 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) maxrss: 541140
2025-11-10 21:36:46,209 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) minflt: 16165
2025-11-10 21:36:46,209 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) msgrcv: 0
2025-11-10 21:36:46,210 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) msgsnd: 0
2025-11-10 21:36:46,210 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) nivcsw: 2
2025-11-10 21:36:46,210 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) nsignals: 0
2025-11-10 21:36:46,210 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) nswap: 0
2025-11-10 21:36:46,210 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) nvcsw: 13
2025-11-10 21:36:46,211 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) oublock: 8
2025-11-10 21:36:46,211 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) stime: 0.253334
2025-11-10 21:36:46,211 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) utime: 0.102917
2025-11-10 21:36:46,211 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) wtime: 0.405
2025-11-10 21:36:46,211 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) output:
Git info:
    Commit: d951e6677c38ebdd6569e6affda038b7ec0dcd73
    Branch: 
    Author: Nikolay Shestakov <n.shestakov@gmail.com>
    Summary: Merge c2bda7a48744aababd08d376c19d40e30411b1c2 into 4e0685800275111225607954e1f1bf0783794f3b

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-k6w43pqfnq
    Host information: 
        Linux ghrun-k6w43pqfnq 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 -UNDEBUG -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 -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -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-10 21:36:46,211 - DEBUG - ya.test - _save_outputs: Command (pid 3889469) errors:

2025-11-10 21:36:46,212 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: d951e6677c38ebdd6569e6affda038b7ec0dcd73
    Branch: 
    Author: Nikolay Shestakov <n.shestakov@gmail.com>
    Summary: Merge c2bda7a48744aababd08d376c19d40e30411b1c2 into 4e0685800275111225607954e1f1bf0783794f3b
2025-11-10 21:36:48,055 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydbd/ydbd
2025-11-10 21:36:48,055 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10846', '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: 28932\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-10 21:36:48,056 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-10 21:36:48,057 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-10 21:36:48,057 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10846', '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: 28932\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-10 21:36:48,058 - DEBUG - ya.test - execute: Command pid: 3891204
2025-11-10 21:36:48,475 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) rc: 0
2025-11-10 21:36:48,475 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) elapsed time (sec): 0.415804386138916
2025-11-10 21:36:48,476 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) idrss: 0
2025-11-10 21:36:48,476 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) inblock: 0
2025-11-10 21:36:48,476 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) isrss: 0
2025-11-10 21:36:48,476 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) ixrss: 0
2025-11-10 21:36:48,476 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) majflt: 0
2025-11-10 21:36:48,477 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) maxrss: 549368
2025-11-10 21:36:48,477 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) minflt: 18307
2025-11-10 21:36:48,477 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) msgrcv: 0
2025-11-10 21:36:48,477 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) msgsnd: 0
2025-11-10 21:36:48,477 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) nivcsw: 5
2025-11-10 21:36:48,478 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) nsignals: 0
2025-11-10 21:36:48,478 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) nswap: 0
2025-11-10 21:36:48,478 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) nvcsw: 140
2025-11-10 21:36:48,478 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) oublock: 8
2025-11-10 21:36:48,478 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) stime: 0.258582
2025-11-10 21:36:48,478 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) utime: 0.114925
2025-11-10 21:36:48,479 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) wtime: 0.417
2025-11-10 21:36:48,479 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-10 21:36:48,479 - DEBUG - ya.test - _save_outputs: Command (pid 3891204) errors:

2025-11-10 21:36:48,480 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydbd/ydbd
2025-11-10 21:36:48,480 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10846', '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-10 21:36:48,481 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-10 21:36:48,482 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-10 21:36:48,482 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:10846', '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-10 21:36:48,483 - DEBUG - ya.test - execute: Command pid: 3891468
2025-11-10 21:36:48,910 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) rc: 0
2025-11-10 21:36:48,910 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) elapsed time (sec): 0.42558884620666504
2025-11-10 21:36:48,910 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) idrss: 0
2025-11-10 21:36:48,911 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) inblock: 0
2025-11-10 21:36:48,911 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) isrss: 0
2025-11-10 21:36:48,911 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) ixrss: 0
2025-11-10 21:36:48,911 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) majflt: 0
2025-11-10 21:36:48,911 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) maxrss: 549420
2025-11-10 21:36:48,911 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) minflt: 18307
2025-11-10 21:36:48,912 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) msgrcv: 0
2025-11-10 21:36:48,912 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) msgsnd: 0
2025-11-10 21:36:48,912 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) nivcsw: 0
2025-11-10 21:36:48,912 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) nsignals: 0
2025-11-10 21:36:48,912 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) nswap: 0
2025-11-10 21:36:48,912 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) nvcsw: 131
2025-11-10 21:36:48,913 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) oublock: 8
2025-11-10 21:36:48,913 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) stime: 0.24786699999999998
2025-11-10 21:36:48,913 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) utime: 0.135927
2025-11-10 21:36:48,913 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) wtime: 0.427
2025-11-10 21:36:48,913 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-10 21:36:48,913 - DEBUG - ya.test - _save_outputs: Command (pid 3891468) errors:

2025-11-10 21:36:48,914 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-10 21:36:48,929 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-10 21:36:48,940 - 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-10 21:36:48,942 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:36:48,944 - 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-10 21:36:48,950 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-10 21:36:48,950 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 3d0aff9d-08da-400d-a224-8e669bef5113, localhost:10846): creating call state
2025-11-10 21:36:48,951 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 3d0aff9d-08da-400d-a224-8e669bef5113, localhost:10846): request = { database: "/Root" }
2025-11-10 21:36:48,954 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 3d0aff9d-08da-400d-a224-8e669bef5113, localhost:10846): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-k6w43pqfnq.auto.internal" port: 10846 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-10 21:36:48,954 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-k6w43pqfnq.auto.internal:10846, location 1, ssl: False>]>
2025-11-10 21:36:48,955 - 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-10 21:36:48,963 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_uniform)
2025-11-10 21:36:48,963 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydb/ydb
2025-11-10 21:36:48,963 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydb/ydb -e grpc://localhost:10846 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-11-10 21:36:48,964 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-11-10 21:36:48,964 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-11-10 21:36:48,965 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:10846', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-11-10 21:36:48,965 - DEBUG - ya.test - execute: Command pid: 3891830
2025-11-10 21:36:49,270 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) rc: 0
2025-11-10 21:36:49,271 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) elapsed time (sec): 0.3041994571685791
2025-11-10 21:36:49,271 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) idrss: 0
2025-11-10 21:36:49,271 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) inblock: 0
2025-11-10 21:36:49,271 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) isrss: 0
2025-11-10 21:36:49,271 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) ixrss: 0
2025-11-10 21:36:49,271 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) majflt: 0
2025-11-10 21:36:49,272 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) maxrss: 166372
2025-11-10 21:36:49,272 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) minflt: 3544
2025-11-10 21:36:49,272 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) msgrcv: 0
2025-11-10 21:36:49,272 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) msgsnd: 0
2025-11-10 21:36:49,272 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) nivcsw: 3
2025-11-10 21:36:49,272 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) nsignals: 0
2025-11-10 21:36:49,272 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) nswap: 0
2025-11-10 21:36:49,273 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) nvcsw: 203
2025-11-10 21:36:49,273 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) oublock: 8
2025-11-10 21:36:49,273 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) stime: 0.025154
2025-11-10 21:36:49,273 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) utime: 0.046116
2025-11-10 21:36:49,273 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) wtime: 0.305
2025-11-10 21:36:49,273 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) output:
Init tables ...
Init tables ...Ok

2025-11-10 21:36:49,273 - DEBUG - ya.test - _save_outputs: Command (pid 3891830) errors:

2025-11-10 21:36:49,274 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-10 21:36:49,274 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydb/ydb -e grpc://localhost:10846 -d /Root workload log --path log run bulk_upsert --seconds 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-11-10 21:36:49,275 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-11-10 21:36:49,275 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-11-10 21:36:49,276 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:10846', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-11-10 21:36:49,276 - DEBUG - ya.test - execute: Command pid: 3892185
2025-11-10 21:37:19,535 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-10 21:37:19,535 - DEBUG - ya.test - _save_outputs: Command (pid 3892185) rc: 0
2025-11-10 21:37:19,536 - DEBUG - ya.test - _save_outputs: Command (pid 3892185) elapsed time (sec): 30.258657932281494
2025-11-10 21:37:19,536 - DEBUG - ya.test - _save_outputs: Command (pid 3892185) wtime: 30.26
2025-11-10 21:37:19,536 - DEBUG - ya.test - _save_outputs: Command (pid 3892185) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	    473	473	0	0	20	25	32	40
2	    433	433	0	0	21	31	38	54
3	    346	346	0	0	27	57	64	68
4	    347	347	0	0	23	59	67	71
5	    346	346	0	0	25	53	67	87
6	    444	444	0	0	20	34	48	50
7	    420	420	0	0	21	36	44	44
8	    429	429	0	0	21	44	68	71
9	    421	421	0	0	20	46	66	70
10	    367	367	0	0	21	57	77	80
11	    457	457	0	0	20	30	39	46
12	    474	474	0	0	20	26	28	32
13	    457	457	0	0	20	28	47	56
14	    475	475	0	0	20	24	26	28
15	    453	453	0	0	21	28	36	47
16	    386	386	0	0	23	45	59	64
17	    458	458	0	0	20	30	36	39
18	    468	468	0	0	20	26	34	36
19	    467	467	0	0	20	26	30	33
20	    486	486	0	0	20	22	22	23
21	    488	488	0	0	20	21	22	25
22	    486	486	0	0	20	22	23	27
23	    432	432	0	0	21	32	38	39
24	    340	340	0	0	24	59	71	75
25	    288	288	0	0	30	57	66	72
26	    262	262	0	0	34	76	81	89
27	    233	233	0	0	37	79	89	92
28	    302	302	0	0	27	71	90	97
29	    173	173	0	0	51	103	116	124
30	    197	197	0	0	50	94	101	106

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	  11808	393.6	0	0	21	50	76	124

2025-11-10 21:37:19,536 - DEBUG - ya.test - _save_outputs: Command (pid 3892185) errors:

2025-11-10 21:37:19,537 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:19,537 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, dcc77788-5a82-4f0e-9117-cf7b402efe33, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:19,538 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, dcc77788-5a82-4f0e-9117-cf7b402efe33, ghrun-k6w43pqfnq.auto.internal:10846): request = {  }
2025-11-10 21:37:19,547 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, dcc77788-5a82-4f0e-9117-cf7b402efe33, ghrun-k6w43pqfnq.auto.internal:10846): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZmE5MTBhMjItYTA2NTMyMzUtMmMyMTgyMzItNmYyY2QzMWI=" node_id: 1 }
2025-11-10 21:37:19,548 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 493825ec-2154-4b39-94f3-e0787b64ad25, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:19,548 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 493825ec-2154-4b39-94f3-e0787b64ad25, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=ZmE5MTBhMjItYTA2NTMyMzUtMmMyMTgyMzItNmYyY2QzMWI=" }
2025-11-10 21:37:19,559 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 493825ec-2154-4b39-94f3-e0787b64ad25, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:19,577 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZmE5MTBhMjItYTA2NTMyMzUtMmMyMTgyMzItNmYyY2QzMWI=
2025-11-10 21:37:19,577 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ac4e2acf-286d-402c-af4c-e6d9f96547de, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:19,578 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ac4e2acf-286d-402c-af4c-e6d9f96547de, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=ZmE5MTBhMjItYTA2NTMyMzUtMmMyMTgyMzItNmYyY2QzMWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-10 21:37:19,578 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ac4e2acf-286d-402c-af4c-e6d9f96547de, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:20,340 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmE5MTBhMjItYTA2NTMyMzUtMmMyMTgyMzItNmYyY2QzMWI=
2025-11-10 21:37:20,341 - INFO - root - test_log_uniform: Count rows after insert 11585 before wait
2025-11-10 21:37:20,341 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmE5MTBhMjItYTA2NTMyMzUtMmMyMTgyMzItNmYyY2QzMWI=
2025-11-10 21:37:20,341 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dc4b5a8f-8908-47ae-8adc-adc9efea9fc7, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:20,342 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dc4b5a8f-8908-47ae-8adc-adc9efea9fc7, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=ZmE5MTBhMjItYTA2NTMyMzUtMmMyMTgyMzItNmYyY2QzMWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-10 21:37:20,343 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dc4b5a8f-8908-47ae-8adc-adc9efea9fc7, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:20,896 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmE5MTBhMjItYTA2NTMyMzUtMmMyMTgyMzItNmYyY2QzMWI=
2025-11-10 21:37:20,896 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmE5MTBhMjItYTA2NTMyMzUtMmMyMTgyMzItNmYyY2QzMWI=
2025-11-10 21:37:20,896 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b3461ee2-f3e7-4f16-8d21-2a77b51efbfd, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:20,900 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b3461ee2-f3e7-4f16-8d21-2a77b51efbfd, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=ZmE5MTBhMjItYTA2NTMyMzUtMmMyMTgyMzItNmYyY2QzMWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-10 21:37:20,901 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b3461ee2-f3e7-4f16-8d21-2a77b51efbfd, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:21,319 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmE5MTBhMjItYTA2NTMyMzUtMmMyMTgyMzItNmYyY2QzMWI=
2025-11-10 21:37:21,323 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-10 21:37:21,323 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydb/ydb -e grpc://localhost:10846 -d /Root workload log --path log run bulk_upsert --seconds 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-11-10 21:37:21,324 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-11-10 21:37:21,325 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-11-10 21:37:21,325 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:10846', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-11-10 21:37:21,339 - DEBUG - ya.test - execute: Command pid: 3916647
2025-11-10 21:37:21,347 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-11-10 21:37:21,348 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydb/ydb -e grpc://localhost:10846 -d /Root workload log --path log run insert --seconds 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-11-10 21:37:21,349 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.2
2025-11-10 21:37:21,349 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-11-10 21:37:21,349 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:10846', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'insert', '--seconds', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-11-10 21:37:21,367 - DEBUG - ya.test - execute: Command pid: 3916653
2025-11-10 21:37:21,375 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-11-10 21:37:21,375 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydb/ydb -e grpc://localhost:10846 -d /Root workload log --path log run upsert --seconds 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-11-10 21:37:21,376 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.3
2025-11-10 21:37:21,377 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-11-10 21:37:21,377 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:10846', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'upsert', '--seconds', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-11-10 21:37:21,382 - DEBUG - ya.test - execute: Command pid: 3916660
2025-11-10 21:37:21,392 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmE5MTBhMjItYTA2NTMyMzUtMmMyMTgyMzItNmYyY2QzMWI=
2025-11-10 21:37:21,393 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, efd2853c-a4ea-41c0-9794-34cb75dde8da, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,393 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, efd2853c-a4ea-41c0-9794-34cb75dde8da, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=ZmE5MTBhMjItYTA2NTMyMzUtMmMyMTgyMzItNmYyY2QzMWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 21:37:21,394 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, efd2853c-a4ea-41c0-9794-34cb75dde8da, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:21,402 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-11-10 21:37:21,403 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ead9d1c4-da01-4a05-b44a-db79d56a547b, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,404 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ead9d1c4-da01-4a05-b44a-db79d56a547b, ghrun-k6w43pqfnq.auto.internal:10846): request = {  }
2025-11-10 21:37:21,419 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ead9d1c4-da01-4a05-b44a-db79d56a547b, ghrun-k6w43pqfnq.auto.internal:10846): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZDg5YzM4OTEtMjY0OTVmNDItOTIxMjJhMGMtZWEzYjY3OGY=" node_id: 1 }
2025-11-10 21:37:21,420 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 7a9e7b6a-5e2b-4b58-9ac7-50cd810b3a96, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,420 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 7a9e7b6a-5e2b-4b58-9ac7-50cd810b3a96, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=ZDg5YzM4OTEtMjY0OTVmNDItOTIxMjJhMGMtZWEzYjY3OGY=" }
2025-11-10 21:37:21,421 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 7a9e7b6a-5e2b-4b58-9ac7-50cd810b3a96, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:21,443 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZDg5YzM4OTEtMjY0OTVmNDItOTIxMjJhMGMtZWEzYjY3OGY=
2025-11-10 21:37:21,444 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6ee69526-5788-4d14-875a-3c2abb42846c, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,444 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6ee69526-5788-4d14-875a-3c2abb42846c, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=ZDg5YzM4OTEtMjY0OTVmNDItOTIxMjJhMGMtZWEzYjY3OGY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 21:37:21,445 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6ee69526-5788-4d14-875a-3c2abb42846c, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:21,447 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 2 < 100, will create new one.
2025-11-10 21:37:21,447 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 36b1184c-84c7-4c8d-b8ae-ee2d8877669d, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,448 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 36b1184c-84c7-4c8d-b8ae-ee2d8877669d, ghrun-k6w43pqfnq.auto.internal:10846): request = {  }
2025-11-10 21:37:21,459 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 36b1184c-84c7-4c8d-b8ae-ee2d8877669d, ghrun-k6w43pqfnq.auto.internal:10846): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=N2Q5MjMyMDYtN2E4ODdlNWEtM2UwY2Q5ZC1kNGUxZGE0NQ==" node_id: 1 }
2025-11-10 21:37:21,460 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, afdee351-ea61-432e-9e8a-dfeb051623c5, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,460 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, afdee351-ea61-432e-9e8a-dfeb051623c5, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=N2Q5MjMyMDYtN2E4ODdlNWEtM2UwY2Q5ZC1kNGUxZGE0NQ==" }
2025-11-10 21:37:21,461 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, afdee351-ea61-432e-9e8a-dfeb051623c5, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:21,483 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=N2Q5MjMyMDYtN2E4ODdlNWEtM2UwY2Q5ZC1kNGUxZGE0NQ==
2025-11-10 21:37:21,484 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 738decac-96f6-425e-9f61-a78d8dab3003, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,484 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 738decac-96f6-425e-9f61-a78d8dab3003, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=N2Q5MjMyMDYtN2E4ODdlNWEtM2UwY2Q5ZC1kNGUxZGE0NQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 21:37:21,485 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 738decac-96f6-425e-9f61-a78d8dab3003, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:21,486 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 3 < 100, will create new one.
2025-11-10 21:37:21,486 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ee007622-37d6-4614-a1f5-732ac7de97f2, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,486 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ee007622-37d6-4614-a1f5-732ac7de97f2, ghrun-k6w43pqfnq.auto.internal:10846): request = {  }
2025-11-10 21:37:21,523 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ee007622-37d6-4614-a1f5-732ac7de97f2, ghrun-k6w43pqfnq.auto.internal:10846): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=Zjk1Nzk5ZjItZDNlYWI2NjUtNzcwMzVkOTQtNDVmZTYyZTY=" node_id: 1 }
2025-11-10 21:37:21,524 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, e6f8a025-7563-4be8-9443-fc6e58b52082, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,525 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, e6f8a025-7563-4be8-9443-fc6e58b52082, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=Zjk1Nzk5ZjItZDNlYWI2NjUtNzcwMzVkOTQtNDVmZTYyZTY=" }
2025-11-10 21:37:21,525 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, e6f8a025-7563-4be8-9443-fc6e58b52082, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:21,572 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=Zjk1Nzk5ZjItZDNlYWI2NjUtNzcwMzVkOTQtNDVmZTYyZTY=
2025-11-10 21:37:21,573 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e12fc358-2280-455d-9304-39161a55b779, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,573 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e12fc358-2280-455d-9304-39161a55b779, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=Zjk1Nzk5ZjItZDNlYWI2NjUtNzcwMzVkOTQtNDVmZTYyZTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 21:37:21,574 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e12fc358-2280-455d-9304-39161a55b779, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:21,575 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 4 < 100, will create new one.
2025-11-10 21:37:21,576 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, b3cc80fa-e872-457c-a9a7-65e9f12753ba, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,576 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, b3cc80fa-e872-457c-a9a7-65e9f12753ba, ghrun-k6w43pqfnq.auto.internal:10846): request = {  }
2025-11-10 21:37:21,600 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, b3cc80fa-e872-457c-a9a7-65e9f12753ba, ghrun-k6w43pqfnq.auto.internal:10846): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YjVmZDEwOTQtNjc1MjdjNDEtMjg2M2U1ZTUtOWZkODg1MTM=" node_id: 1 }
2025-11-10 21:37:21,601 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, d5fb5b64-8f82-48f2-9920-9ec2d3b0cde3, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,601 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, d5fb5b64-8f82-48f2-9920-9ec2d3b0cde3, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=YjVmZDEwOTQtNjc1MjdjNDEtMjg2M2U1ZTUtOWZkODg1MTM=" }
2025-11-10 21:37:21,602 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, d5fb5b64-8f82-48f2-9920-9ec2d3b0cde3, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:21,619 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YjVmZDEwOTQtNjc1MjdjNDEtMjg2M2U1ZTUtOWZkODg1MTM=
2025-11-10 21:37:21,620 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 545d4ce9-83fe-430d-80f1-614a88c88c97, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,620 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 545d4ce9-83fe-430d-80f1-614a88c88c97, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=YjVmZDEwOTQtNjc1MjdjNDEtMjg2M2U1ZTUtOWZkODg1MTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 21:37:21,621 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 545d4ce9-83fe-430d-80f1-614a88c88c97, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:21,622 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 5 < 100, will create new one.
2025-11-10 21:37:21,622 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, b5f55e66-49f9-4ac8-8009-71314434179d, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,622 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, b5f55e66-49f9-4ac8-8009-71314434179d, ghrun-k6w43pqfnq.auto.internal:10846): request = {  }
2025-11-10 21:37:21,688 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, b5f55e66-49f9-4ac8-8009-71314434179d, ghrun-k6w43pqfnq.auto.internal:10846): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YjEyMTM5MjgtODMzNWM0MzQtYjc3MWFiNzAtZTYwN2IxODY=" node_id: 1 }
2025-11-10 21:37:21,688 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, ff4bb3d8-7fec-489e-9c52-0f95024d12d0, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,688 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, ff4bb3d8-7fec-489e-9c52-0f95024d12d0, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=YjEyMTM5MjgtODMzNWM0MzQtYjc3MWFiNzAtZTYwN2IxODY=" }
2025-11-10 21:37:21,689 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, ff4bb3d8-7fec-489e-9c52-0f95024d12d0, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:21,735 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YjEyMTM5MjgtODMzNWM0MzQtYjc3MWFiNzAtZTYwN2IxODY=
2025-11-10 21:37:21,736 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 72424bb9-9de3-4b14-9107-91e4c5e17cad, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,736 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 72424bb9-9de3-4b14-9107-91e4c5e17cad, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=YjEyMTM5MjgtODMzNWM0MzQtYjc3MWFiNzAtZTYwN2IxODY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 21:37:21,737 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 72424bb9-9de3-4b14-9107-91e4c5e17cad, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:21,739 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 6 < 100, will create new one.
2025-11-10 21:37:21,739 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 79d6ffbf-cfc4-49d6-88e9-ccb289fa207f, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,740 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 79d6ffbf-cfc4-49d6-88e9-ccb289fa207f, ghrun-k6w43pqfnq.auto.internal:10846): request = {  }
2025-11-10 21:37:21,768 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 79d6ffbf-cfc4-49d6-88e9-ccb289fa207f, ghrun-k6w43pqfnq.auto.internal:10846): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NGY2YjUxYWQtOGY1ZTY4YTAtZTYzNjdkYzItM2MzY2U2OTU=" node_id: 1 }
2025-11-10 21:37:21,769 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 2429302a-d379-4f25-9c49-0ab685a91f5d, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,769 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 2429302a-d379-4f25-9c49-0ab685a91f5d, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=NGY2YjUxYWQtOGY1ZTY4YTAtZTYzNjdkYzItM2MzY2U2OTU=" }
2025-11-10 21:37:21,770 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 2429302a-d379-4f25-9c49-0ab685a91f5d, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:21,826 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NGY2YjUxYWQtOGY1ZTY4YTAtZTYzNjdkYzItM2MzY2U2OTU=
2025-11-10 21:37:21,827 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d0b91c88-8c94-463b-950a-1444a28b0994, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,827 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d0b91c88-8c94-463b-950a-1444a28b0994, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=NGY2YjUxYWQtOGY1ZTY4YTAtZTYzNjdkYzItM2MzY2U2OTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 21:37:21,828 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d0b91c88-8c94-463b-950a-1444a28b0994, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:21,828 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 7 < 100, will create new one.
2025-11-10 21:37:21,829 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 9a47859e-1cd0-464a-83d1-05bcb2e4cd2f, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,829 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 9a47859e-1cd0-464a-83d1-05bcb2e4cd2f, ghrun-k6w43pqfnq.auto.internal:10846): request = {  }
2025-11-10 21:37:21,859 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 9a47859e-1cd0-464a-83d1-05bcb2e4cd2f, ghrun-k6w43pqfnq.auto.internal:10846): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YjNjN2ZlNWEtYTVjNDJiN2ItNTMwMjEzNDgtMzMwMzY4Yzg=" node_id: 1 }
2025-11-10 21:37:21,860 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 229aeb0f-c01c-40fe-91b5-db0b4b0ddefe, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,860 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 229aeb0f-c01c-40fe-91b5-db0b4b0ddefe, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=YjNjN2ZlNWEtYTVjNDJiN2ItNTMwMjEzNDgtMzMwMzY4Yzg=" }
2025-11-10 21:37:21,861 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 229aeb0f-c01c-40fe-91b5-db0b4b0ddefe, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:21,919 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YjNjN2ZlNWEtYTVjNDJiN2ItNTMwMjEzNDgtMzMwMzY4Yzg=
2025-11-10 21:37:21,920 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 582e659d-5d58-4b7a-8891-c0f95d86f425, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,920 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 582e659d-5d58-4b7a-8891-c0f95d86f425, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=YjNjN2ZlNWEtYTVjNDJiN2ItNTMwMjEzNDgtMzMwMzY4Yzg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 21:37:21,921 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 582e659d-5d58-4b7a-8891-c0f95d86f425, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:21,923 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 8 < 100, will create new one.
2025-11-10 21:37:21,924 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 10d90c45-42cd-4de5-a350-125b2d583c85, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,924 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 10d90c45-42cd-4de5-a350-125b2d583c85, ghrun-k6w43pqfnq.auto.internal:10846): request = {  }
2025-11-10 21:37:21,964 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 10d90c45-42cd-4de5-a350-125b2d583c85, ghrun-k6w43pqfnq.auto.internal:10846): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NzJjZWNmNTctNTk0YTNlOWEtMzE4NDAxNzktYmMxY2I0YWM=" node_id: 1 }
2025-11-10 21:37:21,972 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 61699c0d-c42c-4aec-adb2-d8593c627901, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:21,972 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 61699c0d-c42c-4aec-adb2-d8593c627901, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=NzJjZWNmNTctNTk0YTNlOWEtMzE4NDAxNzktYmMxY2I0YWM=" }
2025-11-10 21:37:21,973 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 61699c0d-c42c-4aec-adb2-d8593c627901, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:22,023 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NzJjZWNmNTctNTk0YTNlOWEtMzE4NDAxNzktYmMxY2I0YWM=
2025-11-10 21:37:22,024 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9a19c33f-07cc-4d89-b4ee-f441244a7cf7, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:22,025 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9a19c33f-07cc-4d89-b4ee-f441244a7cf7, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=NzJjZWNmNTctNTk0YTNlOWEtMzE4NDAxNzktYmMxY2I0YWM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 21:37:22,025 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9a19c33f-07cc-4d89-b4ee-f441244a7cf7, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:22,031 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 9 < 100, will create new one.
2025-11-10 21:37:22,032 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ba72d09f-22b7-438f-8707-061d9e1ae619, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:22,032 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ba72d09f-22b7-438f-8707-061d9e1ae619, ghrun-k6w43pqfnq.auto.internal:10846): request = {  }
2025-11-10 21:37:22,075 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ba72d09f-22b7-438f-8707-061d9e1ae619, ghrun-k6w43pqfnq.auto.internal:10846): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=Y2VlNzllZjMtY2ZlYTdjNTItOTQyYzgwNTgtZDgwZDJmZDU=" node_id: 1 }
2025-11-10 21:37:22,076 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 2eb5dc85-8948-4ad3-a4aa-d0e3776d7fe3, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:22,076 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 2eb5dc85-8948-4ad3-a4aa-d0e3776d7fe3, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=Y2VlNzllZjMtY2ZlYTdjNTItOTQyYzgwNTgtZDgwZDJmZDU=" }
2025-11-10 21:37:22,077 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 2eb5dc85-8948-4ad3-a4aa-d0e3776d7fe3, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:22,143 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=Y2VlNzllZjMtY2ZlYTdjNTItOTQyYzgwNTgtZDgwZDJmZDU=
2025-11-10 21:37:22,144 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9dc8eab5-4a53-4518-8857-b407ad9edb21, ghrun-k6w43pqfnq.auto.internal:10846): creating call state
2025-11-10 21:37:22,144 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9dc8eab5-4a53-4518-8857-b407ad9edb21, ghrun-k6w43pqfnq.auto.internal:10846): request = { session_id: "ydb://session/3?node_id=1&id=Y2VlNzllZjMtY2ZlYTdjNTItOTQyYzgwNTgtZDgwZDJmZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 21:37:22,145 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9dc8eab5-4a53-4518-8857-b407ad9edb21, ghrun-k6w43pqfnq.auto.internal:10846): response = { <_MultiThreadedRendezvous object> }
2025-11-10 21:37:30,282 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, efd2853c-a4ea-41c0-9794-34cb75dde8da, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer  {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T21:37:30.270136245+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 21:37:30,283 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,283 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmE5MTBhMjItYTA2NTMyMzUtMmMyMTgyMzItNmYyY2QzMWI=
2025-11-10 21:37:30,283 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 7a9e7b6a-5e2b-4b58-9ac7-50cd810b3a96, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer ipv4:127.0.1.1:10846 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T21:37:30.270159079+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 21:37:30,284 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,284 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 545d4ce9-83fe-430d-80f1-614a88c88c97, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer  {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-10T21:37:30.270278986+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-10 21:37:30,285 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,285 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjVmZDEwOTQtNjc1MjdjNDEtMjg2M2U1ZTUtOWZkODg1MTM=
2025-11-10 21:37:30,285 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, ff4bb3d8-7fec-489e-9c52-0f95024d12d0, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer ipv4:127.0.1.1:10846 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T21:37:30.270292823+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 21:37:30,285 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,286 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 9dc8eab5-4a53-4518-8857-b407ad9edb21, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer  {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-10T21:37:30.270423055+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-10 21:37:30,286 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,286 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2VlNzllZjMtY2ZlYTdjNTItOTQyYzgwNTgtZDgwZDJmZDU=
2025-11-10 21:37:30,286 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 72424bb9-9de3-4b14-9107-91e4c5e17cad, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer  {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T21:37:30.270306328+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 21:37:30,287 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,287 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 21:37:30,292 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 61699c0d-c42c-4aec-adb2-d8593c627901, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer ipv4:127.0.1.1:10846 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T21:37:30.27038603+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 21:37:30,299 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,293 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 493825ec-2154-4b39-94f3-e0787b64ad25, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer ipv4:127.0.1.1:10846 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-10T21:37:30.2700041+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-10 21:37:30,422 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,293 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 2429302a-d379-4f25-9c49-0ab685a91f5d, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer ipv4:127.0.1.1:10846 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-10T21:37:30.270319377+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-10 21:37:30,422 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,293 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, d5fb5b64-8f82-48f2-9920-9ec2d3b0cde3, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer ipv4:127.0.1.1:10846 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-10T21:37:30.270243302+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-10 21:37:30,423 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,293 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:37:30,424 - 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-10 21:37:30,293 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 6ee69526-5788-4d14-875a-3c2abb42846c, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer  {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-10T21:37:30.270173186+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-10 21:37:30,293 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 2eb5dc85-8948-4ad3-a4aa-d0e3776d7fe3, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer ipv4:127.0.1.1:10846 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-10T21:37:30.270411111+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-10 21:37:30,295 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 738decac-96f6-425e-9f61-a78d8dab3003, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer  {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T21:37:30.270200069+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 21:37:30,295 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 582e659d-5d58-4b7a-8891-c0f95d86f425, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer  {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T21:37:30.270369611+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 21:37:30,295 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 9a19c33f-07cc-4d89-b4ee-f441244a7cf7, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer  {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T21:37:30.270398758+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 21:37:30,295 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, d0b91c88-8c94-463b-950a-1444a28b0994, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer  {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-10T21:37:30.270331476+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-10 21:37:30,292 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, e6f8a025-7563-4be8-9443-fc6e58b52082, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer ipv4:127.0.1.1:10846 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T21:37:30.27021315+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 21:37:30,294 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 229aeb0f-c01c-40fe-91b5-db0b4b0ddefe, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer ipv4:127.0.1.1:10846 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T21:37:30.27034602+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 21:37:30,475 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,294 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, afdee351-ea61-432e-9e8a-dfeb051623c5, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer ipv4:127.0.1.1:10846 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T21:37:30.270186196+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 21:37:30,476 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,427 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,477 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 21:37:30,455 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,460 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,477 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 21:37:30,460 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,478 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 21:37:30,460 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,478 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 21:37:30,461 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,459 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,478 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 21:37:30,294 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmE5MTBhMjItYTA2NTMyMzUtMmMyMTgyMzItNmYyY2QzMWI=
2025-11-10 21:37:30,293 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, e12fc358-2280-455d-9304-39161a55b779, ghrun-k6w43pqfnq.auto.internal:10846): received error, <_MultiThreadedRendezvous of RPC that terminated with:
	status = StatusCode.UNAVAILABLE
	details = "recvmsg:Connection reset by peer"
	debug_error_string = "UNKNOWN:Error received from peer  {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T21:37:30.270230032+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 21:37:30,479 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 21:37:30,479 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 21:37:30,487 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-k6w43pqfnq.auto.internal:10846
2025-11-10 21:37:30,506 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 21:37:30,502 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 9 < 100, will create new one.
2025-11-10 21:37:30,511 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 21:37:30,511 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 8 < 100, will create new one.
2025-11-10 21:37:30,515 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 21:37:30,523 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 7 < 100, will create new one.
2025-11-10 21:37:30,523 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 21:37:30,524 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 6 < 100, will create new one.
2025-11-10 21:37:30,524 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 21:37:30,524 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 5 < 100, will create new one.
2025-11-10 21:37:30,524 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 21:37:30,539 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 4 < 100, will create new one.
2025-11-10 21:37:30,543 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 21:37:30,543 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 3 < 100, will create new one.
2025-11-10 21:37:30,544 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 21:37:30,544 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 2 < 100, will create new one.
2025-11-10 21:37:30,544 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 21:37:30,567 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-11-10 21:37:31,715 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 21:37:31,715 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:31,731 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:31,851 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:32,106 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:32,123 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:32,133 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:32,194 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:32,391 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:32,415 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:33,975 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:34,651 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:35,115 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:35,245 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:35,334 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:35,443 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:35,485 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:35,645 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:35,687 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:39,920 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:40,110 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:40,303 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:40,435 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:37:40,563 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:37:41,202 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:41,203 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:37:41,203 - 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-10 21:37:41,327 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:41,659 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:42,411 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:42,703 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:43,263 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:51,215 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:37:51,332 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:37:51,674 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-10 21:37:51,675 - DEBUG - ya.test - _save_outputs: Command (pid 3916660) rc: 0
2025-11-10 21:37:51,675 - DEBUG - ya.test - _save_outputs: Command (pid 3916660) elapsed time (sec): 30.29208278656006
2025-11-10 21:37:51,675 - DEBUG - ya.test - _save_outputs: Command (pid 3916660) wtime: 30.297
2025-11-10 21:37:51,676 - DEBUG - ya.test - _save_outputs: Command (pid 3916660) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	      0	0	0	10	0	0	0	0
3	      3	3	0	10	659	659	659	659
4	     27	27	0	0	447	707	751	751
5	     30	30	0	0	317	371	387	387
6	     40	40	0	0	275	349	377	377
7	     43	43	0	0	227	311	313	313
8	     57	57	0	0	154	259	269	275
9	      0	0	0	240	0	0	0	0
10	      0	0	0	770	0	0	0	0
11	      0	0	0	780	0	0	0	0
12	      0	0	0	800	0	0	0	0
13	      0	0	0	760	0	0	0	0
14	      0	0	0	740	0	0	0	0
15	      0	0	0	680	0	0	0	0
16	      0	0	0	760	0	0	0	0
17	      0	0	0	779	0	0	0	0
18	      0	0	0	821	0	0	0	0
19	      0	0	0	749	0	0	0	0
20	      0	0	0	770	0	0	0	0
21	      0	0	0	780	0	0	0	0
22	      0	0	0	930	0	0	0	0
23	      0	0	0	770	0	0	0	0
24	      0	0	0	790	0	0	0	0
25	      0	0	0	770	0	0	0	0
26	      0	0	0	800	0	0	0	0
27	      0	0	0	840	0	0	0	0
28	      0	0	0	830	0	0	0	0
29	      0	0	0	780	0	0	0	0
30	      0	0	0	841	0	0	0	0

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    200	6.66667	0	16800	259	505	687	751

2025-11-10 21:37:51,676 - DEBUG - ya.test - _save_outputs: Command (pid 3916660) errors:

2025-11-10 21:37:51,707 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-10 21:37:51,708 - DEBUG - ya.test - _save_outputs: Command (pid 3916647) rc: 0
2025-11-10 21:37:51,708 - DEBUG - ya.test - _save_outputs: Command (pid 3916647) elapsed time (sec): 30.367873668670654
2025-11-10 21:37:51,708 - DEBUG - ya.test - _save_outputs: Command (pid 3916647) wtime: 30.382
2025-11-10 21:37:51,708 - DEBUG - ya.test - _save_outputs: Command (pid 3916647) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	     11	11	0	0	595	695	727	727
2	      0	0	0	0	0	0	0	0
3	     14	14	0	0	1847	1951	1967	1967
4	     36	36	0	0	315	459	479	479
5	     36	36	0	0	275	333	341	341
6	     46	46	0	0	211	279	287	287
7	     52	52	0	0	180	259	261	277
8	     74	74	0	0	115	220	247	247
9	      0	0	0	156	0	0	0	0
10	      0	0	0	770	0	0	0	0
11	      0	0	0	780	0	0	0	0
12	      0	0	0	758	0	0	0	0
13	      0	0	0	715	0	0	0	0
14	      0	0	0	659	0	0	0	0
15	      0	0	0	770	0	0	0	0
16	      0	0	0	860	0	0	0	0
17	      0	0	0	779	0	0	0	0
18	      0	0	0	810	0	0	0	0
19	      0	0	0	770	0	0	0	0
20	      0	0	0	820	0	0	0	0
21	      0	0	0	842	0	0	0	0
22	      0	0	0	758	0	0	0	0
23	      0	0	0	800	0	0	0	0
24	      0	0	0	840	0	0	0	0
25	      0	0	0	837	0	0	0	0
26	      0	0	0	780	0	0	0	0
27	      0	0	0	790	0	0	0	0
28	      0	0	0	870	0	0	0	0
29	      0	0	0	830	0	0	0	0
30	      0	0	0	715	0	0	0	0

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    269	8.96667	0	16709	212	679	1935	1967

2025-11-10 21:37:51,708 - DEBUG - ya.test - _save_outputs: Command (pid 3916647) errors:

2025-11-10 21:37:51,716 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-10 21:37:51,716 - DEBUG - ya.test - _save_outputs: Command (pid 3916653) rc: 0
2025-11-10 21:37:51,716 - DEBUG - ya.test - _save_outputs: Command (pid 3916653) elapsed time (sec): 30.34466576576233
2025-11-10 21:37:51,717 - DEBUG - ya.test - _save_outputs: Command (pid 3916653) wtime: 30.366
2025-11-10 21:37:51,717 - DEBUG - ya.test - _save_outputs: Command (pid 3916653) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	      0	0	0	10	0	0	0	0
3	      0	0	0	10	0	0	0	0
4	      0	0	0	10	0	0	0	0
5	      0	0	0	10	0	0	0	0
6	      0	0	0	10	0	0	0	0
7	      0	0	0	10	0	0	0	0
8	      0	0	0	10	0	0	0	0
9	      0	0	0	291	0	0	0	0
10	      0	0	0	800	0	0	0	0
11	      0	0	0	790	0	0	0	0
12	      0	0	0	810	0	0	0	0
13	      0	0	0	780	0	0	0	0
14	      0	0	0	763	0	0	0	0
15	      0	0	0	760	0	0	0	0
16	      0	0	0	800	0	0	0	0
17	      0	0	0	820	0	0	0	0
18	      0	0	0	730	0	0	0	0
19	      0	0	0	837	0	0	0	0
20	      0	0	0	970	0	0	0	0
21	      0	0	0	810	0	0	0	0
22	      0	0	0	820	0	0	0	0
23	      0	0	0	790	0	0	0	0
24	      0	0	0	700	0	0	0	0
25	      0	0	0	760	0	0	0	0
26	      0	0	0	680	0	0	0	0
27	      0	0	0	880	0	0	0	0
28	      0	0	0	760	0	0	0	0
29	      0	0	0	790	0	0	0	0
30	      0	0	0	730	0	0	0	0

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	      0	0	0	16941	0	0	0	0

2025-11-10 21:37:51,717 - DEBUG - ya.test - _save_outputs: Command (pid 3916653) errors:

2025-11-10 21:37:52,307 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:52,308 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:37:52,308 - 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-10 21:37:52,507 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:52,795 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:52,903 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:53,755 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:55,195 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:55,447 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:56,631 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:37:57,311 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:38:02,327 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:38:02,427 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:38:03,458 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:38:03,459 - 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-10 21:38:08,500 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:38:12,523 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:38:13,475 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:38:13,579 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:38:15,515 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:38:15,515 - 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-10 21:38:16,915 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:38:17,051 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:38:17,386 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:38:19,259 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:38:21,063 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:38:23,095 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:38:25,522 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:38:25,640 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:38:26,443 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:38:26,447 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:38:26,447 - 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-10 21:38:36,459 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:38:36,640 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:38:38,435 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:38:38,435 - 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-10 21:38:48,451 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:38:48,639 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:38:50,627 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:38:50,628 - 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-10 21:38:58,867 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:39:00,635 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:39:00,772 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:39:02,243 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:39:02,247 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:39:02,247 - 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-10 21:39:09,171 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:39:09,987 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:39:11,088 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:39:12,171 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:39:12,267 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:39:12,403 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:39:13,931 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:39:13,931 - 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-10 21:39:19,267 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:39:19,403 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:39:22,183 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:39:23,943 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:39:24,095 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:39:25,327 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:39:25,328 - 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-10 21:39:35,343 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:39:35,472 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:39:37,203 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:39:37,204 - 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-10 21:39:47,223 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:39:47,360 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:39:48,507 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:39:48,508 - 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-10 21:39:49,955 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:39:52,603 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:39:58,515 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:39:58,671 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:39:59,175 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:39:59,179 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:39:59,179 - 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-10 21:39:59,595 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:39:59,859 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:40:00,107 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:40:05,775 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:40:09,199 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:40:09,336 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:40:10,359 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:40:10,363 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:40:10,363 - 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-10 21:40:19,779 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:40:20,383 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:40:20,527 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:40:22,003 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:40:22,004 - 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-10 21:40:28,332 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:40:32,019 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:40:32,159 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:40:32,491 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:40:32,492 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:40:32,492 - 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-10 21:40:37,035 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:40:37,311 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:40:42,503 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:40:42,636 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:40:42,899 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:40:42,903 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:40:42,903 - 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-10 21:40:43,695 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:40:46,135 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:40:52,923 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:40:53,012 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:40:54,179 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:40:54,180 - 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-10 21:41:02,089 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:41:04,195 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:41:04,264 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:41:06,067 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:41:06,067 - 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-10 21:41:14,543 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:41:16,083 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:41:16,267 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:41:16,775 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:41:16,779 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:41:16,779 - 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-10 21:41:22,519 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:41:25,807 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:41:26,795 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:41:26,947 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:41:28,007 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:41:28,008 - 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-10 21:41:30,767 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:41:32,427 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:41:35,731 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:41:38,023 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:41:38,147 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:41:38,731 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:41:38,732 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:41:38,732 - 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-10 21:41:48,183 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:41:48,747 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:41:48,868 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:41:50,463 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:41:50,463 - 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-10 21:42:00,483 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:42:00,594 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:42:02,327 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:42:02,327 - 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-10 21:42:04,491 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 21:42:12,345 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:42:12,500 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:42:14,455 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 21:42:14,455 - 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-10 21:42:20,931 - ERROR - ya.test - logreport: ydb/tests/olap/test_log_scenario.py:235: in test_log_uniform
    thread.join()
ydb/tests/olap/common/thread_helper.py:18: in join
    raise self.exc
ydb/tests/olap/common/thread_helper.py:11: in run
    self.ret = self._target(*self._args, **self._kwargs)
ydb/tests/olap/test_log_scenario.py:147: in aggregation_query
    self.ydb_client.query(f"SELECT COUNT(*) FROM `{self.table_name}` ")
ydb/tests/olap/common/ydb_client.py:24: in query
    return self.session_pool.execute_with_retries(query=statement, settings=request_settings)
contrib/python/ydb/py3/ydb/query/pool.py:252: in execute_with_retries
    return retry_operation_sync(wrapped_callee, retry_settings)
contrib/python/ydb/py3/ydb/retries.py:135: in retry_operation_sync
    for next_opt in opt_generator:
contrib/python/ydb/py3/ydb/retries.py:130: in retry_operation_impl
    raise status
contrib/python/ydb/py3/ydb/retries.py:96: in retry_operation_impl
    result = YdbRetryOperationFinalResult(callee(*args, **kwargs))
contrib/python/ydb/py3/ydb/query/pool.py:248: in wrapped_callee
    with self.checkout(timeout=retry_settings.max_session_acquire_timeout) as session:
contrib/python/ydb/py3/ydb/query/pool.py:331: in __enter__
    self._session = self._pool.acquire(self._timeout)
contrib/python/ydb/py3/ydb/query/pool.py:111: in acquire
    session = self._create_new_session(time_left)
contrib/python/ydb/py3/ydb/query/pool.py:63: in _create_new_session
    session.create(settings=BaseRequestSettings().with_timeout(timeout))
contrib/python/ydb/py3/ydb/query/session.py:301: in create
    self._create_call(settings=settings)
contrib/python/ydb/py3/ydb/query/session.py:171: in _create_call
    return self._driver(
contrib/python/ydb/py3/ydb/tracing.py:70: in wrapper
    return f(self, *args, **kwargs)
contrib/python/ydb/py3/ydb/pool.py:459: in __call__
    connection = self._store.get(preferred_endpoint)
contrib/python/ydb/py3/ydb/tracing.py:70: in wrapper
    return f(self, *args, **kwargs)
contrib/python/ydb/py3/ydb/pool.py:144: in get
    raise issues.ConnectionLost("Couldn't find valid connection")
E   ydb.issues.ConnectionLost: Couldn't find valid connection
2025-11-10 21:42:20,934 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-10 21:42:20,935 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-10 21:42:20,939 - DEBUG - ydb.topic - close: Close topic client
2025-11-10 21:42:20,939 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-10 21:42:20,939 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:42:24,471 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:10846
2025-11-10 21:42:24,488 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:10846". Check endpoint correctness.
2025-11-10 21:42:24,488 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-10 21:42:24,504 - DEBUG - library.python.cores - recover_core_dump_file: hostname = 'ghrun-k6w43pqfnq'
2025-11-10 21:42:24,504 - DEBUG - library.python.cores - recover_core_dump_file: rlimit_core = '(0, -1)'
2025-11-10 21:42:24,504 - DEBUG - library.python.cores - recover_core_dump_file: core_pattern = '|/usr/share/apport/apport -p%p -s%s -c%c -d%d -P%P -u%u -g%g -F%F -- %E'
2025-11-10 21:42:24,505 - DEBUG - library.python.cores - recover_core_dump_file: core_uses_pid = '1'
2025-11-10 21:42:24,505 - DEBUG - library.python.cores - recover_core_dump_file: Core dump dir (/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1) permission mask: 0o40755 (expected: 0o41777 (0o40000-dir, 0o1000-sticky bit))
2025-11-10 21:42:24,506 - DEBUG - library.python.cores - recover_core_dump_file: Search for core dump files match pattern 'core.3889468' in '/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1'
2025-11-10 21:42:24,506 - DEBUG - library.python.cores - recover_core_dump_file: Matched core dump files (0/3): [] (mismatched samples: stderr, logfile_5xa64u5q.log, stdout)
2025-11-10 21:42:24,506 - WARNING - library.python.cores - recover_core_dump_file: Core dump dir doesn't exist: /coredumps
2025-11-10 21:42:24,507 - WARNING - library.python.cores - recover_core_dump_file: Core dump dir doesn't exist: /var/tmp/cores
2025-11-10 21:42:24,511 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:10846/1
2025-11-10 21:42:24,512 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/64126
2025-11-10 21:42:24,512 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6385
2025-11-10 21:42:24,512 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17076
2025-11-10 21:42:24,513 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10846
2025-11-10 21:42:24,513 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28932
2025-11-10 21:42:24,609 - ERROR - ya.test - logreport: ydb/tests/olap/test_log_scenario.py:123: in teardown_class
    cls.cluster.stop()
ydb/tests/library/harness/kikimr_runner.py:694: in stop
    raise daemon.SeveralDaemonErrors(saved_exceptions)
E   ydb.tests.library.harness.daemon.SeveralDaemonErrors: Daemon failed with message: Unexpectedly finished before stop.
E   Process exit_code = -6.
E   Stdout file name: 
E   /home/runner/.ya/build/build_root/0mg8/0002cc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/stdout
E   Stderr file name: 
E   /home/runner/.ya/build/build_root/0mg8/0002cc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/stderr
E   Stderr content:
E   
E   GRpc memory quota was set but disabled due to issues with grpc quoter, to enable it use EnableGRpcMemoryQuota option
E   VERIFY failed (2025-11-10T21:37:22.494927Z): source_id=123;tablet_id=72075186224037924;verification=lhs.type_id() == rhs.type_id();fline=compare.h:82;
E   ydb/library/actors/core/log.cpp:857
E   ~TVerifyFormattedRecordWriter(): requirement false failed
E   0. /-S/util/system/yassert.cpp:86: InternalPanicImpl @ 0xA78E8E5
E   1. /-S/util/system/yassert.cpp:55: Panic @ 0xA786E26
E   2. /tmp//-S/ydb/library/actors/core/log.cpp:857: ~TVerifyFormattedRecordWriter @ 0xB614C78
E   3. /-S/ydb/library/formats/arrow/switch/compare.h:82: TypedCompare<false> @ 0xD90D33B
E   4. /-S/ydb/core/formats/arrow/reader/position.h:83: CompareImpl @ 0x9DE23C8
E   5. /-S/ydb/core/formats/arrow/reader/position.h:471: Compare @ 0x9DE211F
E   6. /tmp//-S/ydb/core/formats/arrow/reader/position.cpp:36: FindBound @ 0xD8E894D
E   7. /tmp//-S/ydb/core/tx/columnshard/engines/predicate/filter.cpp:21: BuildFilter @ 0x141115CB
E   8. /tmp//-S/ydb/core/tx/columnshard/engines/reader/simple_reader/iterator/fetching.cpp:16: DoExecuteInplace @ 0x14D513C7
E   9. /-S/ydb/core/tx/columnshard/engines/reader/common_reader/common/script.h:47: ExecuteInplace @ 0x14CA8BD3
E   10. /tmp//-S/ydb/core/tx/columnshard/engines/reader/common_reader/common/script_cursor.cpp:36: Execute @ 0x14CA8BD3
E   11. /tmp//-S/ydb/core/tx/columnshard/engines/reader/common_reader/iterator/fetching.cpp:30: DoExecuteImpl @ 0x14CA59F3
E   12. /tmp//-S/ydb/core/tx/columnshard/engines/reader/common/conveyor_task.cpp:8: DoExecute @ 0x14CAC4A7
E   13. /tmp//-S/ydb/core/tx/conveyor/usage/abstract.cpp:16: Execute @ 0x1412EE48
E   14. /tmp//-S/ydb/core/tx/conveyor_composite/service/worker.cpp:17: ExecuteTask @ 0x1CB5A7A5
E   15. /tmp//-S/ydb/library/actors/core/actor.cpp:350: Receive @ 0xB5BFED7
E   16. /tmp//-S/ydb/library/actors/core/executor_thread.cpp:267: Execute @ 0xB605724
E   17. /tmp//-S/ydb/library/actors/core/executor_thread.cpp:455: operator() @ 0xB6097E4
E   18. /tmp//-S/ydb/library/actors/core/executor_thread.cpp:507: ProcessExecutorPool @ 0xB6093F0
E   19. /tmp//-S/ydb/library/actors/core/executor_thread.cpp:533: ThreadProc @ 0xB609FDE
E   20. /-S/util/system/thread.cpp:245: ThreadProxy @ 0xA79053C
E   21. ??:0: ?? @ 0x7F13A4D2AAC2
E   22. ??:0: ?? @ 0x7F13A4DBC8BF
2025-11-10 21:42:49,620 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/0mg8/0002cc/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:10846', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-11-10 21:42:49,631 - DEBUG - ya.test - execute: Command pid: 3952464
2025-11-10 21:42:49,879 - DEBUG - ya.test - _kill_process_tree: Killing process 3952464
2025-11-10 21:42:49,879 - DEBUG - ya.test - _nix_kill_process_tree: Killing process tree for pid 3952464 (bin:'error([Errno 2] No such file or directory: '/proc/3952464/exe')')
2025-11-10 21:42:49,880 - DEBUG - ya.test - try_to_send_signal: Error while sending signal 19 to pid 3952464: [Errno 3] No such process
2025-11-10 21:42:49,880 - DEBUG - ya.test - try_to_send_signal: Error while sending signal 9 to pid 3952464: [Errno 3] No such process
2025-11-10 21:42:49,880 - DEBUG - ya.test - try_to_send_signal: Error while sending signal 18 to pid 3952464: [Errno 3] No such process
2025-11-10 21:42:49,880 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-10 21:42:49,880 - DEBUG - ya.test - wait: Command exception: sys.meta_path is None, Python is likely shutting down
2025-11-10 21:42:49,881 - DEBUG - ya.test - _save_outputs: Command (pid 3952464) rc: 0
2025-11-10 21:42:49,881 - DEBUG - ya.test - _save_outputs: Command (pid 3952464) elapsed time (sec): 0.24912762641906738
2025-11-10 21:42:49,881 - DEBUG - ya.test - _save_outputs: Command (pid 3952464) wtime: 0.259
2025-11-10 21:42:49,881 - DEBUG - ya.test - _save_outputs: Command (pid 3952464) output:
Remove path log...

2025-11-10 21:42:49,881 - DEBUG - ya.test - _save_outputs: Command (pid 3952464) errors:
Status: TRANSPORT_UNAVAILABLE
Issues: 
<main>: Error: GRpc error: (14): connections to all backends failing; last error: UNKNOWN: ipv4:127.0.0.1:10846: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint localhost:10846
<main>: Error: Endpoint list is empty for database /Root, cluster endpoint localhost:10846.

