2025-11-11 15:37:18,198 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 15:37:18,198 - INFO - ya.test - pytest_runtest_setup: test_log_uniform
2025-11-11 15:37:18,198 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 15:37:18,198 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-11 15:37:18,207 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-11 15:37:18,207 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-11 15:37:18,208 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-11 15:37:18,232 - DEBUG - ya.test - execute: Command pid: 842203
2025-11-11 15:37:19,663 - DEBUG - ya.test - _save_outputs: Command (pid 842203) rc: 0
2025-11-11 15:37:19,664 - DEBUG - ya.test - _save_outputs: Command (pid 842203) elapsed time (sec): 1.3922083377838135
2025-11-11 15:37:19,664 - DEBUG - ya.test - _save_outputs: Command (pid 842203) idrss: 0
2025-11-11 15:37:19,664 - DEBUG - ya.test - _save_outputs: Command (pid 842203) inblock: 0
2025-11-11 15:37:19,664 - DEBUG - ya.test - _save_outputs: Command (pid 842203) isrss: 0
2025-11-11 15:37:19,664 - DEBUG - ya.test - _save_outputs: Command (pid 842203) ixrss: 0
2025-11-11 15:37:19,665 - DEBUG - ya.test - _save_outputs: Command (pid 842203) majflt: 0
2025-11-11 15:37:19,665 - DEBUG - ya.test - _save_outputs: Command (pid 842203) maxrss: 541144
2025-11-11 15:37:19,665 - DEBUG - ya.test - _save_outputs: Command (pid 842203) minflt: 16166
2025-11-11 15:37:19,665 - DEBUG - ya.test - _save_outputs: Command (pid 842203) msgrcv: 0
2025-11-11 15:37:19,665 - DEBUG - ya.test - _save_outputs: Command (pid 842203) msgsnd: 0
2025-11-11 15:37:19,665 - DEBUG - ya.test - _save_outputs: Command (pid 842203) nivcsw: 124
2025-11-11 15:37:19,665 - DEBUG - ya.test - _save_outputs: Command (pid 842203) nsignals: 0
2025-11-11 15:37:19,666 - DEBUG - ya.test - _save_outputs: Command (pid 842203) nswap: 0
2025-11-11 15:37:19,666 - DEBUG - ya.test - _save_outputs: Command (pid 842203) nvcsw: 15
2025-11-11 15:37:19,666 - DEBUG - ya.test - _save_outputs: Command (pid 842203) oublock: 8
2025-11-11 15:37:19,666 - DEBUG - ya.test - _save_outputs: Command (pid 842203) stime: 0.219831
2025-11-11 15:37:19,666 - DEBUG - ya.test - _save_outputs: Command (pid 842203) utime: 0.137841
2025-11-11 15:37:19,666 - DEBUG - ya.test - _save_outputs: Command (pid 842203) wtime: 1.417
2025-11-11 15:37:19,667 - DEBUG - ya.test - _save_outputs: Command (pid 842203) output:
Git info:
    Commit: b1fe97d9d8127d8f2b1d0c3f6dfd6bba81272e1b
    Branch: 
    Author: Nikolay Shestakov <n.shestakov@gmail.com>
    Summary: Merge 50d8af9ebc780945b43536873ee711ed2559af4b into c6a8dfb1a22b05b4afbbf1cd50c1ca5a0dcd4305

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-3ynj4chmly
    Host information: 
        Linux ghrun-3ynj4chmly 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/10226725676/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10226725676/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-11 15:37:19,667 - DEBUG - ya.test - _save_outputs: Command (pid 842203) errors:

2025-11-11 15:37:19,675 - INFO - __tests__.test_log_scenario - _setup_ydb: Git info:
    Commit: b1fe97d9d8127d8f2b1d0c3f6dfd6bba81272e1b
    Branch: 
    Author: Nikolay Shestakov <n.shestakov@gmail.com>
    Summary: Merge 50d8af9ebc780945b43536873ee711ed2559af4b into c6a8dfb1a22b05b4afbbf1cd50c1ca5a0dcd4305

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-3ynj4chmly
    Host information: 
        Linux ghrun-3ynj4chmly 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/10226725676/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10226725676/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-11 15:37:19,801 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydbd/ydbd
2025-11-11 15:37:19,803 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30448
2025-11-11 15:37:19,804 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/iofl/001efd/r3tmp/kikimr_cluster_aq8rfaq_
2025-11-11 15:37:20,066 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydbd/ydbd
2025-11-11 15:37:20,082 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/21594
2025-11-11 15:37:20,083 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22301
2025-11-11 15:37:20,083 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10292
2025-11-11 15:37:20,083 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27665
2025-11-11 15:37:20,084 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/iofl/001efd/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/kikimr_configs"
2025-11-11 15:37:20,084 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/iofl/001efd/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/iofl/001efd/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/logfile_cdt899a7.log --grpc-port=21594 --mon-port=22301 --ic-port=30448
2025-11-11 15:37:20,085 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:21594/1, disk_size 68719476736
2025-11-11 15:37:20,085 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/iofl/001efd/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/kikimr_configs"
2025-11-11 15:37:20,085 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/iofl/001efd/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/iofl/001efd/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/logfile_cdt899a7.log --grpc-port=21594 --mon-port=22301 --ic-port=30448
2025-11-11 15:37:20,086 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/iofl/001efd/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/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/iofl/001efd/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/logfile_cdt899a7.log', '--grpc-port=21594', '--mon-port=22301', '--ic-port=30448')' in '/home/runner/.ya/build/build_root/iofl/001efd/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1' (no wait)
2025-11-11 15:37:20,151 - DEBUG - ya.test - execute: Command pid: 843046
2025-11-11 15:37:20,152 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:21594/1
2025-11-11 15:37:20,153 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-11 15:37:20,154 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-11 15:37:20,154 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-11 15:37:20,158 - DEBUG - ya.test - execute: Command pid: 843057
2025-11-11 15:37:21,297 - DEBUG - ya.test - _save_outputs: Command (pid 843057) rc: 0
2025-11-11 15:37:21,297 - DEBUG - ya.test - _save_outputs: Command (pid 843057) elapsed time (sec): 1.1380527019500732
2025-11-11 15:37:21,297 - DEBUG - ya.test - _save_outputs: Command (pid 843057) idrss: 0
2025-11-11 15:37:21,298 - DEBUG - ya.test - _save_outputs: Command (pid 843057) inblock: 0
2025-11-11 15:37:21,298 - DEBUG - ya.test - _save_outputs: Command (pid 843057) isrss: 0
2025-11-11 15:37:21,298 - DEBUG - ya.test - _save_outputs: Command (pid 843057) ixrss: 0
2025-11-11 15:37:21,298 - DEBUG - ya.test - _save_outputs: Command (pid 843057) majflt: 0
2025-11-11 15:37:21,298 - DEBUG - ya.test - _save_outputs: Command (pid 843057) maxrss: 541152
2025-11-11 15:37:21,298 - DEBUG - ya.test - _save_outputs: Command (pid 843057) minflt: 16166
2025-11-11 15:37:21,298 - DEBUG - ya.test - _save_outputs: Command (pid 843057) msgrcv: 0
2025-11-11 15:37:21,299 - DEBUG - ya.test - _save_outputs: Command (pid 843057) msgsnd: 0
2025-11-11 15:37:21,299 - DEBUG - ya.test - _save_outputs: Command (pid 843057) nivcsw: 114
2025-11-11 15:37:21,299 - DEBUG - ya.test - _save_outputs: Command (pid 843057) nsignals: 0
2025-11-11 15:37:21,299 - DEBUG - ya.test - _save_outputs: Command (pid 843057) nswap: 0
2025-11-11 15:37:21,299 - DEBUG - ya.test - _save_outputs: Command (pid 843057) nvcsw: 13
2025-11-11 15:37:21,299 - DEBUG - ya.test - _save_outputs: Command (pid 843057) oublock: 8
2025-11-11 15:37:21,299 - DEBUG - ya.test - _save_outputs: Command (pid 843057) stime: 0.245826
2025-11-11 15:37:21,300 - DEBUG - ya.test - _save_outputs: Command (pid 843057) utime: 0.115349
2025-11-11 15:37:21,300 - DEBUG - ya.test - _save_outputs: Command (pid 843057) wtime: 1.142
2025-11-11 15:37:21,300 - DEBUG - ya.test - _save_outputs: Command (pid 843057) output:
Git info:
    Commit: b1fe97d9d8127d8f2b1d0c3f6dfd6bba81272e1b
    Branch: 
    Author: Nikolay Shestakov <n.shestakov@gmail.com>
    Summary: Merge 50d8af9ebc780945b43536873ee711ed2559af4b into c6a8dfb1a22b05b4afbbf1cd50c1ca5a0dcd4305

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-3ynj4chmly
    Host information: 
        Linux ghrun-3ynj4chmly 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/10226725676/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/10226725676/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-11 15:37:21,300 - DEBUG - ya.test - _save_outputs: Command (pid 843057) errors:

2025-11-11 15:37:21,300 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: b1fe97d9d8127d8f2b1d0c3f6dfd6bba81272e1b
    Branch: 
    Author: Nikolay Shestakov <n.shestakov@gmail.com>
    Summary: Merge 50d8af9ebc780945b43536873ee711ed2559af4b into c6a8dfb1a22b05b4afbbf1cd50c1ca5a0dcd4305
2025-11-11 15:37:27,297 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydbd/ydbd
2025-11-11 15:37:27,297 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:21594', '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: 30448\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-11 15:37:27,298 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-11 15:37:27,299 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-11 15:37:27,299 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:21594', '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: 30448\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-11 15:37:27,316 - DEBUG - ya.test - execute: Command pid: 846149
2025-11-11 15:37:28,802 - DEBUG - ya.test - _save_outputs: Command (pid 846149) rc: 0
2025-11-11 15:37:28,803 - DEBUG - ya.test - _save_outputs: Command (pid 846149) elapsed time (sec): 1.4855921268463135
2025-11-11 15:37:28,803 - DEBUG - ya.test - _save_outputs: Command (pid 846149) idrss: 0
2025-11-11 15:37:28,803 - DEBUG - ya.test - _save_outputs: Command (pid 846149) inblock: 0
2025-11-11 15:37:28,803 - DEBUG - ya.test - _save_outputs: Command (pid 846149) isrss: 0
2025-11-11 15:37:28,803 - DEBUG - ya.test - _save_outputs: Command (pid 846149) ixrss: 0
2025-11-11 15:37:28,803 - DEBUG - ya.test - _save_outputs: Command (pid 846149) majflt: 0
2025-11-11 15:37:28,804 - DEBUG - ya.test - _save_outputs: Command (pid 846149) maxrss: 549388
2025-11-11 15:37:28,804 - DEBUG - ya.test - _save_outputs: Command (pid 846149) minflt: 18309
2025-11-11 15:37:28,804 - DEBUG - ya.test - _save_outputs: Command (pid 846149) msgrcv: 0
2025-11-11 15:37:28,804 - DEBUG - ya.test - _save_outputs: Command (pid 846149) msgsnd: 0
2025-11-11 15:37:28,804 - DEBUG - ya.test - _save_outputs: Command (pid 846149) nivcsw: 153
2025-11-11 15:37:28,804 - DEBUG - ya.test - _save_outputs: Command (pid 846149) nsignals: 0
2025-11-11 15:37:28,804 - DEBUG - ya.test - _save_outputs: Command (pid 846149) nswap: 0
2025-11-11 15:37:28,805 - DEBUG - ya.test - _save_outputs: Command (pid 846149) nvcsw: 75
2025-11-11 15:37:28,805 - DEBUG - ya.test - _save_outputs: Command (pid 846149) oublock: 8
2025-11-11 15:37:28,805 - DEBUG - ya.test - _save_outputs: Command (pid 846149) stime: 0.250789
2025-11-11 15:37:28,805 - DEBUG - ya.test - _save_outputs: Command (pid 846149) utime: 0.142166
2025-11-11 15:37:28,805 - DEBUG - ya.test - _save_outputs: Command (pid 846149) wtime: 1.502
2025-11-11 15:37:28,805 - DEBUG - ya.test - _save_outputs: Command (pid 846149) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-11 15:37:28,805 - DEBUG - ya.test - _save_outputs: Command (pid 846149) errors:

2025-11-11 15:37:28,808 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydbd/ydbd
2025-11-11 15:37:28,808 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:21594', '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-11 15:37:28,809 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-11 15:37:28,810 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-11 15:37:28,810 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:21594', '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-11 15:37:28,832 - DEBUG - ya.test - execute: Command pid: 847014
2025-11-11 15:37:30,288 - DEBUG - ya.test - _save_outputs: Command (pid 847014) rc: 0
2025-11-11 15:37:30,289 - DEBUG - ya.test - _save_outputs: Command (pid 847014) elapsed time (sec): 1.4555776119232178
2025-11-11 15:37:30,289 - DEBUG - ya.test - _save_outputs: Command (pid 847014) idrss: 0
2025-11-11 15:37:30,289 - DEBUG - ya.test - _save_outputs: Command (pid 847014) inblock: 0
2025-11-11 15:37:30,289 - DEBUG - ya.test - _save_outputs: Command (pid 847014) isrss: 0
2025-11-11 15:37:30,290 - DEBUG - ya.test - _save_outputs: Command (pid 847014) ixrss: 0
2025-11-11 15:37:30,290 - DEBUG - ya.test - _save_outputs: Command (pid 847014) majflt: 0
2025-11-11 15:37:30,290 - DEBUG - ya.test - _save_outputs: Command (pid 847014) maxrss: 549404
2025-11-11 15:37:30,290 - DEBUG - ya.test - _save_outputs: Command (pid 847014) minflt: 18310
2025-11-11 15:37:30,290 - DEBUG - ya.test - _save_outputs: Command (pid 847014) msgrcv: 0
2025-11-11 15:37:30,290 - DEBUG - ya.test - _save_outputs: Command (pid 847014) msgsnd: 0
2025-11-11 15:37:30,291 - DEBUG - ya.test - _save_outputs: Command (pid 847014) nivcsw: 150
2025-11-11 15:37:30,291 - DEBUG - ya.test - _save_outputs: Command (pid 847014) nsignals: 0
2025-11-11 15:37:30,291 - DEBUG - ya.test - _save_outputs: Command (pid 847014) nswap: 0
2025-11-11 15:37:30,306 - DEBUG - ya.test - _save_outputs: Command (pid 847014) nvcsw: 74
2025-11-11 15:37:30,306 - DEBUG - ya.test - _save_outputs: Command (pid 847014) oublock: 8
2025-11-11 15:37:30,307 - DEBUG - ya.test - _save_outputs: Command (pid 847014) stime: 0.29325399999999996
2025-11-11 15:37:30,307 - DEBUG - ya.test - _save_outputs: Command (pid 847014) utime: 0.12371499999999999
2025-11-11 15:37:30,307 - DEBUG - ya.test - _save_outputs: Command (pid 847014) wtime: 1.477
2025-11-11 15:37:30,307 - DEBUG - ya.test - _save_outputs: Command (pid 847014) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-11 15:37:30,307 - DEBUG - ya.test - _save_outputs: Command (pid 847014) errors:

2025-11-11 15:37:30,308 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-11 15:37:30,376 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-11 15:37:30,400 - 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-11 15:37:30,410 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:37:30,411 - 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-11 15:37:30,456 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-11 15:37:30,457 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 208c8674-2205-4f5e-8dda-87f96e31df1b, localhost:21594): creating call state
2025-11-11 15:37:30,458 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 208c8674-2205-4f5e-8dda-87f96e31df1b, localhost:21594): request = { database: "/Root" }
2025-11-11 15:37:30,473 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 208c8674-2205-4f5e-8dda-87f96e31df1b, localhost:21594): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-3ynj4chmly.auto.internal" port: 21594 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-11 15:37:30,473 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-3ynj4chmly.auto.internal:21594, location 1, ssl: False>]>
2025-11-11 15:37:30,474 - 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-11 15:37:30,500 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:37:30,502 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_uniform)
2025-11-11 15:37:30,502 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydb/ydb
2025-11-11 15:37:30,503 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydb/ydb -e grpc://localhost:21594 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-11-11 15:37:30,504 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-11-11 15:37:30,504 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-11-11 15:37:30,504 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:21594', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-11-11 15:37:30,518 - DEBUG - ya.test - execute: Command pid: 847920
2025-11-11 15:37:31,561 - DEBUG - ya.test - _save_outputs: Command (pid 847920) rc: 0
2025-11-11 15:37:31,561 - DEBUG - ya.test - _save_outputs: Command (pid 847920) elapsed time (sec): 1.0266706943511963
2025-11-11 15:37:31,561 - DEBUG - ya.test - _save_outputs: Command (pid 847920) idrss: 0
2025-11-11 15:37:31,562 - DEBUG - ya.test - _save_outputs: Command (pid 847920) inblock: 0
2025-11-11 15:37:31,562 - DEBUG - ya.test - _save_outputs: Command (pid 847920) isrss: 0
2025-11-11 15:37:31,562 - DEBUG - ya.test - _save_outputs: Command (pid 847920) ixrss: 0
2025-11-11 15:37:31,562 - DEBUG - ya.test - _save_outputs: Command (pid 847920) majflt: 0
2025-11-11 15:37:31,562 - DEBUG - ya.test - _save_outputs: Command (pid 847920) maxrss: 172736
2025-11-11 15:37:31,562 - DEBUG - ya.test - _save_outputs: Command (pid 847920) minflt: 3542
2025-11-11 15:37:31,562 - DEBUG - ya.test - _save_outputs: Command (pid 847920) msgrcv: 0
2025-11-11 15:37:31,563 - DEBUG - ya.test - _save_outputs: Command (pid 847920) msgsnd: 0
2025-11-11 15:37:31,563 - DEBUG - ya.test - _save_outputs: Command (pid 847920) nivcsw: 54
2025-11-11 15:37:31,563 - DEBUG - ya.test - _save_outputs: Command (pid 847920) nsignals: 0
2025-11-11 15:37:31,563 - DEBUG - ya.test - _save_outputs: Command (pid 847920) nswap: 0
2025-11-11 15:37:31,563 - DEBUG - ya.test - _save_outputs: Command (pid 847920) nvcsw: 116
2025-11-11 15:37:31,567 - DEBUG - ya.test - _save_outputs: Command (pid 847920) oublock: 8
2025-11-11 15:37:31,568 - DEBUG - ya.test - _save_outputs: Command (pid 847920) stime: 0.030876999999999998
2025-11-11 15:37:31,568 - DEBUG - ya.test - _save_outputs: Command (pid 847920) utime: 0.049449
2025-11-11 15:37:31,568 - DEBUG - ya.test - _save_outputs: Command (pid 847920) wtime: 1.04
2025-11-11 15:37:31,568 - DEBUG - ya.test - _save_outputs: Command (pid 847920) output:
Init tables ...
Init tables ...Ok

2025-11-11 15:37:31,568 - DEBUG - ya.test - _save_outputs: Command (pid 847920) errors:

2025-11-11 15:37:31,568 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-11 15:37:31,569 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydb/ydb -e grpc://localhost:21594 -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-11 15:37:31,570 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-11-11 15:37:31,570 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-11-11 15:37:31,570 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:21594', '-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-11 15:37:31,589 - DEBUG - ya.test - execute: Command pid: 848238
2025-11-11 15:38:02,029 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 15:38:02,030 - DEBUG - ya.test - _save_outputs: Command (pid 848238) rc: 0
2025-11-11 15:38:02,030 - DEBUG - ya.test - _save_outputs: Command (pid 848238) elapsed time (sec): 30.43954634666443
2025-11-11 15:38:02,030 - DEBUG - ya.test - _save_outputs: Command (pid 848238) wtime: 30.459
2025-11-11 15:38:02,031 - DEBUG - ya.test - _save_outputs: Command (pid 848238) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	    159	159	0	0	55	101	104	112
2	    206	206	0	0	46	66	71	88
3	    186	186	0	0	51	76	88	93
4	    132	132	0	0	75	107	119	119
5	    181	181	0	0	53	80	86	101
6	    115	115	0	0	84	109	120	124
7	    156	156	0	0	63	96	101	109
8	    142	142	0	0	67	100	113	118
9	    116	116	0	0	84	114	120	129
10	    201	201	0	0	47	80	94	102
11	    199	199	0	0	45	76	87	88
12	    166	166	0	0	60	79	84	84
13	    179	179	0	0	53	92	98	103
14	    199	199	0	0	48	79	97	105
15	    183	183	0	0	49	77	83	85
16	    216	216	0	0	43	78	88	90
17	    132	132	0	0	78	113	123	126
18	    155	155	0	0	65	92	101	109
19	    131	131	0	0	80	109	121	122
20	    126	126	0	0	85	116	123	124
21	    160	160	0	0	58	98	110	119
22	    208	208	0	0	40	99	118	123
23	    145	145	0	0	70	100	114	119
24	    154	154	0	0	60	111	158	174
25	    178	178	0	0	52	88	96	98
26	    122	122	0	0	78	112	139	143
27	    201	201	0	0	48	76	82	85
28	    196	196	0	0	49	67	73	83
29	    220	220	0	0	45	67	75	79
30	    167	167	0	0	56	113	128	142

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	   5031	167.7	0	0	55	99	116	174

2025-11-11 15:38:02,031 - DEBUG - ya.test - _save_outputs: Command (pid 848238) errors:

2025-11-11 15:38:02,031 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:02,032 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ba8fdc18-f807-4cfd-842b-4847fbfed366, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:02,032 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ba8fdc18-f807-4cfd-842b-4847fbfed366, ghrun-3ynj4chmly.auto.internal:21594): request = {  }
2025-11-11 15:38:02,052 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ba8fdc18-f807-4cfd-842b-4847fbfed366, ghrun-3ynj4chmly.auto.internal:21594): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NDg4YzA1ZjctOWUyYTQ3OGUtNTlmN2E3YTItZGIwYzExZGQ=" node_id: 1 }
2025-11-11 15:38:02,053 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 701e6514-51d8-465b-9cc6-4acb1926fcca, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:02,054 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 701e6514-51d8-465b-9cc6-4acb1926fcca, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=NDg4YzA1ZjctOWUyYTQ3OGUtNTlmN2E3YTItZGIwYzExZGQ=" }
2025-11-11 15:38:02,076 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 701e6514-51d8-465b-9cc6-4acb1926fcca, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:02,116 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NDg4YzA1ZjctOWUyYTQ3OGUtNTlmN2E3YTItZGIwYzExZGQ=
2025-11-11 15:38:02,116 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b0d71045-765a-49a0-949e-8826a48a0f9d, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:02,117 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b0d71045-765a-49a0-949e-8826a48a0f9d, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=NDg4YzA1ZjctOWUyYTQ3OGUtNTlmN2E3YTItZGIwYzExZGQ=" 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-11 15:38:02,118 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b0d71045-765a-49a0-949e-8826a48a0f9d, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:02,867 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg4YzA1ZjctOWUyYTQ3OGUtNTlmN2E3YTItZGIwYzExZGQ=
2025-11-11 15:38:02,868 - INFO - root - test_log_uniform: Count rows after insert 4962 before wait
2025-11-11 15:38:02,868 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg4YzA1ZjctOWUyYTQ3OGUtNTlmN2E3YTItZGIwYzExZGQ=
2025-11-11 15:38:02,869 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ddca95d5-0aac-413b-a128-d799f676ef5d, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:02,869 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ddca95d5-0aac-413b-a128-d799f676ef5d, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=NDg4YzA1ZjctOWUyYTQ3OGUtNTlmN2E3YTItZGIwYzExZGQ=" 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-11 15:38:02,870 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ddca95d5-0aac-413b-a128-d799f676ef5d, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:03,297 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg4YzA1ZjctOWUyYTQ3OGUtNTlmN2E3YTItZGIwYzExZGQ=
2025-11-11 15:38:03,297 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg4YzA1ZjctOWUyYTQ3OGUtNTlmN2E3YTItZGIwYzExZGQ=
2025-11-11 15:38:03,298 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 47fa5629-599e-40ec-a16a-42f50d4f05b3, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:03,298 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 47fa5629-599e-40ec-a16a-42f50d4f05b3, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=NDg4YzA1ZjctOWUyYTQ3OGUtNTlmN2E3YTItZGIwYzExZGQ=" 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-11 15:38:03,299 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 47fa5629-599e-40ec-a16a-42f50d4f05b3, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:03,940 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg4YzA1ZjctOWUyYTQ3OGUtNTlmN2E3YTItZGIwYzExZGQ=
2025-11-11 15:38:03,943 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-11 15:38:03,943 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydb/ydb -e grpc://localhost:21594 -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-11 15:38:03,944 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-11-11 15:38:03,945 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-11-11 15:38:03,945 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:21594', '-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-11 15:38:03,960 - DEBUG - ya.test - execute: Command pid: 864040
2025-11-11 15:38:03,967 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-11-11 15:38:03,967 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydb/ydb -e grpc://localhost:21594 -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-11 15:38:03,968 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.2
2025-11-11 15:38:03,969 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-11-11 15:38:03,969 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:21594', '-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-11 15:38:03,990 - DEBUG - ya.test - execute: Command pid: 864053
2025-11-11 15:38:03,991 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-11-11 15:38:03,995 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydb/ydb -e grpc://localhost:21594 -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-11 15:38:03,996 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.3
2025-11-11 15:38:03,997 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-11-11 15:38:03,997 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/iofl/001efd/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:21594', '-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-11 15:38:04,007 - DEBUG - ya.test - execute: Command pid: 864081
2025-11-11 15:38:04,008 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDg4YzA1ZjctOWUyYTQ3OGUtNTlmN2E3YTItZGIwYzExZGQ=
2025-11-11 15:38:04,009 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c5ae2006-2dc3-493b-846b-33d7c6ebb87a, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,010 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c5ae2006-2dc3-493b-846b-33d7c6ebb87a, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=NDg4YzA1ZjctOWUyYTQ3OGUtNTlmN2E3YTItZGIwYzExZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:04,010 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c5ae2006-2dc3-493b-846b-33d7c6ebb87a, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:04,024 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-11-11 15:38:04,025 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a0f5f132-1049-45f8-bce2-d32cfbe7736c, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,025 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a0f5f132-1049-45f8-bce2-d32cfbe7736c, ghrun-3ynj4chmly.auto.internal:21594): request = {  }
2025-11-11 15:38:04,032 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a0f5f132-1049-45f8-bce2-d32cfbe7736c, ghrun-3ynj4chmly.auto.internal:21594): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=OGRlMzk3MC1iOGQwOTYxYS1iMTAzYmE3OC1hMTNjM2FkZA==" node_id: 1 }
2025-11-11 15:38:04,032 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, e1ea71fa-7277-4510-920f-eb6b684b3f69, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,033 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, e1ea71fa-7277-4510-920f-eb6b684b3f69, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=OGRlMzk3MC1iOGQwOTYxYS1iMTAzYmE3OC1hMTNjM2FkZA==" }
2025-11-11 15:38:04,033 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, e1ea71fa-7277-4510-920f-eb6b684b3f69, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:04,080 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=OGRlMzk3MC1iOGQwOTYxYS1iMTAzYmE3OC1hMTNjM2FkZA==
2025-11-11 15:38:04,081 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0281226f-9113-4350-83b0-a33494ded933, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,081 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0281226f-9113-4350-83b0-a33494ded933, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=OGRlMzk3MC1iOGQwOTYxYS1iMTAzYmE3OC1hMTNjM2FkZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:04,082 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0281226f-9113-4350-83b0-a33494ded933, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:04,083 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 2 < 100, will create new one.
2025-11-11 15:38:04,084 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ce0c94dc-6363-4cbe-bbcb-3d0b1b770db9, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,084 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ce0c94dc-6363-4cbe-bbcb-3d0b1b770db9, ghrun-3ynj4chmly.auto.internal:21594): request = {  }
2025-11-11 15:38:04,088 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ce0c94dc-6363-4cbe-bbcb-3d0b1b770db9, ghrun-3ynj4chmly.auto.internal:21594): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YTQyMzdkMmQtMmQ2OTI4MGItNmRiYTdiYmYtNmE4NzdiZGE=" node_id: 1 }
2025-11-11 15:38:04,088 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, c241afbe-9a6b-4e88-86ef-d95ab52b6473, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,088 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, c241afbe-9a6b-4e88-86ef-d95ab52b6473, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=YTQyMzdkMmQtMmQ2OTI4MGItNmRiYTdiYmYtNmE4NzdiZGE=" }
2025-11-11 15:38:04,089 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, c241afbe-9a6b-4e88-86ef-d95ab52b6473, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:04,135 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YTQyMzdkMmQtMmQ2OTI4MGItNmRiYTdiYmYtNmE4NzdiZGE=
2025-11-11 15:38:04,135 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b544baf5-61d6-4876-9603-2aad3e003172, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,136 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b544baf5-61d6-4876-9603-2aad3e003172, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=YTQyMzdkMmQtMmQ2OTI4MGItNmRiYTdiYmYtNmE4NzdiZGE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:04,137 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b544baf5-61d6-4876-9603-2aad3e003172, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:04,139 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 3 < 100, will create new one.
2025-11-11 15:38:04,139 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 4801ec91-5eae-4a49-a106-d88c1fd47367, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,140 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 4801ec91-5eae-4a49-a106-d88c1fd47367, ghrun-3ynj4chmly.auto.internal:21594): request = {  }
2025-11-11 15:38:04,142 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 4801ec91-5eae-4a49-a106-d88c1fd47367, ghrun-3ynj4chmly.auto.internal:21594): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NmFiNWU4MTMtMjBkNDNkMzAtYzE2MWYyZTYtNjAxNDU5NGE=" node_id: 1 }
2025-11-11 15:38:04,142 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 134f97aa-74fa-4f2b-ba3d-d50c9c2415c1, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,143 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 134f97aa-74fa-4f2b-ba3d-d50c9c2415c1, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=NmFiNWU4MTMtMjBkNDNkMzAtYzE2MWYyZTYtNjAxNDU5NGE=" }
2025-11-11 15:38:04,143 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 134f97aa-74fa-4f2b-ba3d-d50c9c2415c1, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:04,207 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NmFiNWU4MTMtMjBkNDNkMzAtYzE2MWYyZTYtNjAxNDU5NGE=
2025-11-11 15:38:04,208 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 581214e3-f567-4132-9ed5-baccc9688d3c, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,208 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 581214e3-f567-4132-9ed5-baccc9688d3c, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=NmFiNWU4MTMtMjBkNDNkMzAtYzE2MWYyZTYtNjAxNDU5NGE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:04,209 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 581214e3-f567-4132-9ed5-baccc9688d3c, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:04,209 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 4 < 100, will create new one.
2025-11-11 15:38:04,210 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 303fb7d5-3491-4203-946f-3d3a4541705d, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,210 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 303fb7d5-3491-4203-946f-3d3a4541705d, ghrun-3ynj4chmly.auto.internal:21594): request = {  }
2025-11-11 15:38:04,324 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 303fb7d5-3491-4203-946f-3d3a4541705d, ghrun-3ynj4chmly.auto.internal:21594): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YmY2OGI4ODAtMjJhZWIzYTEtYTg2YzA0NzctNmVkM2I5OGM=" node_id: 1 }
2025-11-11 15:38:04,325 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, ef34ec70-3e2b-456c-8db3-e9a1691f6d48, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,325 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, ef34ec70-3e2b-456c-8db3-e9a1691f6d48, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=YmY2OGI4ODAtMjJhZWIzYTEtYTg2YzA0NzctNmVkM2I5OGM=" }
2025-11-11 15:38:04,326 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, ef34ec70-3e2b-456c-8db3-e9a1691f6d48, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:04,376 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YmY2OGI4ODAtMjJhZWIzYTEtYTg2YzA0NzctNmVkM2I5OGM=
2025-11-11 15:38:04,376 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6fc8e3c4-5b33-404d-9491-40fd20aa9917, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,377 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6fc8e3c4-5b33-404d-9491-40fd20aa9917, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=YmY2OGI4ODAtMjJhZWIzYTEtYTg2YzA0NzctNmVkM2I5OGM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:04,377 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6fc8e3c4-5b33-404d-9491-40fd20aa9917, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:04,378 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 5 < 100, will create new one.
2025-11-11 15:38:04,378 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, c435428b-43b1-4764-8d7b-8b4ebad0cb92, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,378 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, c435428b-43b1-4764-8d7b-8b4ebad0cb92, ghrun-3ynj4chmly.auto.internal:21594): request = {  }
2025-11-11 15:38:04,436 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, c435428b-43b1-4764-8d7b-8b4ebad0cb92, ghrun-3ynj4chmly.auto.internal:21594): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YzUyOGQxOS05Zjk4NjQ1Zi03Y2Q1NmM2Yi0zZmNjMzIyZg==" node_id: 1 }
2025-11-11 15:38:04,436 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 31c145f9-378a-4583-a857-b7103b5593c1, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,437 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 31c145f9-378a-4583-a857-b7103b5593c1, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=YzUyOGQxOS05Zjk4NjQ1Zi03Y2Q1NmM2Yi0zZmNjMzIyZg==" }
2025-11-11 15:38:04,437 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 31c145f9-378a-4583-a857-b7103b5593c1, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:04,478 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YzUyOGQxOS05Zjk4NjQ1Zi03Y2Q1NmM2Yi0zZmNjMzIyZg==
2025-11-11 15:38:04,479 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8b0e7103-7488-439b-aec7-938bc1c630e7, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,479 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8b0e7103-7488-439b-aec7-938bc1c630e7, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=YzUyOGQxOS05Zjk4NjQ1Zi03Y2Q1NmM2Yi0zZmNjMzIyZg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:04,480 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8b0e7103-7488-439b-aec7-938bc1c630e7, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:04,481 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 6 < 100, will create new one.
2025-11-11 15:38:04,481 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, cc805b18-cf69-49a6-ad23-9949a7baaaa3, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,481 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, cc805b18-cf69-49a6-ad23-9949a7baaaa3, ghrun-3ynj4chmly.auto.internal:21594): request = {  }
2025-11-11 15:38:04,548 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, cc805b18-cf69-49a6-ad23-9949a7baaaa3, ghrun-3ynj4chmly.auto.internal:21594): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=OTIwZDUxMDctODU0Y2E1NmItZTE0NGNjMWMtMmYyOWI3YTg=" node_id: 1 }
2025-11-11 15:38:04,549 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, d01316c0-c587-4270-81cd-52882f0d435d, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,549 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, d01316c0-c587-4270-81cd-52882f0d435d, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=OTIwZDUxMDctODU0Y2E1NmItZTE0NGNjMWMtMmYyOWI3YTg=" }
2025-11-11 15:38:04,550 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, d01316c0-c587-4270-81cd-52882f0d435d, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:04,611 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=OTIwZDUxMDctODU0Y2E1NmItZTE0NGNjMWMtMmYyOWI3YTg=
2025-11-11 15:38:04,612 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7bafc779-8a16-4da8-9935-66006d923688, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,613 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7bafc779-8a16-4da8-9935-66006d923688, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=OTIwZDUxMDctODU0Y2E1NmItZTE0NGNjMWMtMmYyOWI3YTg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:04,613 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7bafc779-8a16-4da8-9935-66006d923688, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:04,615 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 7 < 100, will create new one.
2025-11-11 15:38:04,616 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, caa35904-ed64-4bb7-8710-7412b7014f0d, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,616 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, caa35904-ed64-4bb7-8710-7412b7014f0d, ghrun-3ynj4chmly.auto.internal:21594): request = {  }
2025-11-11 15:38:04,728 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, caa35904-ed64-4bb7-8710-7412b7014f0d, ghrun-3ynj4chmly.auto.internal:21594): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=OGYyNWQyNGItYTg5ZDc5MjAtNzI0ODAzYjUtNWRmMzljZmQ=" node_id: 1 }
2025-11-11 15:38:04,728 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 06dae739-f116-4baf-8b48-132ab0f0ea8d, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,729 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 06dae739-f116-4baf-8b48-132ab0f0ea8d, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=OGYyNWQyNGItYTg5ZDc5MjAtNzI0ODAzYjUtNWRmMzljZmQ=" }
2025-11-11 15:38:04,730 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 06dae739-f116-4baf-8b48-132ab0f0ea8d, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:04,840 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=OGYyNWQyNGItYTg5ZDc5MjAtNzI0ODAzYjUtNWRmMzljZmQ=
2025-11-11 15:38:04,840 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d1fcfcc9-5095-4a7d-b2ff-31806a0f355c, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,841 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d1fcfcc9-5095-4a7d-b2ff-31806a0f355c, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=OGYyNWQyNGItYTg5ZDc5MjAtNzI0ODAzYjUtNWRmMzljZmQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:04,841 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d1fcfcc9-5095-4a7d-b2ff-31806a0f355c, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:04,842 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 8 < 100, will create new one.
2025-11-11 15:38:04,842 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, bfc8307b-c520-4044-a051-33445b36559e, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,842 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, bfc8307b-c520-4044-a051-33445b36559e, ghrun-3ynj4chmly.auto.internal:21594): request = {  }
2025-11-11 15:38:04,957 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, bfc8307b-c520-4044-a051-33445b36559e, ghrun-3ynj4chmly.auto.internal:21594): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZWQ5OGU5LTQ0NjZmN2Y4LTE3Y2MzYjA5LWI3ODI1MDQ2" node_id: 1 }
2025-11-11 15:38:04,957 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, e32f0d61-6776-41dc-81ea-ba3d71200e4a, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:04,958 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, e32f0d61-6776-41dc-81ea-ba3d71200e4a, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=ZWQ5OGU5LTQ0NjZmN2Y4LTE3Y2MzYjA5LWI3ODI1MDQ2" }
2025-11-11 15:38:04,958 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, e32f0d61-6776-41dc-81ea-ba3d71200e4a, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:05,040 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZWQ5OGU5LTQ0NjZmN2Y4LTE3Y2MzYjA5LWI3ODI1MDQ2
2025-11-11 15:38:05,041 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be562f86-c160-4e02-8412-7e59a0c2fba7, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:05,041 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be562f86-c160-4e02-8412-7e59a0c2fba7, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=ZWQ5OGU5LTQ0NjZmN2Y4LTE3Y2MzYjA5LWI3ODI1MDQ2" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:05,042 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be562f86-c160-4e02-8412-7e59a0c2fba7, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:05,043 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 9 < 100, will create new one.
2025-11-11 15:38:05,044 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, f4baf5cd-170c-4294-a0da-9aad92ac4eba, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:05,053 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, f4baf5cd-170c-4294-a0da-9aad92ac4eba, ghrun-3ynj4chmly.auto.internal:21594): request = {  }
2025-11-11 15:38:05,116 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, f4baf5cd-170c-4294-a0da-9aad92ac4eba, ghrun-3ynj4chmly.auto.internal:21594): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZTRiNzMxZjktYzVjMDNiMjEtZDg3M2ZhZTgtZTM2NjQwNTk=" node_id: 1 }
2025-11-11 15:38:05,117 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 8d583c74-d5e7-40c8-a215-92d9a3cf4411, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:05,117 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 8d583c74-d5e7-40c8-a215-92d9a3cf4411, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=ZTRiNzMxZjktYzVjMDNiMjEtZDg3M2ZhZTgtZTM2NjQwNTk=" }
2025-11-11 15:38:05,118 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 8d583c74-d5e7-40c8-a215-92d9a3cf4411, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:05,180 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZTRiNzMxZjktYzVjMDNiMjEtZDg3M2ZhZTgtZTM2NjQwNTk=
2025-11-11 15:38:05,180 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ef69ca09-d939-4881-9926-8683e35ccc71, ghrun-3ynj4chmly.auto.internal:21594): creating call state
2025-11-11 15:38:05,181 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ef69ca09-d939-4881-9926-8683e35ccc71, ghrun-3ynj4chmly.auto.internal:21594): request = { session_id: "ydb://session/3?node_id=1&id=ZTRiNzMxZjktYzVjMDNiMjEtZDg3M2ZhZTgtZTM2NjQwNTk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 15:38:05,182 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ef69ca09-d939-4881-9926-8683e35ccc71, ghrun-3ynj4chmly.auto.internal:21594): response = { <_MultiThreadedRendezvous object> }
2025-11-11 15:38:14,399 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 0281226f-9113-4350-83b0-a33494ded933, ghrun-3ynj4chmly.auto.internal:21594): 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-11T15:38:14.395490593+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-11 15:38:14,400 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,400 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGRlMzk3MC1iOGQwOTYxYS1iMTAzYmE3OC1hMTNjM2FkZA==
2025-11-11 15:38:14,401 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:38:14,401 - 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-11 15:38:14,402 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 06dae739-f116-4baf-8b48-132ab0f0ea8d, ghrun-3ynj4chmly.auto.internal:21594): 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:21594 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-11T15:38:14.395638684+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-11 15:38:14,403 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,404 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, ef69ca09-d939-4881-9926-8683e35ccc71, ghrun-3ynj4chmly.auto.internal:21594): 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-11T15:38:14.395706592+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-11 15:38:14,404 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,404 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTRiNzMxZjktYzVjMDNiMjEtZDg3M2ZhZTgtZTM2NjQwNTk=
2025-11-11 15:38:14,404 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGRlMzk3MC1iOGQwOTYxYS1iMTAzYmE3OC1hMTNjM2FkZA==
2025-11-11 15:38:14,405 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGRlMzk3MC1iOGQwOTYxYS1iMTAzYmE3OC1hMTNjM2FkZA==
2025-11-11 15:38:14,406 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, c241afbe-9a6b-4e88-86ef-d95ab52b6473, ghrun-3ynj4chmly.auto.internal:21594): 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:21594 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-11T15:38:14.395503158+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-11 15:38:14,407 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,407 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, d01316c0-c587-4270-81cd-52882f0d435d, ghrun-3ynj4chmly.auto.internal:21594): 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:21594 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-11T15:38:14.395612747+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-11 15:38:14,408 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, c5ae2006-2dc3-493b-846b-33d7c6ebb87a, ghrun-3ynj4chmly.auto.internal:21594): 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-11T15:38:14.395438317+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-11 15:38:14,417 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,417 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDg4YzA1ZjctOWUyYTQ3OGUtNTlmN2E3YTItZGIwYzExZGQ=
2025-11-11 15:38:14,408 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 6fc8e3c4-5b33-404d-9491-40fd20aa9917, ghrun-3ynj4chmly.auto.internal:21594): 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-11T15:38:14.395574919+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-11 15:38:14,422 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,422 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmY2OGI4ODAtMjJhZWIzYTEtYTg2YzA0NzctNmVkM2I5OGM=
2025-11-11 15:38:14,424 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, b544baf5-61d6-4876-9603-2aad3e003172, ghrun-3ynj4chmly.auto.internal:21594): 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-11T15:38:14.395520159+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-11 15:38:14,424 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,425 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-11 15:38:14,409 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, d1fcfcc9-5095-4a7d-b2ff-31806a0f355c, ghrun-3ynj4chmly.auto.internal:21594): 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-11T15:38:14.395650855+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-11 15:38:14,425 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,426 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-11 15:38:14,409 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, e32f0d61-6776-41dc-81ea-ba3d71200e4a, ghrun-3ynj4chmly.auto.internal:21594): 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:21594 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-11T15:38:14.395666439+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-11 15:38:14,426 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,409 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 8b0e7103-7488-439b-aec7-938bc1c630e7, ghrun-3ynj4chmly.auto.internal:21594): 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-11T15:38:14.395601035+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-11 15:38:14,427 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,428 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzUyOGQxOS05Zjk4NjQ1Zi03Y2Q1NmM2Yi0zZmNjMzIyZg==
2025-11-11 15:38:14,409 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 581214e3-f567-4132-9ed5-baccc9688d3c, ghrun-3ynj4chmly.auto.internal:21594): 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-11T15:38:14.395549129+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-11 15:38:14,428 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,428 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NmFiNWU4MTMtMjBkNDNkMzAtYzE2MWYyZTYtNjAxNDU5NGE=
2025-11-11 15:38:14,411 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,410 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, e1ea71fa-7277-4510-920f-eb6b684b3f69, ghrun-3ynj4chmly.auto.internal:21594): 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:21594 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-11T15:38:14.395477181+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-11 15:38:14,429 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,408 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 134f97aa-74fa-4f2b-ba3d-d50c9c2415c1, ghrun-3ynj4chmly.auto.internal:21594): 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:21594 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-11T15:38:14.395532104+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-11 15:38:14,431 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,410 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, ef34ec70-3e2b-456c-8db3-e9a1691f6d48, ghrun-3ynj4chmly.auto.internal:21594): 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:21594 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-11T15:38:14.395560652+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-11 15:38:14,432 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,416 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 8d583c74-d5e7-40c8-a215-92d9a3cf4411, ghrun-3ynj4chmly.auto.internal:21594): 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:21594 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-11T15:38:14.395694177+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-11 15:38:14,432 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,409 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 7bafc779-8a16-4da8-9935-66006d923688, ghrun-3ynj4chmly.auto.internal:21594): 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-11T15:38:14.395625115+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-11 15:38:14,433 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,433 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-11 15:38:14,434 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-11 15:38:14,434 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 9 < 100, will create new one.
2025-11-11 15:38:14,434 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-11 15:38:14,434 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 8 < 100, will create new one.
2025-11-11 15:38:14,408 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 701e6514-51d8-465b-9cc6-4acb1926fcca, ghrun-3ynj4chmly.auto.internal:21594): 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:21594 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-11T15:38:14.395326255+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-11 15:38:14,435 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,418 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, be562f86-c160-4e02-8412-7e59a0c2fba7, ghrun-3ynj4chmly.auto.internal:21594): 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-11T15:38:14.39568243+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-11 15:38:14,436 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,436 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-11 15:38:14,421 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 31c145f9-378a-4583-a857-b7103b5593c1, ghrun-3ynj4chmly.auto.internal:21594): 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:21594 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-11T15:38:14.395588641+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-11 15:38:14,436 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-3ynj4chmly.auto.internal:21594
2025-11-11 15:38:14,437 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-11 15:38:14,437 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 7 < 100, will create new one.
2025-11-11 15:38:14,438 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-11 15:38:14,438 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 6 < 100, will create new one.
2025-11-11 15:38:14,438 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-11 15:38:14,438 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 5 < 100, will create new one.
2025-11-11 15:38:14,438 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-11 15:38:14,439 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 4 < 100, will create new one.
2025-11-11 15:38:14,439 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-11 15:38:14,439 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 3 < 100, will create new one.
2025-11-11 15:38:14,440 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-11 15:38:14,440 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 2 < 100, will create new one.
2025-11-11 15:38:14,441 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-11 15:38:14,441 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-11-11 15:38:15,559 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-11 15:38:15,560 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:15,651 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:15,798 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:15,823 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:16,047 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:16,058 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:16,112 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:16,128 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:16,402 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:16,433 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:17,765 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:17,883 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:18,234 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:18,779 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:19,312 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:19,447 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:19,619 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:19,694 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:19,694 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:20,043 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:21,958 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:23,187 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:24,263 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:24,431 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:38:24,536 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:38:24,875 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:24,883 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:38:24,891 - 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-11 15:38:24,944 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:24,974 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:25,563 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:25,826 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:26,123 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:26,594 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:34,149 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:34,282 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 15:38:34,283 - DEBUG - ya.test - _save_outputs: Command (pid 864040) rc: 0
2025-11-11 15:38:34,283 - DEBUG - ya.test - _save_outputs: Command (pid 864040) elapsed time (sec): 30.321953773498535
2025-11-11 15:38:34,283 - DEBUG - ya.test - _save_outputs: Command (pid 864040) wtime: 30.337
2025-11-11 15:38:34,283 - DEBUG - ya.test - _save_outputs: Command (pid 864040) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	     16	16	0	0	287	699	779	779
2	      5	5	0	0	903	1415	1415	1415
3	      1	1	0	0	1911	1911	1911	1911
4	     29	29	0	0	331	2351	2351	2351
5	     41	41	0	0	232	351	393	393
6	     46	46	0	0	199	269	277	277
7	     40	40	0	0	242	295	299	299
8	     33	33	0	0	277	317	335	335
9	      0	0	0	0	0	0	0	0
10	      0	0	0	0	0	0	0	0
11	      0	0	0	573	0	0	0	0
12	      0	0	0	803	0	0	0	0
13	      0	0	0	816	0	0	0	0
14	      0	0	0	770	0	0	0	0
15	      0	0	0	700	0	0	0	0
16	      0	0	0	675	0	0	0	0
17	      0	0	0	800	0	0	0	0
18	      0	0	0	850	0	0	0	0
19	      0	0	0	800	0	0	0	0
20	      0	0	0	780	0	0	0	0
21	      0	0	0	760	0	0	0	0
22	      0	0	0	717	0	0	0	0
23	      0	0	0	680	0	0	0	0
24	      0	0	0	780	0	0	0	0
25	      0	0	0	820	0	0	0	0
26	      0	0	0	754	0	0	0	0
27	      0	0	0	749	0	0	0	0
28	      0	0	0	749	0	0	0	0
29	      0	0	0	810	0	0	0	0
30	      0	0	0	850	0	0	0	0

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    211	7.03333	0	15236	248	1255	2335	2351

2025-11-11 15:38:34,283 - DEBUG - ya.test - _save_outputs: Command (pid 864040) errors:

2025-11-11 15:38:34,419 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 15:38:34,420 - DEBUG - ya.test - _save_outputs: Command (pid 864053) rc: 0
2025-11-11 15:38:34,420 - DEBUG - ya.test - _save_outputs: Command (pid 864053) elapsed time (sec): 30.429131984710693
2025-11-11 15:38:34,420 - DEBUG - ya.test - _save_outputs: Command (pid 864053) wtime: 30.45
2025-11-11 15:38:34,421 - DEBUG - ya.test - _save_outputs: Command (pid 864053) 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	0	0	0	0	0
10	      0	0	0	0	0	0	0	0
11	      0	0	0	818	0	0	0	0
12	      0	0	0	810	0	0	0	0
13	      0	0	0	759	0	0	0	0
14	      0	0	0	841	0	0	0	0
15	      0	0	0	760	0	0	0	0
16	      0	0	0	660	0	0	0	0
17	      0	0	0	700	0	0	0	0
18	      0	0	0	840	0	0	0	0
19	      0	0	0	840	0	0	0	0
20	      0	0	0	810	0	0	0	0
21	      0	0	0	778	0	0	0	0
22	      0	0	0	759	0	0	0	0
23	      0	0	0	800	0	0	0	0
24	      0	0	0	769	0	0	0	0
25	      0	0	0	860	0	0	0	0
26	      0	0	0	871	0	0	0	0
27	      0	0	0	960	0	0	0	0
28	      0	0	0	890	0	0	0	0
29	      0	0	0	840	0	0	0	0
30	      0	0	0	840	0	0	0	0

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

2025-11-11 15:38:34,421 - DEBUG - ya.test - _save_outputs: Command (pid 864053) errors:

2025-11-11 15:38:34,518 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 15:38:34,519 - DEBUG - ya.test - _save_outputs: Command (pid 864081) rc: 0
2025-11-11 15:38:34,519 - DEBUG - ya.test - _save_outputs: Command (pid 864081) elapsed time (sec): 30.510842084884644
2025-11-11 15:38:34,519 - DEBUG - ya.test - _save_outputs: Command (pid 864081) wtime: 30.521
2025-11-11 15:38:34,519 - DEBUG - ya.test - _save_outputs: Command (pid 864081) 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	     27	27	0	0	387	1015	1039	1039
5	     37	37	0	0	295	377	411	411
6	     36	36	0	0	261	323	355	355
7	     35	35	0	0	285	339	355	355
8	     19	19	0	0	313	409	413	413
9	      0	0	0	10	0	0	0	0
10	      0	0	0	0	0	0	0	0
11	      0	0	0	786	0	0	0	0
12	      0	0	0	790	0	0	0	0
13	      0	0	0	770	0	0	0	0
14	      0	0	0	790	0	0	0	0
15	      0	0	0	740	0	0	0	0
16	      0	0	0	780	0	0	0	0
17	      0	0	0	710	0	0	0	0
18	      0	0	0	830	0	0	0	0
19	      0	0	0	830	0	0	0	0
20	      0	0	0	820	0	0	0	0
21	      0	0	0	810	0	0	0	0
22	      0	0	0	730	0	0	0	0
23	      0	0	0	758	0	0	0	0
24	      0	0	0	803	0	0	0	0
25	      0	0	0	870	0	0	0	0
26	      0	0	0	997	0	0	0	0
27	      0	0	0	840	0	0	0	0
28	      0	0	0	975	0	0	0	0
29	      0	0	0	1370	0	0	0	0
30	      0	0	0	919	0	0	0	0

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    154	5.13333	0	16948	289	987	1007	1039

2025-11-11 15:38:34,520 - DEBUG - ya.test - _save_outputs: Command (pid 864081) errors:

2025-11-11 15:38:34,919 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:38:34,920 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:35,063 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:38:36,347 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:38:36,347 - 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-11 15:38:36,412 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:36,771 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:37,963 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:38,222 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:38,343 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:38,434 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:40,231 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:40,995 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:46,367 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:38:46,455 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:38:48,146 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:38:48,146 - 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-11 15:38:53,351 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:57,639 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:57,891 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:38:58,175 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:38:58,235 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:39:00,219 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:39:00,219 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:39:00,220 - 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-11 15:39:01,679 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:39:03,051 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:39:03,231 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:39:05,835 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:39:06,055 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:39:10,231 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:39:10,292 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:39:11,509 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:39:11,509 - 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-11 15:39:11,691 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:39:21,533 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:39:21,580 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:39:23,427 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:39:23,428 - 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-11 15:39:29,447 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:39:33,439 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:39:33,484 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:39:35,299 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:39:35,300 - 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-11 15:39:35,591 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:39:42,028 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:39:45,307 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:39:45,388 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:39:46,863 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:39:46,863 - 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-11 15:39:46,879 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:39:49,855 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:39:54,583 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:39:55,587 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:39:56,823 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:39:56,884 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:39:56,948 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:39:58,011 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:39:58,011 - 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-11 15:40:07,090 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:40:07,483 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:40:08,019 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:40:08,091 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:40:09,804 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:40:09,809 - 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-11 15:40:16,655 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:40:19,827 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:40:19,890 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:40:21,047 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:40:21,084 - 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-11 15:40:27,035 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:40:27,611 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:40:29,701 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:40:31,099 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:40:31,177 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:40:32,219 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:40:32,220 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:40:32,220 - 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-11 15:40:38,099 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:40:38,939 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:40:42,230 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:40:42,338 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:40:44,137 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:40:44,137 - 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-11 15:40:49,479 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:40:50,451 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:40:53,715 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:40:54,147 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:40:54,210 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:40:55,979 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:40:55,980 - 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-11 15:41:02,283 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:41:05,983 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:41:06,072 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:41:07,655 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:41:07,662 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:41:07,695 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-11 15:41:07,912 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:41:15,413 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:41:17,707 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:41:17,760 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:41:19,023 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:41:19,024 - 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-11 15:41:25,391 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:41:26,779 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:41:28,153 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:41:29,036 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:41:29,140 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:41:30,659 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:41:30,660 - 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-11 15:41:33,691 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:41:36,126 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:41:37,536 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:41:40,679 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:41:40,764 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:41:41,847 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:41:41,848 - 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-11 15:41:49,693 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:41:50,303 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:41:51,859 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:41:51,916 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:41:53,063 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:41:53,063 - 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-11 15:42:00,500 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:42:03,083 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:42:03,132 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:42:04,983 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:42:04,984 - 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-11 15:42:08,892 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:42:14,999 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:42:15,068 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:42:16,155 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:42:16,156 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:42:16,157 - 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-11 15:42:20,315 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:42:21,770 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:42:26,171 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:42:26,216 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:42:26,531 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:42:26,532 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:42:26,532 - 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-11 15:42:33,319 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:42:36,546 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:42:36,632 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:42:38,243 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 15:42:38,244 - 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-11 15:42:39,160 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 15:42:41,870 - 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-11 15:42:41,873 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-11 15:42:41,874 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-11 15:42:41,874 - DEBUG - ydb.topic - close: Close topic client
2025-11-11 15:42:41,874 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-11 15:42:41,874 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:42:48,263 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:21594
2025-11-11 15:42:48,412 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:21594". Check endpoint correctness.
2025-11-11 15:42:48,412 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-11 15:42:48,432 - DEBUG - library.python.cores - recover_core_dump_file: hostname = 'ghrun-3ynj4chmly'
2025-11-11 15:42:48,432 - DEBUG - library.python.cores - recover_core_dump_file: rlimit_core = '(0, -1)'
2025-11-11 15:42:48,433 - 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-11 15:42:48,433 - DEBUG - library.python.cores - recover_core_dump_file: core_uses_pid = '1'
2025-11-11 15:42:48,433 - DEBUG - library.python.cores - recover_core_dump_file: Core dump dir (/home/runner/.ya/build/build_root/iofl/001efd/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/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-11 15:42:48,433 - DEBUG - library.python.cores - recover_core_dump_file: Search for core dump files match pattern 'core.843046' in '/home/runner/.ya/build/build_root/iofl/001efd/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1'
2025-11-11 15:42:48,434 - DEBUG - library.python.cores - recover_core_dump_file: Matched core dump files (0/3): [] (mismatched samples: stderr, stdout, logfile_cdt899a7.log)
2025-11-11 15:42:48,434 - WARNING - library.python.cores - recover_core_dump_file: Core dump dir doesn't exist: /coredumps
2025-11-11 15:42:48,434 - WARNING - library.python.cores - recover_core_dump_file: Core dump dir doesn't exist: /var/tmp/cores
2025-11-11 15:42:48,435 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:21594/1
2025-11-11 15:42:48,435 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27665
2025-11-11 15:42:48,436 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10292
2025-11-11 15:42:48,436 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22301
2025-11-11 15:42:48,436 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/21594
2025-11-11 15:42:48,436 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30448
2025-11-11 15:42:48,681 - 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/iofl/001efd/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/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/iofl/001efd/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/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-11T15:38:06.197508Z): source_id=135;tablet_id=72075186224037890;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 @ 0xA7928E5
E   1. /-S/util/system/yassert.cpp:55: Panic @ 0xA78AE26
E   2. /tmp//-S/ydb/library/actors/core/log.cpp:857: ~TVerifyFormattedRecordWriter @ 0xB618C78
E   3. /-S/ydb/library/formats/arrow/switch/compare.h:82: TypedCompare<false> @ 0xD91133B
E   4. /-S/ydb/core/formats/arrow/reader/position.h:83: CompareImpl @ 0x9DE63C8
E   5. /-S/ydb/core/formats/arrow/reader/position.h:471: Compare @ 0x9DE611F
E   6. /tmp//-S/ydb/core/formats/arrow/reader/position.cpp:36: FindBound @ 0xD8EC94D
E   7. /tmp//-S/ydb/core/tx/columnshard/engines/predicate/filter.cpp:21: BuildFilter @ 0x1411564B
E   8. /tmp//-S/ydb/core/tx/columnshard/engines/reader/simple_reader/iterator/fetching.cpp:16: DoExecuteInplace @ 0x14D55257
E   9. /-S/ydb/core/tx/columnshard/engines/reader/common_reader/common/script.h:47: ExecuteInplace @ 0x14CACC53
E   10. /tmp//-S/ydb/core/tx/columnshard/engines/reader/common_reader/common/script_cursor.cpp:36: Execute @ 0x14CACC53
E   11. /tmp//-S/ydb/core/tx/columnshard/engines/reader/common_reader/iterator/fetching.cpp:30: DoExecuteImpl @ 0x14CA9A73
E   12. /tmp//-S/ydb/core/tx/columnshard/engines/reader/common/conveyor_task.cpp:8: DoExecute @ 0x14CB0527
E   13. /tmp//-S/ydb/core/tx/conveyor/usage/abstract.cpp:16: Execute @ 0x14132EC8
E   14. /tmp//-S/ydb/core/tx/conveyor_composite/service/worker.cpp:17: ExecuteTask @ 0x1CB661E5
E   15. /tmp//-S/ydb/library/actors/core/actor.cpp:350: Receive @ 0xB5C3ED7
E   16. /tmp//-S/ydb/library/actors/core/executor_thread.cpp:267: Execute @ 0xB609724
E   17. /tmp//-S/ydb/library/actors/core/executor_thread.cpp:455: operator() @ 0xB60D7E4
E   18. /tmp//-S/ydb/library/actors/core/executor_thread.cpp:507: ProcessExecutorPool @ 0xB60D3F0
E   19. /tmp//-S/ydb/library/actors/core/executor_thread.cpp:533: ThreadProc @ 0xB60DFDE
E   20. /-S/util/system/thread.cpp:245: ThreadProxy @ 0xA79453C
E   21. ??:0: ?? @ 0x7F04D522AAC2
E   22. ??:0: ?? @ 0x7F04D52BC8BF
