2025-11-14 21:13:42,539 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 21:13:42,539 - INFO - ya.test - pytest_runtest_setup: test_overloads_workload[2-2]
2025-11-14 21:13:42,539 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 21:13:42,539 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-14 21:13:42,540 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_overloads.py::TestLogScenario, test_name: test_overloads_workload[2-2])
2025-11-14 21:13:42,541 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.7
2025-11-14 21:13:42,542 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.7
2025-11-14 21:13:42,542 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-14 21:13:42,551 - DEBUG - ya.test - execute: Command pid: 3756466
2025-11-14 21:13:43,016 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) rc: 0
2025-11-14 21:13:43,017 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) elapsed time (sec): 0.4621546268463135
2025-11-14 21:13:43,017 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) idrss: 0
2025-11-14 21:13:43,017 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) inblock: 0
2025-11-14 21:13:43,017 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) isrss: 0
2025-11-14 21:13:43,017 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) ixrss: 0
2025-11-14 21:13:43,017 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) majflt: 0
2025-11-14 21:13:43,018 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) maxrss: 543356
2025-11-14 21:13:43,018 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) minflt: 16222
2025-11-14 21:13:43,018 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) msgrcv: 0
2025-11-14 21:13:43,018 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) msgsnd: 0
2025-11-14 21:13:43,020 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) nivcsw: 53
2025-11-14 21:13:43,021 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) nsignals: 0
2025-11-14 21:13:43,021 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) nswap: 0
2025-11-14 21:13:43,022 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) nvcsw: 30
2025-11-14 21:13:43,022 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) oublock: 8
2025-11-14 21:13:43,022 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) stime: 0.266951
2025-11-14 21:13:43,022 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) utime: 0.11762099999999999
2025-11-14 21:13:43,022 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) wtime: 0.471
2025-11-14 21:13:43,023 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) output:
Git info:
    Commit: 302fd6c34ff58466aa8562431b18a60cdd15dc3d
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 833456b412a419378277ccb14deba7ed4b030a1a into df494f8fe0ba6d8754262575ecf290bdfad6a0ce

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-alaktn3s7q
    Host information: 
        Linux ghrun-alaktn3s7q 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/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-14 21:13:43,023 - DEBUG - ya.test - _save_outputs: Command (pid 3756466) errors:

2025-11-14 21:13:43,023 - INFO - __tests__.test_overloads - _setup_ydb: Git info:
    Commit: 302fd6c34ff58466aa8562431b18a60cdd15dc3d
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 833456b412a419378277ccb14deba7ed4b030a1a into df494f8fe0ba6d8754262575ecf290bdfad6a0ce

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-alaktn3s7q
    Host information: 
        Linux ghrun-alaktn3s7q 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/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-14 21:13:43,097 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydbd/ydbd
2025-11-14 21:13:43,100 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/62670
2025-11-14 21:13:43,104 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/9c3s/0007dc/r3tmp/kikimr_cluster_835x6ww2
2025-11-14 21:13:43,136 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydbd/ydbd
2025-11-14 21:13:43,137 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/9411
2025-11-14 21:13:43,138 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/10268
2025-11-14 21:13:43,139 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3775
2025-11-14 21:13:43,140 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24613
2025-11-14 21:13:43,141 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/9c3s/0007dc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/test_overloads.py.TestLogScenario.test_overloads_workload.2-2/cluster/kikimr_configs"
2025-11-14 21:13:43,142 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/9c3s/0007dc/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/9c3s/0007dc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/test_overloads.py.TestLogScenario.test_overloads_workload.2-2/cluster/node_1/logfile_4_ps2g8h.log --grpc-port=9411 --mon-port=10268 --ic-port=62670
2025-11-14 21:13:43,143 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:9411/1, disk_size 68719476736
2025-11-14 21:13:43,143 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/9c3s/0007dc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/test_overloads.py.TestLogScenario.test_overloads_workload.2-2/cluster/kikimr_configs"
2025-11-14 21:13:43,145 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/9c3s/0007dc/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/9c3s/0007dc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/test_overloads.py.TestLogScenario.test_overloads_workload.2-2/cluster/node_1/logfile_4_ps2g8h.log --grpc-port=9411 --mon-port=10268 --ic-port=62670
2025-11-14 21:13:43,148 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/9c3s/0007dc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/test_overloads.py.TestLogScenario.test_overloads_workload.2-2/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/9c3s/0007dc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/test_overloads.py.TestLogScenario.test_overloads_workload.2-2/cluster/node_1/logfile_4_ps2g8h.log', '--grpc-port=9411', '--mon-port=10268', '--ic-port=62670')' in '/home/runner/.ya/build/build_root/9c3s/0007dc/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk9/testing_out_stuff/test_overloads.py.TestLogScenario.test_overloads_workload.2-2/cluster/node_1' (no wait)
2025-11-14 21:13:43,150 - DEBUG - ya.test - execute: Command pid: 3756819
2025-11-14 21:13:43,150 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:9411/1
2025-11-14 21:13:43,153 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.8
2025-11-14 21:13:43,153 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.8
2025-11-14 21:13:43,154 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-14 21:13:43,155 - DEBUG - ya.test - execute: Command pid: 3756822
2025-11-14 21:13:43,610 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) rc: 0
2025-11-14 21:13:43,611 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) elapsed time (sec): 0.4507417678833008
2025-11-14 21:13:43,611 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) idrss: 0
2025-11-14 21:13:43,611 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) inblock: 0
2025-11-14 21:13:43,613 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) isrss: 0
2025-11-14 21:13:43,613 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) ixrss: 0
2025-11-14 21:13:43,613 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) majflt: 0
2025-11-14 21:13:43,613 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) maxrss: 543356
2025-11-14 21:13:43,613 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) minflt: 16222
2025-11-14 21:13:43,614 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) msgrcv: 0
2025-11-14 21:13:43,614 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) msgsnd: 0
2025-11-14 21:13:43,614 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) nivcsw: 53
2025-11-14 21:13:43,615 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) nsignals: 0
2025-11-14 21:13:43,616 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) nswap: 0
2025-11-14 21:13:43,616 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) nvcsw: 13
2025-11-14 21:13:43,623 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) oublock: 8
2025-11-14 21:13:43,624 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) stime: 0.252831
2025-11-14 21:13:43,625 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) utime: 0.128273
2025-11-14 21:13:43,625 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) wtime: 0.452
2025-11-14 21:13:43,626 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) output:
Git info:
    Commit: 302fd6c34ff58466aa8562431b18a60cdd15dc3d
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 833456b412a419378277ccb14deba7ed4b030a1a into df494f8fe0ba6d8754262575ecf290bdfad6a0ce

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-alaktn3s7q
    Host information: 
        Linux ghrun-alaktn3s7q 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/9695656278/bin/clang++
    Compiler version: 
        clang version 20.1.2
        Target: x86_64-unknown-linux-gnu
        Thread model: posix
        InstalledDir: /home/runner/.ya/tools/v4/9695656278/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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-deprecated-literal-operator -Wno-unused-but-set-variable -Wno-unused-private-field -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-14 21:13:43,632 - DEBUG - ya.test - _save_outputs: Command (pid 3756822) errors:

2025-11-14 21:13:43,633 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 302fd6c34ff58466aa8562431b18a60cdd15dc3d
    Branch: 
    Author: Daniil Timižev <ditimizhev@ydb.tech>
    Summary: Merge 833456b412a419378277ccb14deba7ed4b030a1a into df494f8fe0ba6d8754262575ecf290bdfad6a0ce
2025-11-14 21:13:45,511 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydbd/ydbd
2025-11-14 21:13:45,515 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:9411', '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: 62670\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-14 21:13:45,532 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.9
2025-11-14 21:13:45,559 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.9
2025-11-14 21:13:45,568 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:9411', '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: 62670\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-14 21:13:45,573 - DEBUG - ya.test - execute: Command pid: 3758298
2025-11-14 21:13:46,168 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) rc: 0
2025-11-14 21:13:46,168 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) elapsed time (sec): 0.5887556076049805
2025-11-14 21:13:46,168 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) idrss: 0
2025-11-14 21:13:46,168 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) inblock: 0
2025-11-14 21:13:46,169 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) isrss: 0
2025-11-14 21:13:46,169 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) ixrss: 0
2025-11-14 21:13:46,169 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) majflt: 0
2025-11-14 21:13:46,169 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) maxrss: 551752
2025-11-14 21:13:46,169 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) minflt: 18363
2025-11-14 21:13:46,169 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) msgrcv: 0
2025-11-14 21:13:46,170 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) msgsnd: 0
2025-11-14 21:13:46,170 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) nivcsw: 91
2025-11-14 21:13:46,170 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) nsignals: 0
2025-11-14 21:13:46,170 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) nswap: 0
2025-11-14 21:13:46,171 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) nvcsw: 115
2025-11-14 21:13:46,171 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) oublock: 8
2025-11-14 21:13:46,171 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) stime: 0.277294
2025-11-14 21:13:46,171 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) utime: 0.138647
2025-11-14 21:13:46,171 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) wtime: 0.594
2025-11-14 21:13:46,171 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-14 21:13:46,172 - DEBUG - ya.test - _save_outputs: Command (pid 3758298) errors:

2025-11-14 21:13:46,172 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydbd/ydbd
2025-11-14 21:13:46,173 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:9411', '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-14 21:13:46,174 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.10
2025-11-14 21:13:46,174 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.10
2025-11-14 21:13:46,175 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:9411', '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-14 21:13:46,176 - DEBUG - ya.test - execute: Command pid: 3758626
2025-11-14 21:13:46,695 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) rc: 0
2025-11-14 21:13:46,696 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) elapsed time (sec): 0.5189719200134277
2025-11-14 21:13:46,696 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) idrss: 0
2025-11-14 21:13:46,696 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) inblock: 0
2025-11-14 21:13:46,696 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) isrss: 0
2025-11-14 21:13:46,697 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) ixrss: 0
2025-11-14 21:13:46,697 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) majflt: 0
2025-11-14 21:13:46,697 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) maxrss: 551744
2025-11-14 21:13:46,697 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) minflt: 18363
2025-11-14 21:13:46,697 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) msgrcv: 0
2025-11-14 21:13:46,698 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) msgsnd: 0
2025-11-14 21:13:46,698 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) nivcsw: 84
2025-11-14 21:13:46,698 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) nsignals: 0
2025-11-14 21:13:46,698 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) nswap: 0
2025-11-14 21:13:46,699 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) nvcsw: 108
2025-11-14 21:13:46,699 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) oublock: 8
2025-11-14 21:13:46,699 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) stime: 0.257488
2025-11-14 21:13:46,699 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) utime: 0.145845
2025-11-14 21:13:46,700 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) wtime: 0.52
2025-11-14 21:13:46,700 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-14 21:13:46,700 - DEBUG - ya.test - _save_outputs: Command (pid 3758626) errors:

2025-11-14 21:13:46,700 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-14 21:13:46,732 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-14 21:13:46,779 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-14 21:13:46,781 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-14 21:13:46,788 - 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-14 21:13:46,822 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-14 21:13:46,823 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 1042ad63-a707-4231-9639-3bbfc9226027, localhost:9411): creating call state
2025-11-14 21:13:46,823 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 1042ad63-a707-4231-9639-3bbfc9226027, localhost:9411): request = { database: "/Root" }
2025-11-14 21:13:46,832 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 1042ad63-a707-4231-9639-3bbfc9226027, localhost:9411): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alaktn3s7q.auto.internal" port: 9411 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-14 21:13:46,839 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alaktn3s7q.auto.internal:9411, location 1, ssl: False>]>
2025-11-14 21:13:46,840 - 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-14 21:13:46,856 - INFO - ydb.connection - close: Closing channel for endpoint localhost:9411
2025-11-14 21:13:46,859 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydb/ydb
2025-11-14 21:13:46,859 - INFO - root - _call: YdbWorkloadOverload execute /home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydb/ydb -e grpc://localhost:9411 -d /Root workload log --path log_2_2 init --path log_2_2 --store column --ttl 1000 with wait = True
2025-11-14 21:13:46,860 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.5
2025-11-14 21:13:46,861 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:9411', '-d', '/Root', 'workload', 'log', '--path', 'log_2_2', 'init', '--path', 'log_2_2', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-11-14 21:13:46,866 - DEBUG - ya.test - execute: Command pid: 3758940
2025-11-14 21:13:47,430 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) rc: 0
2025-11-14 21:13:47,431 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) elapsed time (sec): 0.5564167499542236
2025-11-14 21:13:47,431 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) idrss: 0
2025-11-14 21:13:47,431 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) inblock: 0
2025-11-14 21:13:47,431 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) isrss: 0
2025-11-14 21:13:47,431 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) ixrss: 0
2025-11-14 21:13:47,432 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) majflt: 0
2025-11-14 21:13:47,432 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) maxrss: 177132
2025-11-14 21:13:47,432 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) minflt: 3500
2025-11-14 21:13:47,432 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) msgrcv: 0
2025-11-14 21:13:47,432 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) msgsnd: 0
2025-11-14 21:13:47,433 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) nivcsw: 27
2025-11-14 21:13:47,433 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) nsignals: 0
2025-11-14 21:13:47,433 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) nswap: 0
2025-11-14 21:13:47,433 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) nvcsw: 141
2025-11-14 21:13:47,433 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) oublock: 8
2025-11-14 21:13:47,434 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) stime: 0.020737
2025-11-14 21:13:47,434 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) utime: 0.053918
2025-11-14 21:13:47,435 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) wtime: 0.563
2025-11-14 21:13:47,435 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) output:
None
2025-11-14 21:13:47,435 - DEBUG - ya.test - _save_outputs: Command (pid 3758940) errors:

2025-11-14 21:13:47,436 - INFO - root - _insert_rows: YdbWorkloadOverload bulk_upsert
2025-11-14 21:13:47,437 - INFO - root - _call: YdbWorkloadOverload execute /home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydb/ydb -e grpc://localhost:9411 -d /Root workload log --path log_2_2 run bulk_upsert --seconds 60 --threads 10 --rows 10 --quiet with wait = True
2025-11-14 21:13:47,442 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.6
2025-11-14 21:13:47,443 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:9411', '-d', '/Root', 'workload', 'log', '--path', 'log_2_2', 'run', 'bulk_upsert', '--seconds', '60', '--threads', '10', '--rows', '10', '--quiet']' in 'None' (waiting)
2025-11-14 21:13:47,453 - DEBUG - ya.test - execute: Command pid: 3759301
2025-11-14 21:14:15,537 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-14 21:14:15,538 - 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-14 21:14:15,542 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-14 21:14:15,543 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, bc4b7b93-fa38-4724-8c51-3b085015eec3, localhost:29816): creating call state
2025-11-14 21:14:15,543 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, bc4b7b93-fa38-4724-8c51-3b085015eec3, localhost:29816): request = { database: "/Root" }
2025-11-14 21:14:15,546 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, bc4b7b93-fa38-4724-8c51-3b085015eec3, localhost:29816): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alaktn3s7q.auto.internal" port: 29816 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-14 21:14:15,547 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alaktn3s7q.auto.internal:29816, location 1, ssl: False>]>
2025-11-14 21:14:15,547 - INFO - ydb.connection - close: Closing channel for endpoint localhost:29816
2025-11-14 21:14:23,441 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-14 21:14:23,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-14 21:14:23,447 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-14 21:14:23,448 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 0808433c-9789-4aa1-8616-b358453d979d, localhost:31541): creating call state
2025-11-14 21:14:23,448 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 0808433c-9789-4aa1-8616-b358453d979d, localhost:31541): request = { database: "/Root" }
2025-11-14 21:14:23,452 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 0808433c-9789-4aa1-8616-b358453d979d, localhost:31541): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alaktn3s7q.auto.internal" port: 31541 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-14 21:14:23,453 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alaktn3s7q.auto.internal:31541, location 1, ssl: False>]>
2025-11-14 21:14:23,453 - INFO - ydb.connection - close: Closing channel for endpoint localhost:31541
2025-11-14 21:15:10,101 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-14 21:15:10,102 - DEBUG - ya.test - _save_outputs: Command (pid 3759301) rc: 0
2025-11-14 21:15:10,102 - DEBUG - ya.test - _save_outputs: Command (pid 3759301) elapsed time (sec): 82.64801931381226
2025-11-14 21:15:10,102 - DEBUG - ya.test - _save_outputs: Command (pid 3759301) wtime: 82.659
2025-11-14 21:15:10,103 - DEBUG - ya.test - _save_outputs: Command (pid 3759301) output:
None
2025-11-14 21:15:10,103 - DEBUG - ya.test - _save_outputs: Command (pid 3759301) errors:

2025-11-14 21:15:10,103 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-14 21:15:10,105 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 612bceca-c1e4-4266-810f-76d919b05503, ghrun-alaktn3s7q.auto.internal:9411): creating call state
2025-11-14 21:15:10,105 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 612bceca-c1e4-4266-810f-76d919b05503, ghrun-alaktn3s7q.auto.internal:9411): request = {  }
2025-11-14 21:15:10,108 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 612bceca-c1e4-4266-810f-76d919b05503, ghrun-alaktn3s7q.auto.internal:9411): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZWMwNmU3MS1iMDQxNzE0OS00NjBlNWQ2Yy0xMmI4Y2Y2NQ==" node_id: 1 }
2025-11-14 21:15:10,109 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 3ba3a93c-b7af-409a-8248-2c7d3f5fccfb, ghrun-alaktn3s7q.auto.internal:9411): creating call state
2025-11-14 21:15:10,109 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 3ba3a93c-b7af-409a-8248-2c7d3f5fccfb, ghrun-alaktn3s7q.auto.internal:9411): request = { session_id: "ydb://session/3?node_id=1&id=ZWMwNmU3MS1iMDQxNzE0OS00NjBlNWQ2Yy0xMmI4Y2Y2NQ==" }
2025-11-14 21:15:10,111 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 3ba3a93c-b7af-409a-8248-2c7d3f5fccfb, ghrun-alaktn3s7q.auto.internal:9411): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:15:10,113 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZWMwNmU3MS1iMDQxNzE0OS00NjBlNWQ2Yy0xMmI4Y2Y2NQ==
2025-11-14 21:15:10,113 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aec0e808-12b8-4e39-920b-56fdf9a60b70, ghrun-alaktn3s7q.auto.internal:9411): creating call state
2025-11-14 21:15:10,114 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aec0e808-12b8-4e39-920b-56fdf9a60b70, ghrun-alaktn3s7q.auto.internal:9411): request = { session_id: "ydb://session/3?node_id=1&id=ZWMwNmU3MS1iMDQxNzE0OS00NjBlNWQ2Yy0xMmI4Y2Y2NQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log_2_2`" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:15:10,115 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aec0e808-12b8-4e39-920b-56fdf9a60b70, ghrun-alaktn3s7q.auto.internal:9411): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:15:10,675 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWMwNmU3MS1iMDQxNzE0OS00NjBlNWQ2Yy0xMmI4Y2Y2NQ==
2025-11-14 21:15:10,675 - INFO - root - test_overloads_workload: Count rows after insert 11190
2025-11-14 21:15:10,676 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWMwNmU3MS1iMDQxNzE0OS00NjBlNWQ2Yy0xMmI4Y2Y2NQ==
2025-11-14 21:15:10,676 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 638af879-0c5a-42bb-837d-e3f79c3422e7, ghrun-alaktn3s7q.auto.internal:9411): creating call state
2025-11-14 21:15:10,676 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 638af879-0c5a-42bb-837d-e3f79c3422e7, ghrun-alaktn3s7q.auto.internal:9411): request = { session_id: "ydb://session/3?node_id=1&id=ZWMwNmU3MS1iMDQxNzE0OS00NjBlNWQ2Yy0xMmI4Y2Y2NQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log_2_2`" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:15:10,677 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 638af879-0c5a-42bb-837d-e3f79c3422e7, ghrun-alaktn3s7q.auto.internal:9411): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:15:11,003 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWMwNmU3MS1iMDQxNzE0OS00NjBlNWQ2Yy0xMmI4Y2Y2NQ==
2025-11-14 21:15:11,005 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-11-14 21:15:11,006 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.7
2025-11-14 21:15:11,006 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007dc/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:9411', '-d', '/Root', 'workload', 'log', '--path', 'log_2_2', 'clean']' in 'None' (waiting)
2025-11-14 21:15:11,007 - DEBUG - ya.test - execute: Command pid: 3808043
2025-11-14 21:15:11,172 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) rc: 0
2025-11-14 21:15:11,172 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) elapsed time (sec): 0.16277813911437988
2025-11-14 21:15:11,173 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) idrss: 0
2025-11-14 21:15:11,173 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) inblock: 0
2025-11-14 21:15:11,173 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) isrss: 0
2025-11-14 21:15:11,173 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) ixrss: 0
2025-11-14 21:15:11,173 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) majflt: 0
2025-11-14 21:15:11,173 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) maxrss: 177132
2025-11-14 21:15:11,174 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) minflt: 3488
2025-11-14 21:15:11,174 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) msgrcv: 0
2025-11-14 21:15:11,174 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) msgsnd: 0
2025-11-14 21:15:11,174 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) nivcsw: 6
2025-11-14 21:15:11,174 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) nsignals: 0
2025-11-14 21:15:11,174 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) nswap: 0
2025-11-14 21:15:11,175 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) nvcsw: 191
2025-11-14 21:15:11,175 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) oublock: 8
2025-11-14 21:15:11,175 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) stime: 0.021023999999999998
2025-11-14 21:15:11,175 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) utime: 0.050457999999999996
2025-11-14 21:15:11,175 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) wtime: 0.164
2025-11-14 21:15:11,175 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) output:
Remove path log_2_2...
Remove path log_2_2...Ok

2025-11-14 21:15:11,176 - DEBUG - ya.test - _save_outputs: Command (pid 3808043) errors:

2025-11-14 21:15:11,179 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-14 21:15:11,188 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, ac784d35-b4b0-4a75-9722-84f3623f687f, ghrun-alaktn3s7q.auto.internal:9411): creating call state
2025-11-14 21:15:11,194 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, ac784d35-b4b0-4a75-9722-84f3623f687f, ghrun-alaktn3s7q.auto.internal:9411): request = { session_id: "ydb://session/3?node_id=1&id=ZWMwNmU3MS1iMDQxNzE0OS00NjBlNWQ2Yy0xMmI4Y2Y2NQ==" }
2025-11-14 21:15:11,196 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, ac784d35-b4b0-4a75-9722-84f3623f687f, ghrun-alaktn3s7q.auto.internal:9411): response = { status: SUCCESS }
2025-11-14 21:15:11,197 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-14 21:15:11,198 - DEBUG - ydb.topic - close: Close topic client
2025-11-14 21:15:11,198 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-14 21:15:11,199 - INFO - ydb.connection - close: Closing channel for endpoint localhost:9411
2025-11-14 21:15:11,199 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-alaktn3s7q.auto.internal:9411
2025-11-14 21:15:11,200 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-14 21:15:12,201 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:9411/1
2025-11-14 21:15:12,208 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24613
2025-11-14 21:15:12,209 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3775
2025-11-14 21:15:12,209 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/10268
2025-11-14 21:15:12,209 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/9411
2025-11-14 21:15:12,209 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/62670
