2025-11-10 10:02:09,315 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-10 10:02:09,315 - INFO - ya.test - pytest_runtest_setup: test_log_uniform
2025-11-10 10:02:09,316 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-10 10:02:09,316 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-10 10:02:09,318 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-10 10:02:09,318 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-10 10:02:09,319 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-10 10:02:09,320 - DEBUG - ya.test - execute: Command pid: 1908631
2025-11-10 10:02:09,761 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) rc: 0
2025-11-10 10:02:09,761 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) elapsed time (sec): 0.4397592544555664
2025-11-10 10:02:09,761 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) idrss: 0
2025-11-10 10:02:09,761 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) inblock: 0
2025-11-10 10:02:09,762 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) isrss: 0
2025-11-10 10:02:09,762 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) ixrss: 0
2025-11-10 10:02:09,762 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) majflt: 0
2025-11-10 10:02:09,762 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) maxrss: 541008
2025-11-10 10:02:09,762 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) minflt: 16174
2025-11-10 10:02:09,762 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) msgrcv: 0
2025-11-10 10:02:09,763 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) msgsnd: 0
2025-11-10 10:02:09,763 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) nivcsw: 9
2025-11-10 10:02:09,763 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) nsignals: 0
2025-11-10 10:02:09,763 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) nswap: 0
2025-11-10 10:02:09,763 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) nvcsw: 13
2025-11-10 10:02:09,763 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) oublock: 8
2025-11-10 10:02:09,764 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) stime: 0.2756
2025-11-10 10:02:09,764 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) utime: 0.11583199999999999
2025-11-10 10:02:09,764 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) wtime: 0.441
2025-11-10 10:02:09,764 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) output:
Git info:
    Commit: 2d3bc32bffc01725fa6c3dd0bb0f7616ee098760
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 4ff3894868fb9bba81a268efd022ca68496f230a into 27b8fe5960eec979164acf2636986bcea3ca729d

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

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

2025-11-10 10:02:09,765 - DEBUG - ya.test - _save_outputs: Command (pid 1908631) errors:

2025-11-10 10:02:09,765 - INFO - __tests__.test_log_scenario - _setup_ydb: Git info:
    Commit: 2d3bc32bffc01725fa6c3dd0bb0f7616ee098760
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 4ff3894868fb9bba81a268efd022ca68496f230a into 27b8fe5960eec979164acf2636986bcea3ca729d

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

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

2025-11-10 10:02:09,902 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydbd/ydbd
2025-11-10 10:02:09,903 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/4475
2025-11-10 10:02:09,905 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/6t6o/0024d4/r3tmp/kikimr_cluster_2wlcidq9
2025-11-10 10:02:09,936 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydbd/ydbd
2025-11-10 10:02:09,936 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17720
2025-11-10 10:02:09,937 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27843
2025-11-10 10:02:09,937 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/31337
2025-11-10 10:02:09,937 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/63103
2025-11-10 10:02:09,938 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/kikimr_configs"
2025-11-10 10:02:09,938 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/6t6o/0024d4/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/6t6o/0024d4/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/logfile__855djqn.log --grpc-port=17720 --mon-port=27843 --ic-port=4475
2025-11-10 10:02:09,939 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:17720/1, disk_size 68719476736
2025-11-10 10:02:09,939 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/kikimr_configs"
2025-11-10 10:02:09,939 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/6t6o/0024d4/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/6t6o/0024d4/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/logfile__855djqn.log --grpc-port=17720 --mon-port=27843 --ic-port=4475
2025-11-10 10:02:09,940 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/logfile__855djqn.log', '--grpc-port=17720', '--mon-port=27843', '--ic-port=4475')' in '/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1' (no wait)
2025-11-10 10:02:09,941 - DEBUG - ya.test - execute: Command pid: 1908930
2025-11-10 10:02:09,942 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:17720/1
2025-11-10 10:02:09,943 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-10 10:02:09,943 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-10 10:02:09,944 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-10 10:02:09,945 - DEBUG - ya.test - execute: Command pid: 1908931
2025-11-10 10:02:10,356 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) rc: 0
2025-11-10 10:02:10,357 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) elapsed time (sec): 0.40983033180236816
2025-11-10 10:02:10,357 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) idrss: 0
2025-11-10 10:02:10,357 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) inblock: 0
2025-11-10 10:02:10,358 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) isrss: 0
2025-11-10 10:02:10,358 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) ixrss: 0
2025-11-10 10:02:10,358 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) majflt: 0
2025-11-10 10:02:10,358 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) maxrss: 540980
2025-11-10 10:02:10,358 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) minflt: 16505
2025-11-10 10:02:10,359 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) msgrcv: 0
2025-11-10 10:02:10,359 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) msgsnd: 0
2025-11-10 10:02:10,359 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) nivcsw: 12
2025-11-10 10:02:10,359 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) nsignals: 0
2025-11-10 10:02:10,360 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) nswap: 0
2025-11-10 10:02:10,360 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) nvcsw: 18
2025-11-10 10:02:10,360 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) oublock: 8
2025-11-10 10:02:10,360 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) stime: 0.241189
2025-11-10 10:02:10,360 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) utime: 0.12059399999999999
2025-11-10 10:02:10,361 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) wtime: 0.412
2025-11-10 10:02:10,361 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) output:
Git info:
    Commit: 2d3bc32bffc01725fa6c3dd0bb0f7616ee098760
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 4ff3894868fb9bba81a268efd022ca68496f230a into 27b8fe5960eec979164acf2636986bcea3ca729d

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

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

2025-11-10 10:02:10,361 - DEBUG - ya.test - _save_outputs: Command (pid 1908931) errors:

2025-11-10 10:02:10,363 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 2d3bc32bffc01725fa6c3dd0bb0f7616ee098760
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 4ff3894868fb9bba81a268efd022ca68496f230a into 27b8fe5960eec979164acf2636986bcea3ca729d
2025-11-10 10:02:12,204 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydbd/ydbd
2025-11-10 10:02:12,204 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:17720', '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: 4475\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-10 10:02:12,205 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-10 10:02:12,206 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-10 10:02:12,206 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:17720', '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: 4475\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-10 10:02:12,207 - DEBUG - ya.test - execute: Command pid: 1910166
2025-11-10 10:02:12,694 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) rc: 0
2025-11-10 10:02:12,695 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) elapsed time (sec): 0.48635268211364746
2025-11-10 10:02:12,695 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) idrss: 0
2025-11-10 10:02:12,695 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) inblock: 0
2025-11-10 10:02:12,695 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) isrss: 0
2025-11-10 10:02:12,695 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) ixrss: 0
2025-11-10 10:02:12,695 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) majflt: 0
2025-11-10 10:02:12,696 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) maxrss: 549260
2025-11-10 10:02:12,696 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) minflt: 18314
2025-11-10 10:02:12,696 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) msgrcv: 0
2025-11-10 10:02:12,696 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) msgsnd: 0
2025-11-10 10:02:12,696 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) nivcsw: 7
2025-11-10 10:02:12,696 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) nsignals: 0
2025-11-10 10:02:12,697 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) nswap: 0
2025-11-10 10:02:12,697 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) nvcsw: 136
2025-11-10 10:02:12,697 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) oublock: 8
2025-11-10 10:02:12,697 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) stime: 0.307658
2025-11-10 10:02:12,697 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) utime: 0.134899
2025-11-10 10:02:12,697 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) wtime: 0.487
2025-11-10 10:02:12,698 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-10 10:02:12,698 - DEBUG - ya.test - _save_outputs: Command (pid 1910166) errors:

2025-11-10 10:02:12,699 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydbd/ydbd
2025-11-10 10:02:12,699 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:17720', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-11-10 10:02:12,700 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-10 10:02:12,700 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-10 10:02:12,701 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:17720', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-10 10:02:12,702 - DEBUG - ya.test - execute: Command pid: 1910429
2025-11-10 10:02:13,216 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) rc: 0
2025-11-10 10:02:13,217 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) elapsed time (sec): 0.5134930610656738
2025-11-10 10:02:13,217 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) idrss: 0
2025-11-10 10:02:13,217 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) inblock: 0
2025-11-10 10:02:13,217 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) isrss: 0
2025-11-10 10:02:13,218 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) ixrss: 0
2025-11-10 10:02:13,218 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) majflt: 0
2025-11-10 10:02:13,218 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) maxrss: 549252
2025-11-10 10:02:13,218 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) minflt: 18314
2025-11-10 10:02:13,218 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) msgrcv: 0
2025-11-10 10:02:13,219 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) msgsnd: 0
2025-11-10 10:02:13,219 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) nivcsw: 14
2025-11-10 10:02:13,219 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) nsignals: 0
2025-11-10 10:02:13,219 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) nswap: 0
2025-11-10 10:02:13,219 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) nvcsw: 123
2025-11-10 10:02:13,220 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) oublock: 8
2025-11-10 10:02:13,220 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) stime: 0.324861
2025-11-10 10:02:13,220 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) utime: 0.140372
2025-11-10 10:02:13,220 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) wtime: 0.515
2025-11-10 10:02:13,221 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-10 10:02:13,221 - DEBUG - ya.test - _save_outputs: Command (pid 1910429) errors:

2025-11-10 10:02:13,221 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-10 10:02:13,238 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-10 10:02:13,249 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:02:13,252 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:02:13,254 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:02:13,265 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-10 10:02:13,266 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 8414414f-b8b2-4a87-a4d8-db2ebc3d7c48, localhost:17720): creating call state
2025-11-10 10:02:13,266 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 8414414f-b8b2-4a87-a4d8-db2ebc3d7c48, localhost:17720): request = { database: "/Root" }
2025-11-10 10:02:13,270 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 8414414f-b8b2-4a87-a4d8-db2ebc3d7c48, localhost:17720): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-kipqlopw2q.auto.internal" port: 17720 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-10 10:02:13,271 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-kipqlopw2q.auto.internal:17720, location 1, ssl: False>]>
2025-11-10 10:02:13,271 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:02:13,282 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_uniform)
2025-11-10 10:02:13,283 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydb/ydb
2025-11-10 10:02:13,283 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydb/ydb -e grpc://localhost:17720 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-11-10 10:02:13,284 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-11-10 10:02:13,285 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-11-10 10:02:13,285 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17720', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-11-10 10:02:13,286 - DEBUG - ya.test - execute: Command pid: 1910902
2025-11-10 10:02:13,728 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) rc: 0
2025-11-10 10:02:13,728 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) elapsed time (sec): 0.44057750701904297
2025-11-10 10:02:13,728 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) idrss: 0
2025-11-10 10:02:13,728 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) inblock: 0
2025-11-10 10:02:13,729 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) isrss: 0
2025-11-10 10:02:13,729 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) ixrss: 0
2025-11-10 10:02:13,729 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) majflt: 0
2025-11-10 10:02:13,729 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) maxrss: 165956
2025-11-10 10:02:13,730 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) minflt: 3537
2025-11-10 10:02:13,730 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) msgrcv: 0
2025-11-10 10:02:13,730 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) msgsnd: 0
2025-11-10 10:02:13,730 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) nivcsw: 11
2025-11-10 10:02:13,731 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) nsignals: 0
2025-11-10 10:02:13,731 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) nswap: 0
2025-11-10 10:02:13,731 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) nvcsw: 169
2025-11-10 10:02:13,731 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) oublock: 8
2025-11-10 10:02:13,732 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) stime: 0.029809
2025-11-10 10:02:13,732 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) utime: 0.059619
2025-11-10 10:02:13,732 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) wtime: 0.442
2025-11-10 10:02:13,732 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) output:
Init tables ...
Init tables ...Ok

2025-11-10 10:02:13,733 - DEBUG - ya.test - _save_outputs: Command (pid 1910902) errors:

2025-11-10 10:02:13,733 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-10 10:02:13,733 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydb/ydb -e grpc://localhost:17720 -d /Root workload log --path log run bulk_upsert --seconds 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-11-10 10:02:13,734 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-11-10 10:02:13,735 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-11-10 10:02:13,735 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17720', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-11-10 10:02:13,736 - DEBUG - ya.test - execute: Command pid: 1911235
2025-11-10 10:02:43,887 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-10 10:02:43,888 - DEBUG - ya.test - _save_outputs: Command (pid 1911235) rc: 0
2025-11-10 10:02:43,888 - DEBUG - ya.test - _save_outputs: Command (pid 1911235) elapsed time (sec): 30.150880098342896
2025-11-10 10:02:43,888 - DEBUG - ya.test - _save_outputs: Command (pid 1911235) wtime: 30.152
2025-11-10 10:02:43,888 - DEBUG - ya.test - _save_outputs: Command (pid 1911235) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	    730	730	0	0	12	21	34	47
2	    945	945	0	0	9	14	19	26
3	    923	923	0	0	10	15	22	36
4	    915	915	0	0	10	15	19	21
5	    835	835	0	0	11	16	20	28
6	    757	757	0	0	12	20	23	26
7	    887	887	0	0	10	16	19	20
8	    769	769	0	0	11	22	32	41
9	    739	739	0	0	12	20	23	26
10	    692	692	0	0	12	24	36	43
11	    846	846	0	0	10	17	23	29
12	    701	701	0	0	12	25	30	34
13	    767	767	0	0	11	20	24	31
14	    743	743	0	0	12	21	26	34
15	    707	707	0	0	12	20	50	63
16	    649	649	0	0	13	27	37	45
17	    792	792	0	0	11	17	20	24
18	    839	839	0	0	11	18	23	29
19	    752	752	0	0	11	21	52	61
20	    818	818	0	0	11	18	23	27
21	    811	811	0	0	10	20	27	30
22	    803	803	0	0	11	21	25	30
23	    645	645	0	0	14	24	37	48
24	    734	734	0	0	12	22	37	42
25	    646	646	0	0	13	27	37	46
26	    699	699	0	0	12	22	27	35
27	    581	581	0	0	15	32	47	51
28	    742	742	0	0	11	22	31	35
29	    582	582	0	0	16	27	32	35
30	    647	647	0	0	13	26	38	41

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	  22696	756.533	0	0	11	21	30	63

2025-11-10 10:02:43,889 - DEBUG - ya.test - _save_outputs: Command (pid 1911235) errors:

2025-11-10 10:02:43,889 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:43,890 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 7f8881b5-aaa1-45b9-9ae1-32e74442030a, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:43,890 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 7f8881b5-aaa1-45b9-9ae1-32e74442030a, ghrun-kipqlopw2q.auto.internal:17720): request = {  }
2025-11-10 10:02:43,893 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 7f8881b5-aaa1-45b9-9ae1-32e74442030a, ghrun-kipqlopw2q.auto.internal:17720): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MmZiMGQyYzItYThhZmNmOGQtODE1OTVhOGEtYmI2MGMxNw==" node_id: 1 }
2025-11-10 10:02:43,894 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, aeeb9237-5bff-4baa-9c23-434e1e88dff9, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:43,894 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, aeeb9237-5bff-4baa-9c23-434e1e88dff9, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=MmZiMGQyYzItYThhZmNmOGQtODE1OTVhOGEtYmI2MGMxNw==" }
2025-11-10 10:02:43,896 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, aeeb9237-5bff-4baa-9c23-434e1e88dff9, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:43,898 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MmZiMGQyYzItYThhZmNmOGQtODE1OTVhOGEtYmI2MGMxNw==
2025-11-10 10:02:43,898 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 854e0f24-8657-4938-b415-c1413ebfe376, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:43,899 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 854e0f24-8657-4938-b415-c1413ebfe376, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=MmZiMGQyYzItYThhZmNmOGQtODE1OTVhOGEtYmI2MGMxNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:02:43,899 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 854e0f24-8657-4938-b415-c1413ebfe376, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:44,314 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmZiMGQyYzItYThhZmNmOGQtODE1OTVhOGEtYmI2MGMxNw==
2025-11-10 10:02:44,314 - INFO - root - test_log_uniform: Count rows after insert 22177 before wait
2025-11-10 10:02:44,314 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmZiMGQyYzItYThhZmNmOGQtODE1OTVhOGEtYmI2MGMxNw==
2025-11-10 10:02:44,315 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8e328a4e-7a72-48fe-b516-afef6e27ed39, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,315 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8e328a4e-7a72-48fe-b516-afef6e27ed39, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=MmZiMGQyYzItYThhZmNmOGQtODE1OTVhOGEtYmI2MGMxNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:02:44,317 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8e328a4e-7a72-48fe-b516-afef6e27ed39, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:44,592 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmZiMGQyYzItYThhZmNmOGQtODE1OTVhOGEtYmI2MGMxNw==
2025-11-10 10:02:44,592 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmZiMGQyYzItYThhZmNmOGQtODE1OTVhOGEtYmI2MGMxNw==
2025-11-10 10:02:44,593 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 57dbcf77-7a01-4873-902b-9881d5103a94, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,593 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 57dbcf77-7a01-4873-902b-9881d5103a94, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=MmZiMGQyYzItYThhZmNmOGQtODE1OTVhOGEtYmI2MGMxNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-10 10:02:44,594 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 57dbcf77-7a01-4873-902b-9881d5103a94, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:44,865 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmZiMGQyYzItYThhZmNmOGQtODE1OTVhOGEtYmI2MGMxNw==
2025-11-10 10:02:44,866 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-10 10:02:44,867 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydb/ydb -e grpc://localhost:17720 -d /Root workload log --path log run bulk_upsert --seconds 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-11-10 10:02:44,867 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-11-10 10:02:44,867 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-11-10 10:02:44,869 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-11-10 10:02:44,869 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydb/ydb -e grpc://localhost:17720 -d /Root workload log --path log run insert --seconds 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-11-10 10:02:44,870 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmZiMGQyYzItYThhZmNmOGQtODE1OTVhOGEtYmI2MGMxNw==
2025-11-10 10:02:44,870 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydb/ydb -e grpc://localhost:17720 -d /Root workload log --path log run upsert --seconds 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-11-10 10:02:44,875 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 52a0f8da-c5ff-4a27-88fb-4e0a43c449f9, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,875 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-11-10 10:02:44,879 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 52a0f8da-c5ff-4a27-88fb-4e0a43c449f9, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=MmZiMGQyYzItYThhZmNmOGQtODE1OTVhOGEtYmI2MGMxNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:02:44,879 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-11-10 10:02:44,881 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.2
2025-11-10 10:02:44,882 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.3
2025-11-10 10:02:44,884 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 52a0f8da-c5ff-4a27-88fb-4e0a43c449f9, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:44,885 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17720', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-11-10 10:02:44,886 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-11-10 10:02:44,889 - DEBUG - ya.test - execute: Command pid: 1938917
2025-11-10 10:02:44,880 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 2ed1efa4-fe59-454c-a595-474464bfd9ca, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,887 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-11-10 10:02:44,891 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17720', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'upsert', '--seconds', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-11-10 10:02:44,891 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 2ed1efa4-fe59-454c-a595-474464bfd9ca, ghrun-kipqlopw2q.auto.internal:17720): request = {  }
2025-11-10 10:02:44,890 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:17720', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'insert', '--seconds', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-11-10 10:02:44,892 - DEBUG - ya.test - execute: Command pid: 1938918
2025-11-10 10:02:44,894 - DEBUG - ya.test - execute: Command pid: 1938919
2025-11-10 10:02:44,896 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 2ed1efa4-fe59-454c-a595-474464bfd9ca, ghrun-kipqlopw2q.auto.internal:17720): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=Nzc2MTY3YzUtZDQ0N2YzNTMtNWE1MDIzZDYtOGNiYmQ5Yjc=" node_id: 1 }
2025-11-10 10:02:44,897 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 54e014f6-f5d9-4522-b308-af4381d6edd6, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,897 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 54e014f6-f5d9-4522-b308-af4381d6edd6, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=Nzc2MTY3YzUtZDQ0N2YzNTMtNWE1MDIzZDYtOGNiYmQ5Yjc=" }
2025-11-10 10:02:44,898 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 54e014f6-f5d9-4522-b308-af4381d6edd6, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:44,900 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=Nzc2MTY3YzUtZDQ0N2YzNTMtNWE1MDIzZDYtOGNiYmQ5Yjc=
2025-11-10 10:02:44,901 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7f64a88c-64e3-4759-85fa-b3cc5f028ef2, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,901 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7f64a88c-64e3-4759-85fa-b3cc5f028ef2, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=Nzc2MTY3YzUtZDQ0N2YzNTMtNWE1MDIzZDYtOGNiYmQ5Yjc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:02:44,901 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 2 < 100, will create new one.
2025-11-10 10:02:44,903 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 87ba559a-5641-420a-8dfd-a19dc6379749, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,904 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 87ba559a-5641-420a-8dfd-a19dc6379749, ghrun-kipqlopw2q.auto.internal:17720): request = {  }
2025-11-10 10:02:44,903 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7f64a88c-64e3-4759-85fa-b3cc5f028ef2, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:44,906 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 87ba559a-5641-420a-8dfd-a19dc6379749, ghrun-kipqlopw2q.auto.internal:17720): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=Y2Q1ZTdlNDAtOGZhNWViZGItNGU1OTViYTktOTE2YzE0N2E=" node_id: 1 }
2025-11-10 10:02:44,907 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 63367d0d-0717-470a-be5b-f7df3b4d3805, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,907 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 63367d0d-0717-470a-be5b-f7df3b4d3805, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=Y2Q1ZTdlNDAtOGZhNWViZGItNGU1OTViYTktOTE2YzE0N2E=" }
2025-11-10 10:02:44,908 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 63367d0d-0717-470a-be5b-f7df3b4d3805, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:44,910 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=Y2Q1ZTdlNDAtOGZhNWViZGItNGU1OTViYTktOTE2YzE0N2E=
2025-11-10 10:02:44,911 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a2bd03d4-d148-40ee-b75a-195da244ca95, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,911 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a2bd03d4-d148-40ee-b75a-195da244ca95, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=Y2Q1ZTdlNDAtOGZhNWViZGItNGU1OTViYTktOTE2YzE0N2E=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:02:44,911 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 3 < 100, will create new one.
2025-11-10 10:02:44,913 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 0de0e0f9-9c0e-460a-bf60-0e8672387bcc, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,913 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 0de0e0f9-9c0e-460a-bf60-0e8672387bcc, ghrun-kipqlopw2q.auto.internal:17720): request = {  }
2025-11-10 10:02:44,913 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a2bd03d4-d148-40ee-b75a-195da244ca95, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:44,916 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 0de0e0f9-9c0e-460a-bf60-0e8672387bcc, ghrun-kipqlopw2q.auto.internal:17720): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=Y2FiOWI4YWUtZDU0N2M4MjMtZTM0YzA5Y2QtOWM3NjIyOA==" node_id: 1 }
2025-11-10 10:02:44,916 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 0b5e0b52-55d3-49f7-8531-498e9a4c7111, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,917 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 0b5e0b52-55d3-49f7-8531-498e9a4c7111, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=Y2FiOWI4YWUtZDU0N2M4MjMtZTM0YzA5Y2QtOWM3NjIyOA==" }
2025-11-10 10:02:44,917 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 0b5e0b52-55d3-49f7-8531-498e9a4c7111, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:44,919 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=Y2FiOWI4YWUtZDU0N2M4MjMtZTM0YzA5Y2QtOWM3NjIyOA==
2025-11-10 10:02:44,920 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 50d68972-cc16-46c7-a676-c6db0f1c7e30, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,920 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 50d68972-cc16-46c7-a676-c6db0f1c7e30, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=Y2FiOWI4YWUtZDU0N2M4MjMtZTM0YzA5Y2QtOWM3NjIyOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:02:44,921 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 4 < 100, will create new one.
2025-11-10 10:02:44,923 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, c57b7c70-6ce5-4a32-b959-88ef5ccc2029, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,923 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, c57b7c70-6ce5-4a32-b959-88ef5ccc2029, ghrun-kipqlopw2q.auto.internal:17720): request = {  }
2025-11-10 10:02:44,922 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 50d68972-cc16-46c7-a676-c6db0f1c7e30, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:44,926 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, c57b7c70-6ce5-4a32-b959-88ef5ccc2029, ghrun-kipqlopw2q.auto.internal:17720): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ODJjZmU1ZGYtNDkxZjMxMzAtMzA5OTBhOTItZTMxNDFkZjQ=" node_id: 1 }
2025-11-10 10:02:44,926 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 0f16dea3-8166-465e-bd5b-9b4ad059de67, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,927 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 0f16dea3-8166-465e-bd5b-9b4ad059de67, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=ODJjZmU1ZGYtNDkxZjMxMzAtMzA5OTBhOTItZTMxNDFkZjQ=" }
2025-11-10 10:02:44,928 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 0f16dea3-8166-465e-bd5b-9b4ad059de67, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:44,934 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ODJjZmU1ZGYtNDkxZjMxMzAtMzA5OTBhOTItZTMxNDFkZjQ=
2025-11-10 10:02:44,935 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 503df87b-67d0-4e72-b6cf-bf56cf4099b3, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,936 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 5 < 100, will create new one.
2025-11-10 10:02:44,937 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 503df87b-67d0-4e72-b6cf-bf56cf4099b3, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=ODJjZmU1ZGYtNDkxZjMxMzAtMzA5OTBhOTItZTMxNDFkZjQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:02:44,938 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 90835b49-a08d-4978-8447-1153d7ed1316, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,939 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 90835b49-a08d-4978-8447-1153d7ed1316, ghrun-kipqlopw2q.auto.internal:17720): request = {  }
2025-11-10 10:02:44,939 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 503df87b-67d0-4e72-b6cf-bf56cf4099b3, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:44,942 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 90835b49-a08d-4978-8447-1153d7ed1316, ghrun-kipqlopw2q.auto.internal:17720): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NmYzMzVkNDMtODg5M2RjZjItMzJjN2Q5ZWItNGRhZGI0OGU=" node_id: 1 }
2025-11-10 10:02:44,943 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, e000bf0e-7264-4cbf-9a7e-f459357afcd0, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,943 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, e000bf0e-7264-4cbf-9a7e-f459357afcd0, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=NmYzMzVkNDMtODg5M2RjZjItMzJjN2Q5ZWItNGRhZGI0OGU=" }
2025-11-10 10:02:44,944 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, e000bf0e-7264-4cbf-9a7e-f459357afcd0, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:44,946 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NmYzMzVkNDMtODg5M2RjZjItMzJjN2Q5ZWItNGRhZGI0OGU=
2025-11-10 10:02:44,947 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e85cfcd6-ad1f-470d-98d2-c8183d978835, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,947 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 6 < 100, will create new one.
2025-11-10 10:02:44,948 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e85cfcd6-ad1f-470d-98d2-c8183d978835, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=NmYzMzVkNDMtODg5M2RjZjItMzJjN2Q5ZWItNGRhZGI0OGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:02:44,948 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 8ad7f794-d16a-4992-ae9c-73dbc3da9b6d, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,949 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 8ad7f794-d16a-4992-ae9c-73dbc3da9b6d, ghrun-kipqlopw2q.auto.internal:17720): request = {  }
2025-11-10 10:02:44,950 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e85cfcd6-ad1f-470d-98d2-c8183d978835, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:44,953 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 8ad7f794-d16a-4992-ae9c-73dbc3da9b6d, ghrun-kipqlopw2q.auto.internal:17720): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZWQ3Mzk0MmMtODljZTIyNzktMmRlZDM4N2MtOGUwNGJiM2Q=" node_id: 1 }
2025-11-10 10:02:44,955 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 4e7b04e7-1b75-4b7a-946d-ca9a8cd46f31, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,956 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 4e7b04e7-1b75-4b7a-946d-ca9a8cd46f31, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=ZWQ3Mzk0MmMtODljZTIyNzktMmRlZDM4N2MtOGUwNGJiM2Q=" }
2025-11-10 10:02:44,958 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 4e7b04e7-1b75-4b7a-946d-ca9a8cd46f31, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:44,978 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZWQ3Mzk0MmMtODljZTIyNzktMmRlZDM4N2MtOGUwNGJiM2Q=
2025-11-10 10:02:44,978 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 07ddd904-a41d-4791-89a3-671be4eb8250, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,979 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 07ddd904-a41d-4791-89a3-671be4eb8250, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=ZWQ3Mzk0MmMtODljZTIyNzktMmRlZDM4N2MtOGUwNGJiM2Q=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:02:44,980 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 07ddd904-a41d-4791-89a3-671be4eb8250, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:44,980 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 7 < 100, will create new one.
2025-11-10 10:02:44,982 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 5196ff16-31e7-43cc-9fc7-c451bb575386, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:44,982 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 5196ff16-31e7-43cc-9fc7-c451bb575386, ghrun-kipqlopw2q.auto.internal:17720): request = {  }
2025-11-10 10:02:45,041 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 5196ff16-31e7-43cc-9fc7-c451bb575386, ghrun-kipqlopw2q.auto.internal:17720): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NDJjZTYyN2EtZDEzZTQ1Zi1iYTUyZTQzMy1iNmI4NGViZg==" node_id: 1 }
2025-11-10 10:02:45,047 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 76ccf10d-9f67-475c-b06b-c647f8375029, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:45,048 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 76ccf10d-9f67-475c-b06b-c647f8375029, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=NDJjZTYyN2EtZDEzZTQ1Zi1iYTUyZTQzMy1iNmI4NGViZg==" }
2025-11-10 10:02:45,049 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 76ccf10d-9f67-475c-b06b-c647f8375029, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:45,057 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NDJjZTYyN2EtZDEzZTQ1Zi1iYTUyZTQzMy1iNmI4NGViZg==
2025-11-10 10:02:45,063 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 8 < 100, will create new one.
2025-11-10 10:02:45,063 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2b2599e9-0b8a-4b43-b916-6d23a82324a9, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:45,064 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, d4884bce-3a61-474f-aad4-8a7b5aca720e, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:45,065 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2b2599e9-0b8a-4b43-b916-6d23a82324a9, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=NDJjZTYyN2EtZDEzZTQ1Zi1iYTUyZTQzMy1iNmI4NGViZg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:02:45,065 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, d4884bce-3a61-474f-aad4-8a7b5aca720e, ghrun-kipqlopw2q.auto.internal:17720): request = {  }
2025-11-10 10:02:45,067 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2b2599e9-0b8a-4b43-b916-6d23a82324a9, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:45,080 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, d4884bce-3a61-474f-aad4-8a7b5aca720e, ghrun-kipqlopw2q.auto.internal:17720): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NmQ5ODcwMzMtY2M2MjViMzMtYzEyMTRhNDMtOTE2NzcxNjg=" node_id: 1 }
2025-11-10 10:02:45,080 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 4a77645e-ec7e-4a57-86d9-1c68b29e486b, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:45,081 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 4a77645e-ec7e-4a57-86d9-1c68b29e486b, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=NmQ5ODcwMzMtY2M2MjViMzMtYzEyMTRhNDMtOTE2NzcxNjg=" }
2025-11-10 10:02:45,082 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 4a77645e-ec7e-4a57-86d9-1c68b29e486b, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:45,106 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NmQ5ODcwMzMtY2M2MjViMzMtYzEyMTRhNDMtOTE2NzcxNjg=
2025-11-10 10:02:45,106 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 72afa297-460a-4791-876d-97de0af4a29d, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:45,107 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 72afa297-460a-4791-876d-97de0af4a29d, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=NmQ5ODcwMzMtY2M2MjViMzMtYzEyMTRhNDMtOTE2NzcxNjg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:02:45,107 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 9 < 100, will create new one.
2025-11-10 10:02:45,109 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 72afa297-460a-4791-876d-97de0af4a29d, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:45,110 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 7ff06546-ec41-4cb1-894b-cb5dad4f5802, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:45,111 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 7ff06546-ec41-4cb1-894b-cb5dad4f5802, ghrun-kipqlopw2q.auto.internal:17720): request = {  }
2025-11-10 10:02:45,124 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 7ff06546-ec41-4cb1-894b-cb5dad4f5802, ghrun-kipqlopw2q.auto.internal:17720): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NzdkNzc0MTAtYjFjOTFlZTgtYzQ3ZjcyNjYtYjkzM2RiYg==" node_id: 1 }
2025-11-10 10:02:45,124 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, aefb4c40-cbcf-4e83-a2b6-cd7fe5f1f3f6, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:45,125 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, aefb4c40-cbcf-4e83-a2b6-cd7fe5f1f3f6, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=NzdkNzc0MTAtYjFjOTFlZTgtYzQ3ZjcyNjYtYjkzM2RiYg==" }
2025-11-10 10:02:45,126 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, aefb4c40-cbcf-4e83-a2b6-cd7fe5f1f3f6, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:45,140 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NzdkNzc0MTAtYjFjOTFlZTgtYzQ3ZjcyNjYtYjkzM2RiYg==
2025-11-10 10:02:45,141 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1981716e-e703-4b3a-91ff-38deee253eca, ghrun-kipqlopw2q.auto.internal:17720): creating call state
2025-11-10 10:02:45,142 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1981716e-e703-4b3a-91ff-38deee253eca, ghrun-kipqlopw2q.auto.internal:17720): request = { session_id: "ydb://session/3?node_id=1&id=NzdkNzc0MTAtYjFjOTFlZTgtYzQ3ZjcyNjYtYjkzM2RiYg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-10 10:02:45,143 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1981716e-e703-4b3a-91ff-38deee253eca, ghrun-kipqlopw2q.auto.internal:17720): response = { <_MultiThreadedRendezvous object> }
2025-11-10 10:02:51,022 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 54e014f6-f5d9-4522-b308-af4381d6edd6, ghrun-kipqlopw2q.auto.internal:17720): 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:17720 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-10T10:02:51.015434925+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-10 10:02:51,024 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,025 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, aeeb9237-5bff-4baa-9c23-434e1e88dff9, ghrun-kipqlopw2q.auto.internal:17720): 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:17720 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T10:02:51.015214228+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 10:02:51,026 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,026 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 63367d0d-0717-470a-be5b-f7df3b4d3805, ghrun-kipqlopw2q.auto.internal:17720): 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:17720 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-10T10:02:51.015468995+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-10 10:02:51,027 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 0b5e0b52-55d3-49f7-8531-498e9a4c7111, ghrun-kipqlopw2q.auto.internal:17720): 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:17720 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T10:02:51.015507403+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 10:02:51,029 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,030 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 4e7b04e7-1b75-4b7a-946d-ca9a8cd46f31, ghrun-kipqlopw2q.auto.internal:17720): 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:17720 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T10:02:51.015609474+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 10:02:51,030 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 0f16dea3-8166-465e-bd5b-9b4ad059de67, ghrun-kipqlopw2q.auto.internal:17720): 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:17720 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T10:02:51.01553989+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 10:02:51,031 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,032 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, e000bf0e-7264-4cbf-9a7e-f459357afcd0, ghrun-kipqlopw2q.auto.internal:17720): 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:17720 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-10T10:02:51.015580329+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-10 10:02:51,035 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,035 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,036 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 76ccf10d-9f67-475c-b06b-c647f8375029, ghrun-kipqlopw2q.auto.internal:17720): 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:17720 {grpc_message:"recvmsg:Connection reset by peer", grpc_status:14, created_time:"2025-11-10T10:02:51.01564266+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-10 10:02:51,049 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,046 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,046 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, aefb4c40-cbcf-4e83-a2b6-cd7fe5f1f3f6, ghrun-kipqlopw2q.auto.internal:17720): 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:17720 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T10:02:51.015722801+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 10:02:51,047 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 52a0f8da-c5ff-4a27-88fb-4e0a43c449f9, ghrun-kipqlopw2q.auto.internal:17720): 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-10T10:02:51.015405886+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 10:02:51,050 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, a2bd03d4-d148-40ee-b75a-195da244ca95, ghrun-kipqlopw2q.auto.internal:17720): 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-10T10:02:51.015493906+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-10 10:02:51,043 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(AttachSession, 4a77645e-ec7e-4a57-86d9-1c68b29e486b, ghrun-kipqlopw2q.auto.internal:17720): 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:17720 {file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc", file_line:1211, created_time:"2025-11-10T10:02:51.015683496+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 10:02:51,050 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 503df87b-67d0-4e72-b6cf-bf56cf4099b3, ghrun-kipqlopw2q.auto.internal:17720): 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-10T10:02:51.015563704+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 10:02:51,050 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 50d68972-cc16-46c7-a676-c6db0f1c7e30, ghrun-kipqlopw2q.auto.internal:17720): 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-10T10:02:51.015526954+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-10 10:02:51,048 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 7f64a88c-64e3-4759-85fa-b3cc5f028ef2, ghrun-kipqlopw2q.auto.internal:17720): 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-10T10:02:51.015455925+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 10:02:51,051 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, e85cfcd6-ad1f-470d-98d2-c8183d978835, ghrun-kipqlopw2q.auto.internal:17720): 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-10T10:02:51.015595679+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 10:02:51,086 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,053 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 07ddd904-a41d-4791-89a3-671be4eb8250, ghrun-kipqlopw2q.auto.internal:17720): 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-10T10:02:51.015624595+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 10:02:51,054 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 72afa297-460a-4791-876d-97de0af4a29d, ghrun-kipqlopw2q.auto.internal:17720): 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-10T10:02:51.01570971+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-10 10:02:51,055 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 1981716e-e703-4b3a-91ff-38deee253eca, ghrun-kipqlopw2q.auto.internal:17720): 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-10T10:02:51.015749459+00:00", grpc_status:14, grpc_message:"recvmsg:Connection reset by peer"}"
>
2025-11-10 10:02:51,056 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:02:51,067 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,081 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,083 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,101 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,084 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,085 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,085 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,052 - DEBUG - ydb.connection - _rpc_error_handler: RpcState(ExecuteQuery, 2b2599e9-0b8a-4b43-b916-6d23a82324a9, ghrun-kipqlopw2q.auto.internal:17720): 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-10T10:02:51.015667354+00:00", file_line:1211, file:"/home/runner/actions_runner/_work/ydb/ydb/contrib/libs/grpc/src/core/lib/surface/call.cc"}"
>
2025-11-10 10:02:51,093 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 10:02:51,095 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,096 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,137 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NmQ5ODcwMzMtY2M2MjViMzMtYzEyMTRhNDMtOTE2NzcxNjg=
2025-11-10 10:02:51,111 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 10:02:51,103 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 10:02:51,109 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 10:02:51,102 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:02:51,114 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 10:02:51,115 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 10:02:51,120 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 10:02:51,131 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,133 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 10:02:51,084 - DEBUG - ydb.connection - close: Closing channel for endpoint ghrun-kipqlopw2q.auto.internal:17720
2025-11-10 10:02:51,142 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 10:02:51,148 - DEBUG - ydb.query.pool - release: Session returned to queue: None
2025-11-10 10:02:51,151 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 9 < 100, will create new one.
2025-11-10 10:02:51,153 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 10:02:51,154 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 8 < 100, will create new one.
2025-11-10 10:02:51,154 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 10:02:51,155 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 7 < 100, will create new one.
2025-11-10 10:02:51,161 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 10:02:51,165 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 6 < 100, will create new one.
2025-11-10 10:02:51,165 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 10:02:51,166 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 5 < 100, will create new one.
2025-11-10 10:02:51,166 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 10:02:51,166 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 4 < 100, will create new one.
2025-11-10 10:02:51,167 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 10:02:51,169 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 3 < 100, will create new one.
2025-11-10 10:02:51,169 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 10:02:51,170 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 2 < 100, will create new one.
2025-11-10 10:02:51,171 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 10:02:51,171 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-11-10 10:02:51,172 - DEBUG - ydb.query.pool - acquire: Acquired dead session from queue: None
2025-11-10 10:02:51,172 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:52,184 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:52,217 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:52,276 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:52,292 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:52,374 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:52,390 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:52,489 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:52,556 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:52,925 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:53,161 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:54,424 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:54,636 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:54,758 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:54,827 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:54,909 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:55,021 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:55,199 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:55,704 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:56,234 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:56,637 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:58,645 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:02:59,513 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:00,121 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:00,377 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:01,146 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:03:01,160 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:03:01,438 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:01,443 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:03:01,443 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:03:02,172 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:02,333 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:02,465 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:02,786 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:02,987 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:08,757 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:09,570 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:11,143 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:11,448 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:03:11,453 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:03:11,791 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:11,798 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:03:11,798 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:03:12,200 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:13,493 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:13,907 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:15,137 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-10 10:03:15,137 - DEBUG - ya.test - _save_outputs: Command (pid 1938917) rc: 0
2025-11-10 10:03:15,138 - DEBUG - ya.test - _save_outputs: Command (pid 1938917) elapsed time (sec): 30.247040271759033
2025-11-10 10:03:15,138 - DEBUG - ya.test - _save_outputs: Command (pid 1938917) wtime: 30.249
2025-11-10 10:03:15,138 - DEBUG - ya.test - _save_outputs: Command (pid 1938917) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	     12	12	0	0	265	695	695	695
2	    110	110	0	0	85	827	879	883
3	    102	102	0	0	95	134	142	143
4	    118	118	0	0	83	115	128	159
5	    121	121	0	0	80	114	132	140
6	     33	33	0	11	71	89	91	91
7	      0	0	0	1285	0	0	0	0
8	      0	0	0	1231	0	0	0	0
9	      0	0	0	1500	0	0	0	0
10	      0	0	0	1412	0	0	0	0
11	      0	0	0	1165	0	0	0	0
12	      0	0	0	961	0	0	0	0
13	      0	0	0	1172	0	0	0	0
14	      0	0	0	1213	0	0	0	0
15	      0	0	0	1207	0	0	0	0
16	      0	0	0	1139	0	0	0	0
17	      0	0	0	1073	0	0	0	0
18	      0	0	0	1024	0	0	0	0
19	      0	0	0	1117	0	0	0	0
20	      0	0	0	906	0	0	0	0
21	      0	0	0	1055	0	0	0	0
22	      0	0	0	892	0	0	0	0
23	      0	0	0	1034	0	0	0	0
24	      0	0	0	1078	0	0	0	0
25	      0	0	0	672	0	0	0	0
26	      0	0	0	1156	0	0	0	0
27	      0	0	0	1086	0	0	0	0
28	      0	0	0	1138	0	0	0	0
29	      0	0	0	1364	0	0	0	0
30	      0	0	0	1296	0	0	0	0

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    496	16.5333	0	27187	85	149	827	883

2025-11-10 10:03:15,138 - DEBUG - ya.test - _save_outputs: Command (pid 1938917) errors:

2025-11-10 10:03:15,152 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-10 10:03:15,153 - DEBUG - ya.test - _save_outputs: Command (pid 1938919) rc: 0
2025-11-10 10:03:15,154 - DEBUG - ya.test - _save_outputs: Command (pid 1938919) elapsed time (sec): 30.257104635238647
2025-11-10 10:03:15,154 - DEBUG - ya.test - _save_outputs: Command (pid 1938919) wtime: 30.259
2025-11-10 10:03:15,154 - DEBUG - ya.test - _save_outputs: Command (pid 1938919) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	      0	0	0	10	0	0	0	0
3	      0	0	0	10	0	0	0	0
4	      0	0	0	10	0	0	0	0
5	      0	0	0	10	0	0	0	0
6	      0	0	0	44	0	0	0	0
7	      0	0	0	1221	0	0	0	0
8	      0	0	0	1370	0	0	0	0
9	      0	0	0	1603	0	0	0	0
10	      0	0	0	1458	0	0	0	0
11	      0	0	0	1209	0	0	0	0
12	      0	0	0	993	0	0	0	0
13	      0	0	0	1180	0	0	0	0
14	      0	0	0	1169	0	0	0	0
15	      0	0	0	1321	0	0	0	0
16	      0	0	0	1165	0	0	0	0
17	      0	0	0	1081	0	0	0	0
18	      0	0	0	981	0	0	0	0
19	      0	0	0	1175	0	0	0	0
20	      0	0	0	1081	0	0	0	0
21	      0	0	0	1072	0	0	0	0
22	      0	0	0	952	0	0	0	0
23	      0	0	0	989	0	0	0	0
24	      0	0	0	1132	0	0	0	0
25	      0	0	0	1083	0	0	0	0
26	      0	0	0	1087	0	0	0	0
27	      0	0	0	1101	0	0	0	0
28	      0	0	0	1077	0	0	0	0
29	      0	0	0	1375	0	0	0	0
30	      0	0	0	1334	0	0	0	0

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

2025-11-10 10:03:15,154 - DEBUG - ya.test - _save_outputs: Command (pid 1938919) errors:

2025-11-10 10:03:15,158 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-10 10:03:15,159 - DEBUG - ya.test - _save_outputs: Command (pid 1938918) rc: 0
2025-11-10 10:03:15,160 - DEBUG - ya.test - _save_outputs: Command (pid 1938918) elapsed time (sec): 30.263134241104126
2025-11-10 10:03:15,160 - DEBUG - ya.test - _save_outputs: Command (pid 1938918) wtime: 30.267
2025-11-10 10:03:15,160 - DEBUG - ya.test - _save_outputs: Command (pid 1938918) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      7	7	0	0	851	947	947	947
2	     68	68	0	2	127	429	491	1047
3	     73	73	0	0	139	174	196	199
4	     87	87	0	0	118	148	161	175
5	     85	85	0	0	115	161	167	174
6	     24	24	0	17	100	119	119	119
7	      0	0	0	1206	0	0	0	0
8	      0	0	0	1355	0	0	0	0
9	      0	0	0	1590	0	0	0	0
10	      0	0	0	1493	0	0	0	0
11	      0	0	0	1311	0	0	0	0
12	      0	0	0	1102	0	0	0	0
13	      0	0	0	1180	0	0	0	0
14	      0	0	0	1102	0	0	0	0
15	      0	0	0	1326	0	0	0	0
16	      0	0	0	985	0	0	0	0
17	      0	0	0	1234	0	0	0	0
18	      0	0	0	944	0	0	0	0
19	      0	0	0	1081	0	0	0	0
20	      0	0	0	1092	0	0	0	0
21	      0	0	0	1056	0	0	0	0
22	      0	0	0	277	0	0	0	0
23	      0	0	0	0	0	0	0	0
24	      0	0	0	0	0	0	0	0
25	      0	0	0	682	0	0	0	0
26	      0	0	0	1180	0	0	0	0
27	      0	0	0	1246	0	0	0	0
28	      0	0	0	1306	0	0	0	0
29	      0	0	0	1369	0	0	0	0
30	      0	0	0	1318	0	0	0	0

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    344	11.4667	0	25454	122	203	895	1047

2025-11-10 10:03:15,160 - DEBUG - ya.test - _save_outputs: Command (pid 1938918) errors:

2025-11-10 10:03:15,575 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:16,879 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:17,127 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:21,802 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:03:21,998 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:03:23,926 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:03:23,942 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:03:26,114 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:33,192 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:33,945 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:03:34,093 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:03:35,628 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:03:35,629 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:03:37,095 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:38,239 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:38,968 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:39,321 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:40,445 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:40,948 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:43,718 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:45,358 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:03:45,637 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:03:45,644 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:03:46,675 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:03:46,686 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:03:56,689 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:03:56,873 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:03:58,256 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:03:58,257 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:04:08,265 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:04:08,435 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:04:10,033 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:04:10,034 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:04:16,963 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:04:17,006 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:04:20,039 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:04:20,050 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:04:21,416 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:04:21,417 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:04:25,492 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:04:26,837 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:04:27,651 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:04:31,424 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:04:31,469 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:04:32,754 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:04:32,757 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:04:32,758 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:04:33,793 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:04:34,608 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:04:38,940 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:04:42,762 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:04:42,951 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:04:44,369 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:04:44,373 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:04:46,530 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:04:51,768 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:04:54,378 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:04:54,565 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:04:56,080 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:04:56,082 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:04:56,082 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:05:04,011 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:05:06,086 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:05:06,091 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:05:07,445 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:05:07,446 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:05:12,896 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:05:13,136 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:05:17,452 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:05:17,469 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:05:18,552 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:05:18,553 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:05:18,555 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:05:22,920 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:05:24,610 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:05:28,561 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:05:28,597 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:05:29,852 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:05:29,854 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:05:29,854 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:05:37,950 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:05:39,860 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:05:39,873 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:05:41,476 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:05:41,477 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:05:42,271 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:05:45,676 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:05:51,480 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:05:51,531 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:05:52,728 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:05:52,729 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:05:59,548 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:06:02,742 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:06:02,796 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:06:04,412 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:06:04,413 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:06:04,992 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:06:06,384 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:06:07,215 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:06:09,136 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:06:10,049 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:06:14,421 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:06:14,615 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:06:16,558 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:06:16,560 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:06:16,578 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:06:18,072 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:06:24,521 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:06:26,582 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:06:26,781 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:06:28,140 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:06:28,141 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:06:31,901 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:06:38,145 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:06:38,318 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:06:39,525 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:06:39,526 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:06:41,359 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:06:48,340 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:06:48,915 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:06:49,530 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:06:49,532 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:06:50,919 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:06:50,977 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:06:56,906 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:06:57,675 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:06:57,836 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:06:59,533 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:07:01,002 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:07:01,189 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:07:02,887 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:07:02,887 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:07:07,050 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-10 10:07:12,891 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:07:13,052 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:07:14,828 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-10 10:07:14,829 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-10 10:07:15,821 - ERROR - ya.test - logreport: ydb/tests/olap/test_log_scenario.py:235: in test_log_uniform
    thread.join()
ydb/tests/olap/common/thread_helper.py:18: in join
    raise self.exc
ydb/tests/olap/common/thread_helper.py:11: in run
    self.ret = self._target(*self._args, **self._kwargs)
ydb/tests/olap/test_log_scenario.py:147: in aggregation_query
    self.ydb_client.query(f"SELECT COUNT(*) FROM `{self.table_name}` ")
ydb/tests/olap/common/ydb_client.py:24: in query
    return self.session_pool.execute_with_retries(query=statement, settings=request_settings)
contrib/python/ydb/py3/ydb/query/pool.py:252: in execute_with_retries
    return retry_operation_sync(wrapped_callee, retry_settings)
contrib/python/ydb/py3/ydb/retries.py:135: in retry_operation_sync
    for next_opt in opt_generator:
contrib/python/ydb/py3/ydb/retries.py:130: in retry_operation_impl
    raise status
contrib/python/ydb/py3/ydb/retries.py:96: in retry_operation_impl
    result = YdbRetryOperationFinalResult(callee(*args, **kwargs))
contrib/python/ydb/py3/ydb/query/pool.py:248: in wrapped_callee
    with self.checkout(timeout=retry_settings.max_session_acquire_timeout) as session:
contrib/python/ydb/py3/ydb/query/pool.py:331: in __enter__
    self._session = self._pool.acquire(self._timeout)
contrib/python/ydb/py3/ydb/query/pool.py:111: in acquire
    session = self._create_new_session(time_left)
contrib/python/ydb/py3/ydb/query/pool.py:63: in _create_new_session
    session.create(settings=BaseRequestSettings().with_timeout(timeout))
contrib/python/ydb/py3/ydb/query/session.py:301: in create
    self._create_call(settings=settings)
contrib/python/ydb/py3/ydb/query/session.py:171: in _create_call
    return self._driver(
contrib/python/ydb/py3/ydb/tracing.py:70: in wrapper
    return f(self, *args, **kwargs)
contrib/python/ydb/py3/ydb/pool.py:459: in __call__
    connection = self._store.get(preferred_endpoint)
contrib/python/ydb/py3/ydb/tracing.py:70: in wrapper
    return f(self, *args, **kwargs)
contrib/python/ydb/py3/ydb/pool.py:144: in get
    raise issues.ConnectionLost("Couldn't find valid connection")
E   ydb.issues.ConnectionLost: Couldn't find valid connection
2025-11-10 10:07:15,825 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-10 10:07:15,826 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-10 10:07:15,829 - DEBUG - ydb.topic - close: Close topic client
2025-11-10 10:07:15,829 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-10 10:07:15,829 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:07:24,840 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:17720
2025-11-10 10:07:25,034 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Failed to establish connection to YDB discovery endpoint: "localhost:17720". Check endpoint correctness.
2025-11-10 10:07:25,034 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-10 10:07:25,037 - DEBUG - library.python.cores - recover_core_dump_file: hostname = 'ghrun-kipqlopw2q'
2025-11-10 10:07:25,049 - DEBUG - library.python.cores - recover_core_dump_file: rlimit_core = '(0, -1)'
2025-11-10 10:07:25,049 - DEBUG - library.python.cores - recover_core_dump_file: core_pattern = '|/usr/share/apport/apport -p%p -s%s -c%c -d%d -P%P -u%u -g%g -F%F -- %E'
2025-11-10 10:07:25,051 - DEBUG - library.python.cores - recover_core_dump_file: core_uses_pid = '1'
2025-11-10 10:07:25,051 - DEBUG - library.python.cores - recover_core_dump_file: Core dump dir (/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1) permission mask: 0o40755 (expected: 0o41777 (0o40000-dir, 0o1000-sticky bit))
2025-11-10 10:07:25,052 - DEBUG - library.python.cores - recover_core_dump_file: Search for core dump files match pattern 'core.1908930' in '/home/runner/.ya/build/build_root/6t6o/0024d4/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1'
2025-11-10 10:07:25,052 - DEBUG - library.python.cores - recover_core_dump_file: Matched core dump files (0/3): [] (mismatched samples: stderr, stdout, logfile__855djqn.log)
2025-11-10 10:07:25,052 - WARNING - library.python.cores - recover_core_dump_file: Core dump dir doesn't exist: /coredumps
2025-11-10 10:07:25,053 - WARNING - library.python.cores - recover_core_dump_file: Core dump dir doesn't exist: /var/tmp/cores
2025-11-10 10:07:25,054 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:17720/1
2025-11-10 10:07:25,055 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63103
2025-11-10 10:07:25,055 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/31337
2025-11-10 10:07:25,055 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27843
2025-11-10 10:07:25,056 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17720
2025-11-10 10:07:25,056 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4475
2025-11-10 10:07:25,164 - 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/6t6o/0024d4/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/stdout
E   Stderr file name: 
E   /home/runner/.ya/build/build_root/6t6o/0024d4/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk4/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/stderr
E   Stderr content:
E   
E   GRpc memory quota was set but disabled due to issues with grpc quoter, to enable it use EnableGRpcMemoryQuota option
E   VERIFY failed (2025-11-10T10:02:45.467130Z): source_id=111;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 @ 0xA785E25
E   1. /-S/util/system/yassert.cpp:55: Panic @ 0xA77E366
E   2. /tmp//-S/ydb/library/actors/core/log.cpp:857: ~TVerifyFormattedRecordWriter @ 0xB60CC78
E   3. /-S/ydb/library/formats/arrow/switch/compare.h:82: TypedCompare<false> @ 0xD9052EB
E   4. /-S/ydb/core/formats/arrow/reader/position.h:83: CompareImpl @ 0x9DDB218
E   5. /-S/ydb/core/formats/arrow/reader/position.h:471: Compare @ 0x9DDAF6F
E   6. /tmp//-S/ydb/core/formats/arrow/reader/position.cpp:36: FindBound @ 0xD8E08FD
E   7. /tmp//-S/ydb/core/tx/columnshard/engines/predicate/filter.cpp:21: BuildFilter @ 0x166CBD2B
E   8. /tmp//-S/ydb/core/tx/columnshard/engines/reader/simple_reader/iterator/fetching.cpp:16: DoExecuteInplace @ 0x16922BF7
E   9. /-S/ydb/core/tx/columnshard/engines/reader/common_reader/common/script.h:47: ExecuteInplace @ 0x1687A423
E   10. /tmp//-S/ydb/core/tx/columnshard/engines/reader/common_reader/common/script_cursor.cpp:36: Execute @ 0x1687A423
E   11. /tmp//-S/ydb/core/tx/columnshard/engines/reader/common_reader/iterator/fetching.cpp:30: DoExecuteImpl @ 0x16877243
E   12. /tmp//-S/ydb/core/tx/columnshard/engines/reader/common/conveyor_task.cpp:8: DoExecute @ 0x1687DCF7
E   13. /tmp//-S/ydb/core/tx/conveyor/usage/abstract.cpp:16: Execute @ 0x166E9598
E   14. /tmp//-S/ydb/core/tx/conveyor_composite/service/worker.cpp:17: ExecuteTask @ 0x1CB3CB55
E   15. /tmp//-S/ydb/library/actors/core/actor.cpp:350: Receive @ 0xB5B7ED7
E   16. /tmp//-S/ydb/library/actors/core/executor_thread.cpp:267: Execute @ 0xB5FD724
E   17. /tmp//-S/ydb/library/actors/core/executor_thread.cpp:455: operator() @ 0xB6017E4
E   18. /tmp//-S/ydb/library/actors/core/executor_thread.cpp:507: ProcessExecutorPool @ 0xB6013F0
E   19. /tmp//-S/ydb/library/actors/core/executor_thread.cpp:533: ThreadProc @ 0xB601FDE
E   20. /-S/util/system/thread.cpp:245: ThreadProxy @ 0xA787A7C
E   21. ??:0: ?? @ 0x7FD00735FAC2
E   22. ??:0: ?? @ 0x7FD0073F18BF
