2025-11-07 15:57:08,077 - INFO - ya.test - pytest_collection_modifyitems: Modulo 0 tests are: [[<Function test_log_uniform>]]
2025-11-07 15:57:08,080 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-07 15:57:08,081 - INFO - ya.test - pytest_runtest_setup: test_log_uniform
2025-11-07 15:57:08,081 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-07 15:57:08,081 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-07 15:57:08,082 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-07 15:57:08,083 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-07 15:57:08,086 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-07 15:57:08,087 - DEBUG - ya.test - execute: Command pid: 3702550
2025-11-07 15:57:08,425 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) rc: 0
2025-11-07 15:57:08,426 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) elapsed time (sec): 0.33768224716186523
2025-11-07 15:57:08,426 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) idrss: 0
2025-11-07 15:57:08,426 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) inblock: 0
2025-11-07 15:57:08,426 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) isrss: 0
2025-11-07 15:57:08,426 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) ixrss: 0
2025-11-07 15:57:08,426 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) majflt: 0
2025-11-07 15:57:08,427 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) maxrss: 538956
2025-11-07 15:57:08,427 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) minflt: 16130
2025-11-07 15:57:08,427 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) msgrcv: 0
2025-11-07 15:57:08,427 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) msgsnd: 0
2025-11-07 15:57:08,427 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) nivcsw: 6
2025-11-07 15:57:08,427 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) nsignals: 0
2025-11-07 15:57:08,427 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) nswap: 0
2025-11-07 15:57:08,428 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) nvcsw: 13
2025-11-07 15:57:08,428 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) oublock: 8
2025-11-07 15:57:08,428 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) stime: 0.160801
2025-11-07 15:57:08,428 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) utime: 0.12864
2025-11-07 15:57:08,428 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) wtime: 0.339
2025-11-07 15:57:08,429 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) output:
Git info:
    Commit: bff82a331b8002752a5d8b197b911a10472065c7
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 6004f4a8531ee4ff3ba1ddd36755b2be4e220723 into 45828a561dc8c075cdca5398db260764192ef17f

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-7awxhsvlnm
    Host information: 
        Linux ghrun-7awxhsvlnm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-07 15:57:08,429 - DEBUG - ya.test - _save_outputs: Command (pid 3702550) errors:

2025-11-07 15:57:08,429 - INFO - __tests__.test_log_scenario - _setup_ydb: Git info:
    Commit: bff82a331b8002752a5d8b197b911a10472065c7
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 6004f4a8531ee4ff3ba1ddd36755b2be4e220723 into 45828a561dc8c075cdca5398db260764192ef17f

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-7awxhsvlnm
    Host information: 
        Linux ghrun-7awxhsvlnm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-07 15:57:08,544 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydbd/ydbd
2025-11-07 15:57:08,545 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/14941
2025-11-07 15:57:08,546 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/5zmm/0002a9/r3tmp/kikimr_cluster_0q94ytxr
2025-11-07 15:57:08,579 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydbd/ydbd
2025-11-07 15:57:08,579 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6567
2025-11-07 15:57:08,580 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20601
2025-11-07 15:57:08,580 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62966
2025-11-07 15:57:08,580 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/7115
2025-11-07 15:57:08,581 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/kikimr_configs"
2025-11-07 15:57:08,581 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/5zmm/0002a9/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/5zmm/0002a9/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/logfile_jlu94f23.log --grpc-port=6567 --mon-port=20601 --ic-port=14941
2025-11-07 15:57:08,582 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:6567/1, disk_size 68719476736
2025-11-07 15:57:08,582 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/kikimr_configs"
2025-11-07 15:57:08,582 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/5zmm/0002a9/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/5zmm/0002a9/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/logfile_jlu94f23.log --grpc-port=6567 --mon-port=20601 --ic-port=14941
2025-11-07 15:57:08,583 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/logfile_jlu94f23.log', '--grpc-port=6567', '--mon-port=20601', '--ic-port=14941')' in '/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1' (no wait)
2025-11-07 15:57:08,584 - DEBUG - ya.test - execute: Command pid: 3702739
2025-11-07 15:57:08,585 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:6567/1
2025-11-07 15:57:08,586 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-07 15:57:08,586 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-07 15:57:08,586 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-07 15:57:08,587 - DEBUG - ya.test - execute: Command pid: 3702740
2025-11-07 15:57:08,919 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) rc: 0
2025-11-07 15:57:08,920 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) elapsed time (sec): 0.33158135414123535
2025-11-07 15:57:08,920 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) idrss: 0
2025-11-07 15:57:08,920 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) inblock: 0
2025-11-07 15:57:08,920 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) isrss: 0
2025-11-07 15:57:08,920 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) ixrss: 0
2025-11-07 15:57:08,921 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) majflt: 0
2025-11-07 15:57:08,921 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) maxrss: 538956
2025-11-07 15:57:08,921 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) minflt: 16128
2025-11-07 15:57:08,921 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) msgrcv: 0
2025-11-07 15:57:08,921 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) msgsnd: 0
2025-11-07 15:57:08,921 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) nivcsw: 3
2025-11-07 15:57:08,921 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) nsignals: 0
2025-11-07 15:57:08,922 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) nswap: 0
2025-11-07 15:57:08,922 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) nvcsw: 13
2025-11-07 15:57:08,922 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) oublock: 8
2025-11-07 15:57:08,922 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) stime: 0.183342
2025-11-07 15:57:08,922 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) utime: 0.099642
2025-11-07 15:57:08,922 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) wtime: 0.332
2025-11-07 15:57:08,922 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) output:
Git info:
    Commit: bff82a331b8002752a5d8b197b911a10472065c7
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 6004f4a8531ee4ff3ba1ddd36755b2be4e220723 into 45828a561dc8c075cdca5398db260764192ef17f

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-7awxhsvlnm
    Host information: 
        Linux ghrun-7awxhsvlnm 5.15.0-157-generic #167-Ubuntu SMP Wed Sep 17 21:35:53 UTC 2025 x86_64

    
Build info:
    Compiler: /home/runner/.ya/tools/v4/9944335923/bin/clang++
    Compiler version: 
        clang version 20.1.8
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9944335923/bin
    Compile flags: -Xclang -fdebug-compilation-dir -Xclang /tmp -pipe -m64 -O3 -gline-tables-only -fno-common -ffunction-sections -fdata-sections -fsized-deallocation -fexceptions -fuse-init-array -fcolor-diagnostics -faligned-allocation -Werror -Wall -Wextra -Wno-parentheses -Wno-implicit-const-int-float-conversion -Wno-unknown-warning-option -D_THREAD_SAFE -D_PTHREADS -D_REENTRANT -D_LARGEFILE_SOURCE -D__STDC_CONSTANT_MACROS -D__STDC_FORMAT_MACROS -D_FILE_OFFSET_BITS=64 -D_GNU_SOURCE -D_YNDX_LIBUNWIND_ENABLE_EXCEPTION_BACKTRACE -D__LONG_LONG_SUPPORTED -fno-omit-frame-pointer -Wno-unknown-argument -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -DCATBOOST_OPENSOURCE=yes -msse2 -msse3 -mssse3 -msse4.1 -msse4.2 -mpopcnt -mcx16 -UNDEBUG -Wno-array-parameter -Wno-deprecate-lax-vec-conv-all -Wno-unqualified-std-cast-call -Wno-unused-but-set-parameter -Wno-implicit-function-declaration -Wno-int-conversion -Wno-incompatible-function-pointer-types -Wno-address-of-packed-member -Wno-deprecated-this-capture -Wno-missing-designated-field-initializers -Wno-format -Wno-vla-cxx-extension -Wno-invalid-offsetof -Wno-alias-template-in-declaration-name -Wno-cast-function-type-mismatch -Wno-explicit-specialization-storage-class -Wno-extraneous-template-head -Wno-missing-template-arg-list-after-template-kw -Wno-nontrivial-memcall -Wno-strict-primary-template-shadow -std=c++20 -Wimport-preprocessor-directive-pedantic -Woverloaded-virtual -Wno-ambiguous-reversed-operator -Wno-defaulted-function-deleted -Wno-deprecated-anon-enum-enum-conversion -Wno-deprecated-enum-enum-conversion -Wno-deprecated-enum-float-conversion -Wno-deprecated-volatile -Wno-pessimizing-move -Wno-undefined-var-template -nostdinc++ -DCATBOOST_OPENSOURCE=yes -nostdinc++

2025-11-07 15:57:08,923 - DEBUG - ya.test - _save_outputs: Command (pid 3702740) errors:

2025-11-07 15:57:08,923 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: bff82a331b8002752a5d8b197b911a10472065c7
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 6004f4a8531ee4ff3ba1ddd36755b2be4e220723 into 45828a561dc8c075cdca5398db260764192ef17f
2025-11-07 15:57:10,836 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydbd/ydbd
2025-11-07 15:57:10,837 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6567', '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: 14941\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-07 15:57:10,838 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-07 15:57:10,838 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-07 15:57:10,839 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6567', '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: 14941\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-07 15:57:10,840 - DEBUG - ya.test - execute: Command pid: 3704011
2025-11-07 15:57:11,258 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) rc: 0
2025-11-07 15:57:11,258 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) elapsed time (sec): 0.41731715202331543
2025-11-07 15:57:11,258 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) idrss: 0
2025-11-07 15:57:11,258 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) inblock: 0
2025-11-07 15:57:11,259 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) isrss: 0
2025-11-07 15:57:11,259 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) ixrss: 0
2025-11-07 15:57:11,259 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) majflt: 0
2025-11-07 15:57:11,259 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) maxrss: 547376
2025-11-07 15:57:11,259 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) minflt: 18270
2025-11-07 15:57:11,259 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) msgrcv: 0
2025-11-07 15:57:11,259 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) msgsnd: 0
2025-11-07 15:57:11,259 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) nivcsw: 59
2025-11-07 15:57:11,259 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) nsignals: 0
2025-11-07 15:57:11,259 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) nswap: 0
2025-11-07 15:57:11,259 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) nvcsw: 111
2025-11-07 15:57:11,260 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) oublock: 8
2025-11-07 15:57:11,260 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) stime: 0.228428
2025-11-07 15:57:11,260 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) utime: 0.126444
2025-11-07 15:57:11,260 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) wtime: 0.419
2025-11-07 15:57:11,260 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-07 15:57:11,260 - DEBUG - ya.test - _save_outputs: Command (pid 3704011) errors:

2025-11-07 15:57:11,261 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydbd/ydbd
2025-11-07 15:57:11,261 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6567', '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-07 15:57:11,262 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-07 15:57:11,263 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-07 15:57:11,317 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:6567', '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-07 15:57:11,320 - DEBUG - ya.test - execute: Command pid: 3704287
2025-11-07 15:57:11,792 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) rc: 0
2025-11-07 15:57:11,792 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) elapsed time (sec): 0.47066283226013184
2025-11-07 15:57:11,792 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) idrss: 0
2025-11-07 15:57:11,793 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) inblock: 0
2025-11-07 15:57:11,793 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) isrss: 0
2025-11-07 15:57:11,793 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) ixrss: 0
2025-11-07 15:57:11,793 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) majflt: 0
2025-11-07 15:57:11,793 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) maxrss: 547372
2025-11-07 15:57:11,793 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) minflt: 18273
2025-11-07 15:57:11,794 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) msgrcv: 0
2025-11-07 15:57:11,794 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) msgsnd: 0
2025-11-07 15:57:11,794 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) nivcsw: 47
2025-11-07 15:57:11,794 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) nsignals: 0
2025-11-07 15:57:11,794 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) nswap: 0
2025-11-07 15:57:11,794 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) nvcsw: 106
2025-11-07 15:57:11,795 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) oublock: 8
2025-11-07 15:57:11,795 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) stime: 0.23938299999999998
2025-11-07 15:57:11,795 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) utime: 0.14362999999999998
2025-11-07 15:57:11,795 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) wtime: 0.473
2025-11-07 15:57:11,795 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-07 15:57:11,795 - DEBUG - ya.test - _save_outputs: Command (pid 3704287) errors:

2025-11-07 15:57:11,796 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-07 15:57:11,809 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-07 15:57:11,821 - 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-07 15:57:11,823 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 15:57:11,825 - 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-07 15:57:11,832 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-07 15:57:11,833 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, dd4f022a-df45-4e97-8242-ae2efb9d3b59, localhost:6567): creating call state
2025-11-07 15:57:11,833 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, dd4f022a-df45-4e97-8242-ae2efb9d3b59, localhost:6567): request = { database: "/Root" }
2025-11-07 15:57:11,836 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, dd4f022a-df45-4e97-8242-ae2efb9d3b59, localhost:6567): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-7awxhsvlnm.auto.internal" port: 6567 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-07 15:57:11,836 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-7awxhsvlnm.auto.internal:6567, location 1, ssl: False>]>
2025-11-07 15:57:11,836 - 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-07 15:57:11,843 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 15:57:11,845 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_uniform)
2025-11-07 15:57:11,845 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydb/ydb
2025-11-07 15:57:11,846 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydb/ydb -e grpc://localhost:6567 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-11-07 15:57:11,846 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-11-07 15:57:11,847 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-11-07 15:57:11,847 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:6567', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-11-07 15:57:11,847 - DEBUG - ya.test - execute: Command pid: 3704621
2025-11-07 15:57:12,270 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) rc: 0
2025-11-07 15:57:12,271 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) elapsed time (sec): 0.4224510192871094
2025-11-07 15:57:12,271 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) idrss: 0
2025-11-07 15:57:12,271 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) inblock: 0
2025-11-07 15:57:12,271 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) isrss: 0
2025-11-07 15:57:12,271 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) ixrss: 0
2025-11-07 15:57:12,272 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) majflt: 0
2025-11-07 15:57:12,272 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) maxrss: 166264
2025-11-07 15:57:12,272 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) minflt: 3542
2025-11-07 15:57:12,272 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) msgrcv: 0
2025-11-07 15:57:12,272 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) msgsnd: 0
2025-11-07 15:57:12,272 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) nivcsw: 25
2025-11-07 15:57:12,272 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) nsignals: 0
2025-11-07 15:57:12,272 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) nswap: 0
2025-11-07 15:57:12,273 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) nvcsw: 150
2025-11-07 15:57:12,273 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) oublock: 8
2025-11-07 15:57:12,273 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) stime: 0.020673999999999998
2025-11-07 15:57:12,273 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) utime: 0.051099
2025-11-07 15:57:12,273 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) wtime: 0.423
2025-11-07 15:57:12,273 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) output:
Init tables ...
Init tables ...Ok

2025-11-07 15:57:12,274 - DEBUG - ya.test - _save_outputs: Command (pid 3704621) errors:

2025-11-07 15:57:12,274 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-07 15:57:12,274 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydb/ydb -e grpc://localhost:6567 -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-07 15:57:12,275 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-11-07 15:57:12,275 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-11-07 15:57:12,275 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:6567', '-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-07 15:57:12,276 - DEBUG - ya.test - execute: Command pid: 3704960
2025-11-07 15:57:42,425 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-07 15:57:42,425 - DEBUG - ya.test - _save_outputs: Command (pid 3704960) rc: 0
2025-11-07 15:57:42,425 - DEBUG - ya.test - _save_outputs: Command (pid 3704960) elapsed time (sec): 30.148675680160522
2025-11-07 15:57:42,426 - DEBUG - ya.test - _save_outputs: Command (pid 3704960) wtime: 30.15
2025-11-07 15:57:42,426 - DEBUG - ya.test - _save_outputs: Command (pid 3704960) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	    702	702	0	0	12	26	37	50
2	    648	648	0	0	13	28	37	52
3	    925	925	0	0	9	18	26	29
4	   1189	1189	0	0	8	11	12	15
5	   1225	1225	0	0	7	10	12	14
6	   1225	1225	0	0	7	10	13	18
7	   1154	1154	0	0	8	12	16	20
8	   1152	1152	0	0	8	11	14	17
9	   1118	1118	0	0	8	12	15	17
10	   1366	1366	0	0	7	8	9	10
11	   1307	1307	0	0	7	9	16	37
12	   1290	1290	0	0	7	9	13	18
13	   1008	1008	0	0	8	14	23	70
14	   1209	1209	0	0	7	12	18	82
15	   1332	1332	0	0	7	8	10	18
16	   1427	1427	0	0	6	8	8	9
17	   1429	1429	0	0	6	8	11	15
18	   1408	1408	0	0	6	9	13	16
19	   1411	1411	0	0	6	8	8	9
20	   1383	1383	0	0	7	8	9	11
21	   1397	1397	0	0	7	8	9	13
22	   1416	1416	0	0	6	8	9	10
23	   1415	1415	0	0	6	8	8	9
24	   1470	1470	0	0	6	7	8	11
25	   1537	1537	0	0	6	7	7	8
26	   1456	1456	0	0	6	9	12	18
27	   1505	1505	0	0	6	7	8	8
28	   1529	1529	0	0	6	7	8	9
29	   1547	1547	0	0	6	7	8	8
30	   1538	1538	0	0	6	8	9	10

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	  38718	1290.6	0	0	7	11	18	82

2025-11-07 15:57:42,426 - DEBUG - ya.test - _save_outputs: Command (pid 3704960) errors:

2025-11-07 15:57:42,426 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:42,427 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 6a4c1139-a51f-420d-a3b0-652c96c4c172, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:42,427 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 6a4c1139-a51f-420d-a3b0-652c96c4c172, ghrun-7awxhsvlnm.auto.internal:6567): request = {  }
2025-11-07 15:57:42,428 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 6a4c1139-a51f-420d-a3b0-652c96c4c172, ghrun-7awxhsvlnm.auto.internal:6567): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZWYwMmYxNjctZTVjNjQ5NDAtZDZkNWY4MmUtOWQzZDMyNjE=" node_id: 1 }
2025-11-07 15:57:42,429 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 346564ca-2af8-4e78-9c55-6efecc36c5e7, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:42,429 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 346564ca-2af8-4e78-9c55-6efecc36c5e7, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=ZWYwMmYxNjctZTVjNjQ5NDAtZDZkNWY4MmUtOWQzZDMyNjE=" }
2025-11-07 15:57:42,430 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 346564ca-2af8-4e78-9c55-6efecc36c5e7, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:42,432 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZWYwMmYxNjctZTVjNjQ5NDAtZDZkNWY4MmUtOWQzZDMyNjE=
2025-11-07 15:57:42,432 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, efb2e29e-16e6-4fb4-b98b-2aeaac6bbd43, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:42,433 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, efb2e29e-16e6-4fb4-b98b-2aeaac6bbd43, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=ZWYwMmYxNjctZTVjNjQ5NDAtZDZkNWY4MmUtOWQzZDMyNjE=" 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-07 15:57:42,433 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, efb2e29e-16e6-4fb4-b98b-2aeaac6bbd43, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:42,728 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYwMmYxNjctZTVjNjQ5NDAtZDZkNWY4MmUtOWQzZDMyNjE=
2025-11-07 15:57:42,729 - INFO - root - test_log_uniform: Count rows after insert 37986 before wait
2025-11-07 15:57:42,729 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYwMmYxNjctZTVjNjQ5NDAtZDZkNWY4MmUtOWQzZDMyNjE=
2025-11-07 15:57:42,729 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f7d1f4f8-c443-4d42-8ece-56da1495c62e, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:42,730 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f7d1f4f8-c443-4d42-8ece-56da1495c62e, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=ZWYwMmYxNjctZTVjNjQ5NDAtZDZkNWY4MmUtOWQzZDMyNjE=" 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-07 15:57:42,730 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f7d1f4f8-c443-4d42-8ece-56da1495c62e, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:42,930 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYwMmYxNjctZTVjNjQ5NDAtZDZkNWY4MmUtOWQzZDMyNjE=
2025-11-07 15:57:42,931 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYwMmYxNjctZTVjNjQ5NDAtZDZkNWY4MmUtOWQzZDMyNjE=
2025-11-07 15:57:42,931 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9d158bd2-0d53-41d5-9563-3aa6a3d60730, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:42,931 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9d158bd2-0d53-41d5-9563-3aa6a3d60730, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=ZWYwMmYxNjctZTVjNjQ5NDAtZDZkNWY4MmUtOWQzZDMyNjE=" 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-07 15:57:42,932 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9d158bd2-0d53-41d5-9563-3aa6a3d60730, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,149 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWYwMmYxNjctZTVjNjQ5NDAtZDZkNWY4MmUtOWQzZDMyNjE=
2025-11-07 15:57:43,150 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-07 15:57:43,151 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydb/ydb -e grpc://localhost:6567 -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-07 15:57:43,151 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-11-07 15:57:43,152 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-11-07 15:57:43,152 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydb/ydb -e grpc://localhost:6567 -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-07 15:57:43,153 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWYwMmYxNjctZTVjNjQ5NDAtZDZkNWY4MmUtOWQzZDMyNjE=
2025-11-07 15:57:43,154 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydb/ydb -e grpc://localhost:6567 -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-07 15:57:43,155 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7c01733d-bc52-4f75-94b4-53a07e001a64, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,156 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-11-07 15:57:43,156 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-11-07 15:57:43,159 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7c01733d-bc52-4f75-94b4-53a07e001a64, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=ZWYwMmYxNjctZTVjNjQ5NDAtZDZkNWY4MmUtOWQzZDMyNjE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-07 15:57:43,159 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.2
2025-11-07 15:57:43,160 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 0d14b477-88f5-47c5-a682-0dda03e02339, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,161 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.3
2025-11-07 15:57:43,163 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 0d14b477-88f5-47c5-a682-0dda03e02339, ghrun-7awxhsvlnm.auto.internal:6567): request = {  }
2025-11-07 15:57:43,164 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7c01733d-bc52-4f75-94b4-53a07e001a64, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,164 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-11-07 15:57:43,164 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-11-07 15:57:43,165 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-11-07 15:57:43,166 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:6567', '-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-07 15:57:43,166 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:6567', '-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-07 15:57:43,166 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 0d14b477-88f5-47c5-a682-0dda03e02339, ghrun-7awxhsvlnm.auto.internal:6567): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MzhiMmRhYzAtYTNkMzJjNWItOWRjOTEyNjQtZjQ4YTU0ODk=" node_id: 1 }
2025-11-07 15:57:43,167 - DEBUG - ya.test - execute: Command pid: 3716243
2025-11-07 15:57:43,168 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:6567', '-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-07 15:57:43,169 - DEBUG - ya.test - execute: Command pid: 3716244
2025-11-07 15:57:43,169 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, b4f32c7e-f0fe-4470-b925-86ef578f7d03, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,171 - DEBUG - ya.test - execute: Command pid: 3716245
2025-11-07 15:57:43,171 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, b4f32c7e-f0fe-4470-b925-86ef578f7d03, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=MzhiMmRhYzAtYTNkMzJjNWItOWRjOTEyNjQtZjQ4YTU0ODk=" }
2025-11-07 15:57:43,173 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, b4f32c7e-f0fe-4470-b925-86ef578f7d03, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,175 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MzhiMmRhYzAtYTNkMzJjNWItOWRjOTEyNjQtZjQ4YTU0ODk=
2025-11-07 15:57:43,175 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 28c1cbae-0be3-404e-9a8b-768a304278bd, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,175 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 28c1cbae-0be3-404e-9a8b-768a304278bd, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=MzhiMmRhYzAtYTNkMzJjNWItOWRjOTEyNjQtZjQ4YTU0ODk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-07 15:57:43,175 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 2 < 100, will create new one.
2025-11-07 15:57:43,176 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 3ffae963-9136-42a2-80e5-d991966a14f4, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,176 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 28c1cbae-0be3-404e-9a8b-768a304278bd, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,177 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 3ffae963-9136-42a2-80e5-d991966a14f4, ghrun-7awxhsvlnm.auto.internal:6567): request = {  }
2025-11-07 15:57:43,179 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 3ffae963-9136-42a2-80e5-d991966a14f4, ghrun-7awxhsvlnm.auto.internal:6567): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZDQwM2E5MjYtNTU4OGRiZWYtZGUxODg1OTYtNTJiNWU4NDQ=" node_id: 1 }
2025-11-07 15:57:43,179 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, f4534ec0-2ab7-43a8-9705-d06f58c4d4f4, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,179 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, f4534ec0-2ab7-43a8-9705-d06f58c4d4f4, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=ZDQwM2E5MjYtNTU4OGRiZWYtZGUxODg1OTYtNTJiNWU4NDQ=" }
2025-11-07 15:57:43,180 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, f4534ec0-2ab7-43a8-9705-d06f58c4d4f4, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,181 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZDQwM2E5MjYtNTU4OGRiZWYtZGUxODg1OTYtNTJiNWU4NDQ=
2025-11-07 15:57:43,181 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 97aafcf6-7511-4f98-9860-5bebf5fcfc6c, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,182 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 97aafcf6-7511-4f98-9860-5bebf5fcfc6c, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=ZDQwM2E5MjYtNTU4OGRiZWYtZGUxODg1OTYtNTJiNWU4NDQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-07 15:57:43,182 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 3 < 100, will create new one.
2025-11-07 15:57:43,183 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, aa42c6f2-684d-45bd-b5bd-848faa0bca53, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,183 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 97aafcf6-7511-4f98-9860-5bebf5fcfc6c, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,183 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, aa42c6f2-684d-45bd-b5bd-848faa0bca53, ghrun-7awxhsvlnm.auto.internal:6567): request = {  }
2025-11-07 15:57:43,185 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, aa42c6f2-684d-45bd-b5bd-848faa0bca53, ghrun-7awxhsvlnm.auto.internal:6567): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZDdmNGJlZmItNDYwZWE0OGMtYjk3MGRiYjQtZGE3ZGYyZDU=" node_id: 1 }
2025-11-07 15:57:43,185 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 39010d19-50d3-4e33-90bb-32329d0ecbc7, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,186 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 39010d19-50d3-4e33-90bb-32329d0ecbc7, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=ZDdmNGJlZmItNDYwZWE0OGMtYjk3MGRiYjQtZGE3ZGYyZDU=" }
2025-11-07 15:57:43,186 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 39010d19-50d3-4e33-90bb-32329d0ecbc7, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,188 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZDdmNGJlZmItNDYwZWE0OGMtYjk3MGRiYjQtZGE3ZGYyZDU=
2025-11-07 15:57:43,188 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 544fc9d1-0f23-4051-a0c9-1f5f5ec1ddd7, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,188 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 4 < 100, will create new one.
2025-11-07 15:57:43,189 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 544fc9d1-0f23-4051-a0c9-1f5f5ec1ddd7, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=ZDdmNGJlZmItNDYwZWE0OGMtYjk3MGRiYjQtZGE3ZGYyZDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-07 15:57:43,189 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 839adc05-4ef5-492d-8614-64a16e5a5726, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,190 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 839adc05-4ef5-492d-8614-64a16e5a5726, ghrun-7awxhsvlnm.auto.internal:6567): request = {  }
2025-11-07 15:57:43,190 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 544fc9d1-0f23-4051-a0c9-1f5f5ec1ddd7, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,192 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 839adc05-4ef5-492d-8614-64a16e5a5726, ghrun-7awxhsvlnm.auto.internal:6567): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZjE2ZjRlM2QtNWViYWI5MTYtZjEwYjM2NS1lNWI0YTQ2Mg==" node_id: 1 }
2025-11-07 15:57:43,192 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 6300db08-c53b-4591-ba0a-9d4a98e3d860, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,192 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 6300db08-c53b-4591-ba0a-9d4a98e3d860, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=ZjE2ZjRlM2QtNWViYWI5MTYtZjEwYjM2NS1lNWI0YTQ2Mg==" }
2025-11-07 15:57:43,193 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 6300db08-c53b-4591-ba0a-9d4a98e3d860, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,195 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZjE2ZjRlM2QtNWViYWI5MTYtZjEwYjM2NS1lNWI0YTQ2Mg==
2025-11-07 15:57:43,195 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fff5f7f2-a7be-49fa-82cb-e29ebaf20f55, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,196 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 5 < 100, will create new one.
2025-11-07 15:57:43,196 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fff5f7f2-a7be-49fa-82cb-e29ebaf20f55, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=ZjE2ZjRlM2QtNWViYWI5MTYtZjEwYjM2NS1lNWI0YTQ2Mg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-07 15:57:43,196 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 5f772c16-4227-415c-8f48-0c1f94f9166d, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,197 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 5f772c16-4227-415c-8f48-0c1f94f9166d, ghrun-7awxhsvlnm.auto.internal:6567): request = {  }
2025-11-07 15:57:43,198 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fff5f7f2-a7be-49fa-82cb-e29ebaf20f55, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,199 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 5f772c16-4227-415c-8f48-0c1f94f9166d, ghrun-7awxhsvlnm.auto.internal:6567): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=OTllZjdiNjctYzhiZmNiZmItNDY3NzJmNDgtOWMxYjllNTc=" node_id: 1 }
2025-11-07 15:57:43,200 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 0f1ade40-81de-49fb-be8e-ae28bbbe755f, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,200 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 0f1ade40-81de-49fb-be8e-ae28bbbe755f, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=OTllZjdiNjctYzhiZmNiZmItNDY3NzJmNDgtOWMxYjllNTc=" }
2025-11-07 15:57:43,200 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 0f1ade40-81de-49fb-be8e-ae28bbbe755f, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,202 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=OTllZjdiNjctYzhiZmNiZmItNDY3NzJmNDgtOWMxYjllNTc=
2025-11-07 15:57:43,202 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1dd0b61d-38c9-4163-af59-9875c4db7415, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,202 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 6 < 100, will create new one.
2025-11-07 15:57:43,203 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1dd0b61d-38c9-4163-af59-9875c4db7415, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=OTllZjdiNjctYzhiZmNiZmItNDY3NzJmNDgtOWMxYjllNTc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-07 15:57:43,203 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, c0de9b49-40ec-42b6-9d88-393cb21b6ce1, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,204 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, c0de9b49-40ec-42b6-9d88-393cb21b6ce1, ghrun-7awxhsvlnm.auto.internal:6567): request = {  }
2025-11-07 15:57:43,204 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1dd0b61d-38c9-4163-af59-9875c4db7415, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,206 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, c0de9b49-40ec-42b6-9d88-393cb21b6ce1, ghrun-7awxhsvlnm.auto.internal:6567): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZTY2Y2FhZWQtYzUwZTgxNmYtODgxMTBkMGItNDNhYjY2YjQ=" node_id: 1 }
2025-11-07 15:57:43,206 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 02611bfd-58ed-4b70-9f40-85c0e7b91558, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,206 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 02611bfd-58ed-4b70-9f40-85c0e7b91558, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=ZTY2Y2FhZWQtYzUwZTgxNmYtODgxMTBkMGItNDNhYjY2YjQ=" }
2025-11-07 15:57:43,207 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 02611bfd-58ed-4b70-9f40-85c0e7b91558, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,209 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZTY2Y2FhZWQtYzUwZTgxNmYtODgxMTBkMGItNDNhYjY2YjQ=
2025-11-07 15:57:43,210 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 45c090e4-7312-4bf8-8d36-a1a4c80cb39f, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,210 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 45c090e4-7312-4bf8-8d36-a1a4c80cb39f, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=ZTY2Y2FhZWQtYzUwZTgxNmYtODgxMTBkMGItNDNhYjY2YjQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-07 15:57:43,210 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 7 < 100, will create new one.
2025-11-07 15:57:43,211 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a7fc1dfe-2898-4bbc-891e-57c09773c2d4, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,211 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 45c090e4-7312-4bf8-8d36-a1a4c80cb39f, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,212 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a7fc1dfe-2898-4bbc-891e-57c09773c2d4, ghrun-7awxhsvlnm.auto.internal:6567): request = {  }
2025-11-07 15:57:43,255 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a7fc1dfe-2898-4bbc-891e-57c09773c2d4, ghrun-7awxhsvlnm.auto.internal:6567): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NmNmMWNlMWItNWVlNDRmNGYtZjdlMDUyZmEtMzg1OWFkNjc=" node_id: 1 }
2025-11-07 15:57:43,255 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 380cd936-174b-4ffc-ae28-e224ff7c6f34, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,255 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 380cd936-174b-4ffc-ae28-e224ff7c6f34, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=NmNmMWNlMWItNWVlNDRmNGYtZjdlMDUyZmEtMzg1OWFkNjc=" }
2025-11-07 15:57:43,256 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 380cd936-174b-4ffc-ae28-e224ff7c6f34, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,260 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NmNmMWNlMWItNWVlNDRmNGYtZjdlMDUyZmEtMzg1OWFkNjc=
2025-11-07 15:57:43,261 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a11e4818-d385-4ff1-91b8-ec41232a8877, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,261 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 8 < 100, will create new one.
2025-11-07 15:57:43,261 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a11e4818-d385-4ff1-91b8-ec41232a8877, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=NmNmMWNlMWItNWVlNDRmNGYtZjdlMDUyZmEtMzg1OWFkNjc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-07 15:57:43,262 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a41ee396-7acc-49c7-a1dd-fb13c0952822, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,263 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a41ee396-7acc-49c7-a1dd-fb13c0952822, ghrun-7awxhsvlnm.auto.internal:6567): request = {  }
2025-11-07 15:57:43,263 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a11e4818-d385-4ff1-91b8-ec41232a8877, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,272 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a41ee396-7acc-49c7-a1dd-fb13c0952822, ghrun-7awxhsvlnm.auto.internal:6567): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZGJjODU5NTMtZTc2YTgwMjItYzZiMDkzYmUtNDFmMDRjNDk=" node_id: 1 }
2025-11-07 15:57:43,272 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 55de5dd9-7c4d-4991-903f-90b6387f773b, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,273 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 55de5dd9-7c4d-4991-903f-90b6387f773b, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=ZGJjODU5NTMtZTc2YTgwMjItYzZiMDkzYmUtNDFmMDRjNDk=" }
2025-11-07 15:57:43,273 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 55de5dd9-7c4d-4991-903f-90b6387f773b, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,282 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZGJjODU5NTMtZTc2YTgwMjItYzZiMDkzYmUtNDFmMDRjNDk=
2025-11-07 15:57:43,282 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 26510d7a-6a85-4789-8028-cfbcf5905d9a, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,282 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 9 < 100, will create new one.
2025-11-07 15:57:43,283 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 26510d7a-6a85-4789-8028-cfbcf5905d9a, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=ZGJjODU5NTMtZTc2YTgwMjItYzZiMDkzYmUtNDFmMDRjNDk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-07 15:57:43,283 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 8dcd943b-600f-4137-bd0c-650e6fa7d255, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,284 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 26510d7a-6a85-4789-8028-cfbcf5905d9a, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,285 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 8dcd943b-600f-4137-bd0c-650e6fa7d255, ghrun-7awxhsvlnm.auto.internal:6567): request = {  }
2025-11-07 15:57:43,297 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 8dcd943b-600f-4137-bd0c-650e6fa7d255, ghrun-7awxhsvlnm.auto.internal:6567): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NjBkMzNmN2UtYmE4NDhmYjMtZmQ5MTIyNzYtNzNmZjBlNmM=" node_id: 1 }
2025-11-07 15:57:43,297 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, ceb62c69-aaf4-4ce6-b63f-b5b04f70e2d4, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,298 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, ceb62c69-aaf4-4ce6-b63f-b5b04f70e2d4, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=NjBkMzNmN2UtYmE4NDhmYjMtZmQ5MTIyNzYtNzNmZjBlNmM=" }
2025-11-07 15:57:43,298 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, ceb62c69-aaf4-4ce6-b63f-b5b04f70e2d4, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:43,308 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NjBkMzNmN2UtYmE4NDhmYjMtZmQ5MTIyNzYtNzNmZjBlNmM=
2025-11-07 15:57:43,308 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2f986f3e-cecb-4629-b80d-975827376af4, ghrun-7awxhsvlnm.auto.internal:6567): creating call state
2025-11-07 15:57:43,309 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2f986f3e-cecb-4629-b80d-975827376af4, ghrun-7awxhsvlnm.auto.internal:6567): request = { session_id: "ydb://session/3?node_id=1&id=NjBkMzNmN2UtYmE4NDhmYjMtZmQ5MTIyNzYtNzNmZjBlNmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-07 15:57:43,309 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2f986f3e-cecb-4629-b80d-975827376af4, ghrun-7awxhsvlnm.auto.internal:6567): response = { <_MultiThreadedRendezvous object> }
2025-11-07 15:57:46,190 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 346564ca-2af8-4e78-9c55-6efecc36c5e7, ghrun-7awxhsvlnm.auto.internal:6567): 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:6567 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-07T15:57:46.188379054+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-07 15:57:46,190 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,191 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, b4f32c7e-f0fe-4470-b925-86ef578f7d03, ghrun-7awxhsvlnm.auto.internal:6567): 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:6567 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-07T15:57:46.188486703+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-07 15:57:46,192 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, f4534ec0-2ab7-43a8-9705-d06f58c4d4f4, ghrun-7awxhsvlnm.auto.internal:6567): 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:6567 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-07T15:57:46.188508553+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-07 15:57:46,193 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,193 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 39010d19-50d3-4e33-90bb-32329d0ecbc7, ghrun-7awxhsvlnm.auto.internal:6567): 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:6567 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-07T15:57:46.188528941+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-07 15:57:46,193 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 6300db08-c53b-4591-ba0a-9d4a98e3d860, ghrun-7awxhsvlnm.auto.internal:6567): 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:6567 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-07T15:57:46.188549611+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-07 15:57:46,194 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,195 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 0f1ade40-81de-49fb-be8e-ae28bbbe755f, ghrun-7awxhsvlnm.auto.internal:6567): 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:6567 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-07T15:57:46.188569953+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-07 15:57:46,195 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 02611bfd-58ed-4b70-9f40-85c0e7b91558, ghrun-7awxhsvlnm.auto.internal:6567): 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:6567 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-07T15:57:46.188586631+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-07 15:57:46,196 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,196 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 380cd936-174b-4ffc-ae28-e224ff7c6f34, ghrun-7awxhsvlnm.auto.internal:6567): 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:6567 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-07T15:57:46.188601585+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-07 15:57:46,197 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,197 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, ceb62c69-aaf4-4ce6-b63f-b5b04f70e2d4, ghrun-7awxhsvlnm.auto.internal:6567): 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:6567 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-07T15:57:46.18864646+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-07 15:57:46,198 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 55de5dd9-7c4d-4991-903f-90b6387f773b, ghrun-7awxhsvlnm.auto.internal:6567): 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:6567 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-07T15:57:46.18862478+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-07 15:57:46,199 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,200 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,201 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 7c01733d-bc52-4f75-94b4-53a07e001a64, ghrun-7awxhsvlnm.auto.internal:6567): 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-07T15:57:46.188472186+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-07 15:57:46,201 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 28c1cbae-0be3-404e-9a8b-768a304278bd, ghrun-7awxhsvlnm.auto.internal:6567): 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-07T15:57:46.188499986+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-07 15:57:46,201 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,202 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 97aafcf6-7511-4f98-9860-5bebf5fcfc6c, ghrun-7awxhsvlnm.auto.internal:6567): 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-07T15:57:46.188520655+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-07 15:57:46,202 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 544fc9d1-0f23-4051-a0c9-1f5f5ec1ddd7, ghrun-7awxhsvlnm.auto.internal:6567): 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-07T15:57:46.188541016+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-07 15:57:46,202 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,203 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, fff5f7f2-a7be-49fa-82cb-e29ebaf20f55, ghrun-7awxhsvlnm.auto.internal:6567): 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-07T15:57:46.188560424+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-07 15:57:46,203 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 1dd0b61d-38c9-4163-af59-9875c4db7415, ghrun-7awxhsvlnm.auto.internal:6567): 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-07T15:57:46.188578614+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-07 15:57:46,203 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,204 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, a11e4818-d385-4ff1-91b8-ec41232a8877, ghrun-7awxhsvlnm.auto.internal:6567): 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-07T15:57:46.1886154+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-07 15:57:46,204 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 45c090e4-7312-4bf8-8d36-a1a4c80cb39f, ghrun-7awxhsvlnm.auto.internal:6567): 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-07T15:57:46.188594139+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-07 15:57:46,204 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 26510d7a-6a85-4789-8028-cfbcf5905d9a, ghrun-7awxhsvlnm.auto.internal:6567): 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-07T15:57:46.188634586+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-07 15:57:46,205 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,206 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 2f986f3e-cecb-4629-b80d-975827376af4, ghrun-7awxhsvlnm.auto.internal:6567): 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-07T15:57:46.188661495+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-07 15:57:46,206 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,207 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 15:57:46,208 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,208 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,210 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,210 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,211 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,211 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,212 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,212 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-07 15:57:46,212 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-7awxhsvlnm.auto.internal:6567
2025-11-07 15:57:46,213 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-07 15:57:46,213 - 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-07 15:57:46,213 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-07 15:57:46,214 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-07 15:57:46,214 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-07 15:57:46,214 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-07 15:57:46,215 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-07 15:57:46,215 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-07 15:57:46,215 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-07 15:57:46,216 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-07 15:57:46,220 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-07 15:57:46,223 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 9 < 100, will create new one.
2025-11-07 15:57:46,223 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-07 15:57:46,224 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 8 < 100, will create new one.
2025-11-07 15:57:46,224 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-07 15:57:46,224 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 7 < 100, will create new one.
2025-11-07 15:57:46,225 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-07 15:57:46,225 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 6 < 100, will create new one.
2025-11-07 15:57:46,225 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-07 15:57:46,225 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 5 < 100, will create new one.
2025-11-07 15:57:46,225 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-07 15:57:46,226 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 4 < 100, will create new one.
2025-11-07 15:57:46,226 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-07 15:57:46,226 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 3 < 100, will create new one.
2025-11-07 15:57:46,227 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-07 15:57:46,227 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 2 < 100, will create new one.
2025-11-07 15:57:46,227 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-07 15:57:46,227 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-11-07 15:57:46,228 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-07 15:57:46,228 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:47,298 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:47,302 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:47,306 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:47,320 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:47,369 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:47,573 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:47,693 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:47,762 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:47,878 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:48,080 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:49,816 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:49,879 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:49,914 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:50,128 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:50,720 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:50,981 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:51,071 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:51,115 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:51,226 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:51,283 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:55,512 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:55,677 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:55,829 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:56,220 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 15:57:56,337 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 15:57:56,473 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:56,474 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 15:57:56,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-07 15:57:57,071 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:57,366 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:57,374 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:57,428 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:57,967 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:57:59,004 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:06,477 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 15:58:06,540 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:06,592 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 15:58:07,130 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:07,131 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 15:58:07,131 - 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-07 15:58:07,545 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:07,912 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:08,403 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:08,907 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:09,133 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:10,664 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:12,515 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:13,096 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:13,310 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-07 15:58:13,310 - DEBUG - ya.test - _save_outputs: Command (pid 3716243) rc: 0
2025-11-07 15:58:13,310 - DEBUG - ya.test - _save_outputs: Command (pid 3716243) elapsed time (sec): 30.140619039535522
2025-11-07 15:58:13,311 - DEBUG - ya.test - _save_outputs: Command (pid 3716243) wtime: 30.143
2025-11-07 15:58:13,311 - DEBUG - ya.test - _save_outputs: Command (pid 3716243) 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	210	0	0	0	0
4	      0	0	0	2670	0	0	0	0
5	      0	0	0	2720	0	0	0	0
6	      0	0	0	2720	0	0	0	0
7	      0	0	0	2740	0	0	0	0
8	      0	0	0	2650	0	0	0	0
9	      0	0	0	2720	0	0	0	0
10	      0	0	0	2860	0	0	0	0
11	      0	0	0	2940	0	0	0	0
12	      0	0	0	2920	0	0	0	0
13	      0	0	0	2940	0	0	0	0
14	      0	0	0	2900	0	0	0	0
15	      0	0	0	3160	0	0	0	0
16	      0	0	0	3190	0	0	0	0
17	      0	0	0	2950	0	0	0	0
18	      0	0	0	2990	0	0	0	0
19	      0	0	0	3020	0	0	0	0
20	      0	0	0	2750	0	0	0	0
21	      0	0	0	3070	0	0	0	0
22	      0	0	0	3240	0	0	0	0
23	      0	0	0	2770	0	0	0	0
24	      0	0	0	3210	0	0	0	0
25	      0	0	0	3220	0	0	0	0
26	      0	0	0	3160	0	0	0	0
27	      0	0	0	2870	0	0	0	0
28	      0	0	0	3010	0	0	0	0
29	      0	0	0	3240	0	0	0	0
30	      0	0	0	3260	0	0	0	0

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

2025-11-07 15:58:13,311 - DEBUG - ya.test - _save_outputs: Command (pid 3716243) errors:

2025-11-07 15:58:13,313 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-07 15:58:13,314 - DEBUG - ya.test - _save_outputs: Command (pid 3716244) rc: 0
2025-11-07 15:58:13,314 - DEBUG - ya.test - _save_outputs: Command (pid 3716244) elapsed time (sec): 30.142298698425293
2025-11-07 15:58:13,314 - DEBUG - ya.test - _save_outputs: Command (pid 3716244) wtime: 30.145
2025-11-07 15:58:13,314 - DEBUG - ya.test - _save_outputs: Command (pid 3716244) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	     20	20	0	0	197	591	591	591
2	    223	223	0	0	37	83	455	469
3	    111	111	0	200	33	68	81	98
4	      0	0	0	2670	0	0	0	0
5	      0	0	0	2720	0	0	0	0
6	      0	0	0	2720	0	0	0	0
7	      0	0	0	2740	0	0	0	0
8	      0	0	0	2609	0	0	0	0
9	      0	0	0	2720	0	0	0	0
10	      0	0	0	2859	0	0	0	0
11	      0	0	0	2930	0	0	0	0
12	      0	0	0	2920	0	0	0	0
13	      0	0	0	2904	0	0	0	0
14	      0	0	0	2880	0	0	0	0
15	      0	0	0	3170	0	0	0	0
16	      0	0	0	3190	0	0	0	0
17	      0	0	0	2940	0	0	0	0
18	      0	0	0	2990	0	0	0	0
19	      0	0	0	3020	0	0	0	0
20	      0	0	0	2732	0	0	0	0
21	      0	0	0	3068	0	0	0	0
22	      0	0	0	3230	0	0	0	0
23	      0	0	0	2770	0	0	0	0
24	      0	0	0	3223	0	0	0	0
25	      0	0	0	3214	0	0	0	0
26	      0	0	0	3163	0	0	0	0
27	      0	0	0	2870	0	0	0	0
28	      0	0	0	3020	0	0	0	0
29	      0	0	0	3240	0	0	0	0
30	      0	0	0	3260	0	0	0	0

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    354	11.8	0	79972	36	407	567	591

2025-11-07 15:58:13,314 - DEBUG - ya.test - _save_outputs: Command (pid 3716244) errors:

2025-11-07 15:58:14,715 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-07 15:58:14,716 - DEBUG - ya.test - _save_outputs: Command (pid 3716245) rc: 0
2025-11-07 15:58:14,716 - DEBUG - ya.test - _save_outputs: Command (pid 3716245) elapsed time (sec): 31.543487548828125
2025-11-07 15:58:14,716 - DEBUG - ya.test - _save_outputs: Command (pid 3716245) wtime: 31.545
2025-11-07 15:58:14,716 - DEBUG - ya.test - _save_outputs: Command (pid 3716245) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	     10	10	0	0	635	767	767	767
2	    144	144	0	0	57	475	535	563
3	     69	69	0	200	58	99	99	100
4	      0	0	0	2665	0	0	0	0
5	      0	0	0	2725	0	0	0	0
6	      0	0	0	2720	0	0	0	0
7	      0	0	0	2740	0	0	0	0
8	      0	0	0	2638	0	0	0	0
9	      0	0	0	2720	0	0	0	0
10	      0	0	0	2869	0	0	0	0
11	      0	0	0	2926	0	0	0	0
12	      0	0	0	2930	0	0	0	0
13	      0	0	0	2940	0	0	0	0
14	      0	0	0	2890	0	0	0	0
15	      0	0	0	3154	0	0	0	0
16	      0	0	0	3196	0	0	0	0
17	      0	0	0	2930	0	0	0	0
18	      0	0	0	1160	0	0	0	0
19	      0	0	0	0	0	0	0	0
20	      0	0	0	0	0	0	0	0
21	      0	0	0	1930	0	0	0	0
22	      0	0	0	3240	0	0	0	0
23	      0	0	0	2770	0	0	0	0
24	      0	0	0	3220	0	0	0	0
25	      0	0	0	3217	0	0	0	0
26	      0	0	0	3163	0	0	0	0
27	      0	0	0	2910	0	0	0	0
28	      0	0	0	3020	0	0	0	0
29	      0	0	0	1080	0	0	0	0
30	      0	0	0	10	0	0	0	0

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    223	7.43333	0	65963	58	531	731	767

2025-11-07 15:58:14,716 - DEBUG - ya.test - _save_outputs: Command (pid 3716245) errors:

2025-11-07 15:58:17,133 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 15:58:17,246 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 15:58:19,049 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 15:58:19,049 - 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-07 15:58:26,410 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:28,048 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:28,058 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:28,916 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:29,052 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 15:58:29,168 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 15:58:29,304 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:29,305 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 15:58:29,305 - 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-07 15:58:30,548 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:37,922 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:38,312 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:38,816 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:38,868 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:58:39,307 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 15:58:39,423 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 15:58:41,130 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 15:58:41,131 - 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-07 15:58:51,133 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 15:58:51,253 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 15:58:53,175 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 15:58:53,175 - 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-07 15:59:02,731 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:59:03,177 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 15:59:03,292 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 15:59:03,922 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:59:03,922 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 15:59:03,922 - 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-07 15:59:13,450 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:59:13,924 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 15:59:14,033 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 15:59:15,242 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 15:59:15,243 - 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-07 15:59:19,474 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:59:23,613 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:59:25,245 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 15:59:25,363 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 15:59:26,314 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:59:26,314 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 15:59:26,315 - 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-07 15:59:26,431 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:59:29,622 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:59:30,410 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:59:30,620 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:59:36,316 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 15:59:36,426 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 15:59:37,521 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 15:59:37,521 - 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-07 15:59:47,523 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 15:59:47,637 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 15:59:49,106 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:59:49,107 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 15:59:49,107 - 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-07 15:59:55,538 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 15:59:59,109 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 15:59:59,221 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 16:00:00,994 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 16:00:00,995 - 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-07 16:00:01,263 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:00:04,698 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:00:07,772 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:00:08,360 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:00:10,997 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 16:00:11,107 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 16:00:12,665 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 16:00:12,666 - 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-07 16:00:14,548 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:00:22,156 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:00:22,668 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 16:00:22,776 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 16:00:22,791 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:00:22,792 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 16:00:22,792 - 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-07 16:00:27,768 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:00:32,794 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 16:00:32,904 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 16:00:34,015 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 16:00:34,015 - 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-07 16:00:40,679 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:00:41,794 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:00:43,501 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:00:44,017 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 16:00:44,130 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 16:00:44,486 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:00:44,487 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 16:00:44,487 - 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-07 16:00:51,913 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:00:54,489 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 16:00:54,603 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 16:00:55,845 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 16:00:55,845 - 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-07 16:00:58,909 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:01:05,848 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 16:01:05,871 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:01:05,961 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 16:01:07,897 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 16:01:07,897 - 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-07 16:01:08,990 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:01:17,000 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:01:17,899 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 16:01:18,016 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 16:01:19,573 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 16:01:19,574 - 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-07 16:01:22,272 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:01:26,570 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:01:29,576 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 16:01:29,692 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 16:01:30,264 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:01:30,264 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 16:01:30,265 - 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-07 16:01:36,465 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:01:39,990 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:01:40,267 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 16:01:40,379 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 16:01:40,796 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:01:40,796 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 16:01:40,797 - 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-07 16:01:44,666 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:01:50,799 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 16:01:50,910 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 16:01:51,330 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:01:51,331 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 16:01:51,331 - 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-07 16:02:01,333 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 16:02:01,441 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 16:02:03,371 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 16:02:03,371 - 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-07 16:02:09,286 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:02:09,773 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:02:10,782 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-07 16:02:13,373 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 16:02:13,482 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 16:02:14,527 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-07 16:02:14,527 - 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-07 16:02:14,558 - 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-07 16:02:14,561 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-07 16:02:14,561 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-07 16:02:14,561 - DEBUG - ydb.topic - close: Close topic client
2025-11-07 16:02:14,561 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-07 16:02:14,562 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 16:02:24,559 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:6567
2025-11-07 16:02:24,563 - DEBUG - library.python.cores - recover_core_dump_file: hostname = 'ghrun-7awxhsvlnm'
2025-11-07 16:02:24,563 - DEBUG - library.python.cores - recover_core_dump_file: rlimit_core = '(0, -1)'
2025-11-07 16:02:24,564 - 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-07 16:02:24,564 - DEBUG - library.python.cores - recover_core_dump_file: core_uses_pid = '1'
2025-11-07 16:02:24,564 - DEBUG - library.python.cores - recover_core_dump_file: Core dump dir (/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1) permission mask: 0o40755 (expected: 0o41777 (0o40000-dir, 0o1000-sticky bit))
2025-11-07 16:02:24,564 - DEBUG - library.python.cores - recover_core_dump_file: Search for core dump files match pattern 'core.3702739' in '/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1'
2025-11-07 16:02:24,565 - DEBUG - library.python.cores - recover_core_dump_file: Matched core dump files (0/3): [] (mismatched samples: stderr, stdout, logfile_jlu94f23.log)
2025-11-07 16:02:24,565 - WARNING - library.python.cores - recover_core_dump_file: Core dump dir doesn't exist: /coredumps
2025-11-07 16:02:24,565 - WARNING - library.python.cores - recover_core_dump_file: Core dump dir doesn't exist: /var/tmp/cores
2025-11-07 16:02:24,565 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:6567/1
2025-11-07 16:02:24,566 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7115
2025-11-07 16:02:24,566 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/62966
2025-11-07 16:02:24,566 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20601
2025-11-07 16:02:24,566 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6567
2025-11-07 16:02:24,566 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/14941
2025-11-07 16:02:24,612 - 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/5zmm/0002a9/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/stdout
E   Stderr file name: 
E   /home/runner/.ya/build/build_root/5zmm/0002a9/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk0/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/stderr
E   Stderr content:
E   
E   GRpc memory quota was set but disabled due to issues with grpc quoter, to enable it use EnableGRpcMemoryQuota option
E   VERIFY failed (2025-11-07T15:57:43.531762Z): source_id=445;tablet_id=72075186224037925;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 @ 0xA634E05
E   1. /-S/util/system/yassert.cpp:55: Panic @ 0xA62D346
E   2. /tmp//-S/ydb/library/actors/core/log.cpp:857: ~TVerifyFormattedRecordWriter @ 0xB4BBBF8
E   3. /-S/ydb/library/formats/arrow/switch/compare.h:82: TypedCompare<false> @ 0xD7B36CB
E   4. /-S/ydb/core/formats/arrow/reader/position.h:83: CompareImpl @ 0x9C8A1F8
E   5. /-S/ydb/core/formats/arrow/reader/position.h:471: Compare @ 0x9C89F4F
E   6. /tmp//-S/ydb/core/formats/arrow/reader/position.cpp:36: FindBound @ 0xD78ECDD
E   7. /tmp//-S/ydb/core/tx/columnshard/engines/predicate/filter.cpp:21: BuildFilter @ 0x164FABEB
E   8. /tmp//-S/ydb/core/tx/columnshard/engines/reader/simple_reader/iterator/fetching.cpp:15: DoExecuteInplace @ 0x167519C7
E   9. /-S/ydb/core/tx/columnshard/engines/reader/common_reader/common/script.h:47: ExecuteInplace @ 0x166A91C3
E   10. /tmp//-S/ydb/core/tx/columnshard/engines/reader/common_reader/common/script_cursor.cpp:36: Execute @ 0x166A91C3
E   11. /tmp//-S/ydb/core/tx/columnshard/engines/reader/common_reader/iterator/fetching.cpp:30: DoExecuteImpl @ 0x166A5FE3
E   12. /tmp//-S/ydb/core/tx/columnshard/engines/reader/common/conveyor_task.cpp:8: DoExecute @ 0x166ACA97
E   13. /tmp//-S/ydb/core/tx/conveyor/usage/abstract.cpp:16: Execute @ 0x16518338
E   14. /tmp//-S/ydb/core/tx/conveyor_composite/service/worker.cpp:17: ExecuteTask @ 0x1C9635F5
E   15. /tmp//-S/ydb/library/actors/core/actor.cpp:350: Receive @ 0xB466E57
E   16. /tmp//-S/ydb/library/actors/core/executor_thread.cpp:267: Execute @ 0xB4AC6A4
E   17. /tmp//-S/ydb/library/actors/core/executor_thread.cpp:455: operator() @ 0xB4B0764
E   18. /tmp//-S/ydb/library/actors/core/executor_thread.cpp:507: ProcessExecutorPool @ 0xB4B0370
E   19. /tmp//-S/ydb/library/actors/core/executor_thread.cpp:533: ThreadProc @ 0xB4B0F5E
E   20. /-S/util/system/thread.cpp:245: ThreadProxy @ 0xA636A5C
E   21. ??:0: ?? @ 0x7F6AACEF4AC2
E   22. ??:0: ?? @ 0x7F6AACF868BF
2025-11-07 16:02:24,676 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:6567". Check endpoint correctness.
2025-11-07 16:02:24,676 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-07 16:02:57,423 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/5zmm/0002a9/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:6567', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-11-07 16:02:57,424 - DEBUG - ya.test - execute: Command pid: 3735503
2025-11-07 16:02:57,517 - DEBUG - ya.test - _kill_process_tree: Killing process 3735503
2025-11-07 16:02:57,517 - DEBUG - ya.test - _nix_kill_process_tree: Killing process tree for pid 3735503 (bin:'error([Errno 2] No such file or directory: '/proc/3735503/exe')')
2025-11-07 16:02:57,517 - DEBUG - ya.test - try_to_send_signal: Error while sending signal 19 to pid 3735503: [Errno 3] No such process
2025-11-07 16:02:57,518 - DEBUG - ya.test - try_to_send_signal: Error while sending signal 9 to pid 3735503: [Errno 3] No such process
2025-11-07 16:02:57,518 - DEBUG - ya.test - try_to_send_signal: Error while sending signal 18 to pid 3735503: [Errno 3] No such process
2025-11-07 16:02:57,518 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-07 16:02:57,518 - DEBUG - ya.test - wait: Command exception: sys.meta_path is None, Python is likely shutting down
2025-11-07 16:02:57,518 - DEBUG - ya.test - _save_outputs: Command (pid 3735503) rc: 0
2025-11-07 16:02:57,518 - DEBUG - ya.test - _save_outputs: Command (pid 3735503) elapsed time (sec): 0.0933535099029541
2025-11-07 16:02:57,518 - DEBUG - ya.test - _save_outputs: Command (pid 3735503) wtime: 0.094
2025-11-07 16:02:57,518 - DEBUG - ya.test - _save_outputs: Command (pid 3735503) output:
Remove path log...

2025-11-07 16:02:57,518 - DEBUG - ya.test - _save_outputs: Command (pid 3735503) errors:
Status: TRANSPORT_UNAVAILABLE
Issues: 
<main>: Error: GRpc error: (14): connections to all backends failing; last error: UNKNOWN: ipv4:127.0.0.1:6567: Failed to connect to remote host: Connection refused
<main>: Error: Grpc error response on endpoint localhost:6567
<main>: Error: Endpoint list is empty for database /Root, cluster endpoint localhost:6567.

2025-11-07 15:57:08,084 - INFO - ya.test - pytest_collection_modifyitems: Modulo 1 tests are: []
2025-11-07 15:57:08,462 - INFO - ya.test - pytest_collection_modifyitems: Modulo 2 tests are: []
2025-11-07 15:57:08,747 - INFO - ya.test - pytest_collection_modifyitems: Modulo 3 tests are: []
2025-11-07 15:57:08,489 - INFO - ya.test - pytest_collection_modifyitems: Modulo 4 tests are: []
2025-11-07 15:57:08,422 - INFO - ya.test - pytest_collection_modifyitems: Modulo 5 tests are: []
2025-11-07 15:57:08,329 - INFO - ya.test - pytest_collection_modifyitems: Modulo 6 tests are: []
2025-11-07 15:57:08,559 - INFO - ya.test - pytest_collection_modifyitems: Modulo 7 tests are: []
2025-11-07 15:57:08,062 - INFO - ya.test - pytest_collection_modifyitems: Modulo 8 tests are: []
2025-11-07 15:57:08,832 - INFO - ya.test - pytest_collection_modifyitems: Modulo 9 tests are: []
