2025-11-14 21:10:13,143 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 21:10:13,147 - INFO - ya.test - pytest_runtest_setup: test_log_deviation[1051200]
2025-11-14 21:10:13,170 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 21:10:13,171 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-14 21:10:13,172 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-14 21:10:13,173 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-14 21:10:13,173 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-14 21:10:13,174 - DEBUG - ya.test - execute: Command pid: 3633619
2025-11-14 21:10:14,523 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) rc: 0
2025-11-14 21:10:14,525 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) elapsed time (sec): 1.3428068161010742
2025-11-14 21:10:14,553 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) idrss: 0
2025-11-14 21:10:14,553 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) inblock: 0
2025-11-14 21:10:14,553 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) isrss: 0
2025-11-14 21:10:14,591 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) ixrss: 0
2025-11-14 21:10:14,595 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) majflt: 0
2025-11-14 21:10:14,596 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) maxrss: 543356
2025-11-14 21:10:14,602 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) minflt: 16226
2025-11-14 21:10:14,602 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) msgrcv: 0
2025-11-14 21:10:14,627 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) msgsnd: 0
2025-11-14 21:10:14,627 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) nivcsw: 240
2025-11-14 21:10:14,628 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) nsignals: 0
2025-11-14 21:10:14,628 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) nswap: 0
2025-11-14 21:10:14,628 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) nvcsw: 43
2025-11-14 21:10:14,628 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) oublock: 8
2025-11-14 21:10:14,628 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) stime: 0.900057
2025-11-14 21:10:14,629 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) utime: 0.1215
2025-11-14 21:10:14,629 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) wtime: 1.345
2025-11-14 21:10:14,629 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) 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:10:14,633 - DEBUG - ya.test - _save_outputs: Command (pid 3633619) errors:

2025-11-14 21:10:14,634 - INFO - __tests__.test_log_scenario - _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:10:14,733 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydbd/ydbd
2025-11-14 21:10:14,735 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3815
2025-11-14 21:10:14,784 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/9c3s/0007d8/r3tmp/kikimr_cluster_jqtr7dcx
2025-11-14 21:10:14,857 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydbd/ydbd
2025-11-14 21:10:14,865 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18008
2025-11-14 21:10:14,867 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/26166
2025-11-14 21:10:14,920 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19392
2025-11-14 21:10:14,921 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22655
2025-11-14 21:10:14,974 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/kikimr_configs"
2025-11-14 21:10:14,975 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/9c3s/0007d8/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/0007d8/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/node_1/logfile_0c8ca183.log --grpc-port=18008 --mon-port=26166 --ic-port=3815
2025-11-14 21:10:14,975 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:18008/1, disk_size 68719476736
2025-11-14 21:10:14,975 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/kikimr_configs"
2025-11-14 21:10:14,976 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/9c3s/0007d8/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/0007d8/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/node_1/logfile_0c8ca183.log --grpc-port=18008 --mon-port=26166 --ic-port=3815
2025-11-14 21:10:14,978 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/node_1/logfile_0c8ca183.log', '--grpc-port=18008', '--mon-port=26166', '--ic-port=3815')' in '/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/node_1' (no wait)
2025-11-14 21:10:15,014 - DEBUG - ya.test - execute: Command pid: 3634750
2025-11-14 21:10:15,111 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:18008/1
2025-11-14 21:10:15,166 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-14 21:10:15,166 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-14 21:10:15,169 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-14 21:10:15,171 - DEBUG - ya.test - execute: Command pid: 3634809
2025-11-14 21:10:17,601 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) rc: 0
2025-11-14 21:10:17,601 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) elapsed time (sec): 2.409660577774048
2025-11-14 21:10:17,601 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) idrss: 0
2025-11-14 21:10:17,602 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) inblock: 0
2025-11-14 21:10:17,602 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) isrss: 0
2025-11-14 21:10:17,608 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) ixrss: 0
2025-11-14 21:10:17,645 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) majflt: 1
2025-11-14 21:10:17,645 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) maxrss: 543356
2025-11-14 21:10:17,646 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) minflt: 16226
2025-11-14 21:10:17,666 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) msgrcv: 0
2025-11-14 21:10:17,667 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) msgsnd: 0
2025-11-14 21:10:17,667 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) nivcsw: 460
2025-11-14 21:10:17,667 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) nsignals: 0
2025-11-14 21:10:17,669 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) nswap: 0
2025-11-14 21:10:17,669 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) nvcsw: 50
2025-11-14 21:10:17,669 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) oublock: 8
2025-11-14 21:10:17,669 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) stime: 1.7708499999999998
2025-11-14 21:10:17,669 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) utime: 0.105512
2025-11-14 21:10:17,670 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) wtime: 2.427
2025-11-14 21:10:17,670 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) 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:10:17,670 - DEBUG - ya.test - _save_outputs: Command (pid 3634809) errors:

2025-11-14 21:10:17,671 - 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:10:26,678 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydbd/ydbd
2025-11-14 21:10:26,709 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18008', '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: 3815\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-14 21:10:26,711 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-14 21:10:26,712 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-14 21:10:26,712 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18008', '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: 3815\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-14 21:10:26,714 - DEBUG - ya.test - execute: Command pid: 3644786
2025-11-14 21:10:28,416 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) rc: 0
2025-11-14 21:10:28,416 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) elapsed time (sec): 1.6974315643310547
2025-11-14 21:10:28,417 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) idrss: 0
2025-11-14 21:10:28,417 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) inblock: 0
2025-11-14 21:10:28,417 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) isrss: 0
2025-11-14 21:10:28,417 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) ixrss: 0
2025-11-14 21:10:28,417 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) majflt: 0
2025-11-14 21:10:28,420 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) maxrss: 551704
2025-11-14 21:10:28,421 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) minflt: 18369
2025-11-14 21:10:28,421 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) msgrcv: 0
2025-11-14 21:10:28,467 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) msgsnd: 0
2025-11-14 21:10:28,467 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) nivcsw: 294
2025-11-14 21:10:28,474 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) nsignals: 0
2025-11-14 21:10:28,556 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) nswap: 0
2025-11-14 21:10:28,557 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) nvcsw: 124
2025-11-14 21:10:28,557 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) oublock: 8
2025-11-14 21:10:28,557 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) stime: 1.229934
2025-11-14 21:10:28,557 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) utime: 0.149807
2025-11-14 21:10:28,603 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) wtime: 1.699
2025-11-14 21:10:28,623 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-14 21:10:28,623 - DEBUG - ya.test - _save_outputs: Command (pid 3644786) errors:

2025-11-14 21:10:28,636 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydbd/ydbd
2025-11-14 21:10:28,637 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18008', '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:10:28,642 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-14 21:10:28,692 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-14 21:10:28,701 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18008', '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:10:28,725 - DEBUG - ya.test - execute: Command pid: 3646868
2025-11-14 21:10:30,356 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) rc: 0
2025-11-14 21:10:30,357 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) elapsed time (sec): 1.6211357116699219
2025-11-14 21:10:30,357 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) idrss: 0
2025-11-14 21:10:30,357 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) inblock: 0
2025-11-14 21:10:30,357 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) isrss: 0
2025-11-14 21:10:30,357 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) ixrss: 0
2025-11-14 21:10:30,358 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) majflt: 0
2025-11-14 21:10:30,358 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) maxrss: 551660
2025-11-14 21:10:30,358 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) minflt: 18519
2025-11-14 21:10:30,358 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) msgrcv: 0
2025-11-14 21:10:30,358 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) msgsnd: 0
2025-11-14 21:10:30,358 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) nivcsw: 266
2025-11-14 21:10:30,359 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) nsignals: 0
2025-11-14 21:10:30,359 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) nswap: 0
2025-11-14 21:10:30,359 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) nvcsw: 180
2025-11-14 21:10:30,359 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) oublock: 8
2025-11-14 21:10:30,359 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) stime: 1.210841
2025-11-14 21:10:30,360 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) utime: 0.108808
2025-11-14 21:10:30,360 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) wtime: 1.64
2025-11-14 21:10:30,360 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-14 21:10:30,360 - DEBUG - ya.test - _save_outputs: Command (pid 3646868) errors:

2025-11-14 21:10:30,360 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-14 21:10:30,388 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-14 21:10:30,402 - 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:10:30,405 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-14 21:10:30,410 - 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:10:30,432 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-14 21:10:30,433 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, ebe592db-de20-4a90-bc8e-7ed5b421a1cb, localhost:18008): creating call state
2025-11-14 21:10:30,433 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, ebe592db-de20-4a90-bc8e-7ed5b421a1cb, localhost:18008): request = { database: "/Root" }
2025-11-14 21:10:30,439 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, ebe592db-de20-4a90-bc8e-7ed5b421a1cb, localhost:18008): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alaktn3s7q.auto.internal" port: 18008 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:10:30,440 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alaktn3s7q.auto.internal:18008, location 1, ssl: False>]>
2025-11-14 21:10:30,440 - 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:10:30,472 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_deviation[1051200])
2025-11-14 21:10:30,485 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb
2025-11-14 21:10:30,485 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb -e grpc://localhost:18008 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-11-14 21:10:30,494 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-11-14 21:10:30,495 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-11-14 21:10:30,496 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-11-14 21:10:30,500 - DEBUG - ya.test - execute: Command pid: 3649050
2025-11-14 21:10:31,212 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) rc: 0
2025-11-14 21:10:31,221 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) elapsed time (sec): 0.6740436553955078
2025-11-14 21:10:31,222 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) idrss: 0
2025-11-14 21:10:31,244 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) inblock: 0
2025-11-14 21:10:31,254 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) isrss: 0
2025-11-14 21:10:31,255 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) ixrss: 0
2025-11-14 21:10:31,255 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) majflt: 0
2025-11-14 21:10:31,255 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) maxrss: 179180
2025-11-14 21:10:31,257 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) minflt: 3483
2025-11-14 21:10:31,262 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) msgrcv: 0
2025-11-14 21:10:31,262 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) msgsnd: 0
2025-11-14 21:10:31,262 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) nivcsw: 50
2025-11-14 21:10:31,271 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) nsignals: 0
2025-11-14 21:10:31,271 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) nswap: 0
2025-11-14 21:10:31,274 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) nvcsw: 163
2025-11-14 21:10:31,275 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) oublock: 8
2025-11-14 21:10:31,275 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) stime: 0.017454
2025-11-14 21:10:31,275 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) utime: 0.06509899999999999
2025-11-14 21:10:31,275 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) wtime: 0.677
2025-11-14 21:10:31,275 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) output:
Init tables ...
Init tables ...Ok

2025-11-14 21:10:31,275 - DEBUG - ya.test - _save_outputs: Command (pid 3649050) errors:

2025-11-14 21:10:31,276 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-14 21:10:31,282 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, befc6219-6561-48c0-a677-ff14b969c8a1, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:31,285 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, befc6219-6561-48c0-a677-ff14b969c8a1, ghrun-alaktn3s7q.auto.internal:18008): request = {  }
2025-11-14 21:10:31,293 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, befc6219-6561-48c0-a677-ff14b969c8a1, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" node_id: 1 }
2025-11-14 21:10:31,323 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 0d4c5087-042b-4206-a935-c2ac15de3fc0, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:31,324 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 0d4c5087-042b-4206-a935-c2ac15de3fc0, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" }
2025-11-14 21:10:31,326 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 0d4c5087-042b-4206-a935-c2ac15de3fc0, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:31,331 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:10:31,335 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e921f22d-e76f-43b6-bb8a-134319462a51, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:31,335 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e921f22d-e76f-43b6-bb8a-134319462a51, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "ALTER OBJECT `//Root/log` (TYPE TABLE) SET (ACTION=UPSERT_OPTIONS, `SCAN_READER_POLICY_NAME`=`SIMPLE`) " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:10:31,339 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e921f22d-e76f-43b6-bb8a-134319462a51, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:31,392 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:10:31,399 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-14 21:10:31,411 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb -e grpc://localhost:18008 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 1051200 with wait = True
2025-11-14 21:10:31,412 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-11-14 21:10:31,418 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-11-14 21:10:31,419 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '1051200']' in 'None' (waiting)
2025-11-14 21:10:31,429 - DEBUG - ya.test - execute: Command pid: 3650065
2025-11-14 21:10:35,778 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-14 21:10:35,810 - DEBUG - ya.test - _save_outputs: Command (pid 3650065) rc: 0
2025-11-14 21:10:35,810 - DEBUG - ya.test - _save_outputs: Command (pid 3650065) elapsed time (sec): 4.379925489425659
2025-11-14 21:10:35,812 - DEBUG - ya.test - _save_outputs: Command (pid 3650065) wtime: 4.387
2025-11-14 21:10:35,813 - DEBUG - ya.test - _save_outputs: Command (pid 3650065) 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	     10	10	0	0	1335	1639	1639	1639
3	     17	17	0	0	1375	1551	1575	1575

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	     27	9	0	0	1343	1575	1639	1639

2025-11-14 21:10:35,836 - DEBUG - ya.test - _save_outputs: Command (pid 3650065) errors:

2025-11-14 21:10:35,861 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:10:35,895 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 96e25130-6ec6-4ebb-add1-6f56fd7eff83, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:35,895 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 96e25130-6ec6-4ebb-add1-6f56fd7eff83, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" 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-14 21:10:35,896 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 96e25130-6ec6-4ebb-add1-6f56fd7eff83, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:37,920 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:10:37,923 - INFO - root - test_log_deviation: Count rows after insert 13500 before wait
2025-11-14 21:10:37,923 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:10:37,923 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0b632bfa-ac14-45e4-ac84-9834e80da18d, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:37,924 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0b632bfa-ac14-45e4-ac84-9834e80da18d, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" 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-14 21:10:37,925 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0b632bfa-ac14-45e4-ac84-9834e80da18d, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:39,675 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:10:39,679 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:10:39,693 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8d7ed3f1-4b4d-4823-844a-8695ac47ac68, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:39,694 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8d7ed3f1-4b4d-4823-844a-8695ac47ac68, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" 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-14 21:10:39,695 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8d7ed3f1-4b4d-4823-844a-8695ac47ac68, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:41,320 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:10:41,321 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-14 21:10:41,321 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-11-14 21:10:41,322 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb -e grpc://localhost:18008 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 1051200 with wait = True
2025-11-14 21:10:41,322 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-11-14 21:10:41,323 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb -e grpc://localhost:18008 -d /Root workload log --path log run insert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 1051200 with wait = True
2025-11-14 21:10:41,325 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-11-14 21:10:41,325 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb -e grpc://localhost:18008 -d /Root workload log --path log run upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 1051200 with wait = True
2025-11-14 21:10:41,326 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:10:41,329 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.2
2025-11-14 21:10:41,332 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 90900469-c348-40ec-9733-f9a064b71cf4, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:41,333 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 90900469-c348-40ec-9733-f9a064b71cf4, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:10:41,333 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-11-14 21:10:41,334 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-11-14 21:10:41,333 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.3
2025-11-14 21:10:41,335 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-11-14 21:10:41,340 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 90900469-c348-40ec-9733-f9a064b71cf4, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:41,344 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 960f5751-2a91-4b82-8276-4cb82186c4cf, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:41,346 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 960f5751-2a91-4b82-8276-4cb82186c4cf, ghrun-alaktn3s7q.auto.internal:18008): request = {  }
2025-11-14 21:10:41,347 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'insert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '1051200']' in 'None' (waiting)
2025-11-14 21:10:41,348 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 960f5751-2a91-4b82-8276-4cb82186c4cf, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==" node_id: 1 }
2025-11-14 21:10:41,394 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 7525ab7e-d891-44c3-8b08-0b3bee3cf5cd, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:41,394 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 7525ab7e-d891-44c3-8b08-0b3bee3cf5cd, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==" }
2025-11-14 21:10:41,351 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-11-14 21:10:41,351 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '1051200']' in 'None' (waiting)
2025-11-14 21:10:41,352 - DEBUG - ya.test - execute: Command pid: 3659940
2025-11-14 21:10:41,395 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 7525ab7e-d891-44c3-8b08-0b3bee3cf5cd, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:41,404 - DEBUG - ya.test - execute: Command pid: 3660047
2025-11-14 21:10:41,405 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '1051200']' in 'None' (waiting)
2025-11-14 21:10:41,410 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:10:41,435 - DEBUG - ya.test - execute: Command pid: 3660059
2025-11-14 21:10:41,439 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c76a0156-fd0c-44da-9bf7-12fa0a862590, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:41,441 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 2 < 100, will create new one.
2025-11-14 21:10:41,444 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c76a0156-fd0c-44da-9bf7-12fa0a862590, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:10:41,459 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c76a0156-fd0c-44da-9bf7-12fa0a862590, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:41,457 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, f178b8f3-9ddd-4120-b19e-716a157016b0, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:41,464 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, f178b8f3-9ddd-4120-b19e-716a157016b0, ghrun-alaktn3s7q.auto.internal:18008): request = {  }
2025-11-14 21:10:41,515 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, f178b8f3-9ddd-4120-b19e-716a157016b0, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=" node_id: 1 }
2025-11-14 21:10:41,515 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, da22d8ca-e2d4-4cc3-b367-71d2058727e8, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:41,516 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, da22d8ca-e2d4-4cc3-b367-71d2058727e8, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=" }
2025-11-14 21:10:41,517 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, da22d8ca-e2d4-4cc3-b367-71d2058727e8, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:41,561 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:10:41,575 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4ee94540-7125-4ce4-aef5-53eb4dbcf980, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:41,576 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4ee94540-7125-4ce4-aef5-53eb4dbcf980, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:10:41,576 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4ee94540-7125-4ce4-aef5-53eb4dbcf980, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:41,577 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 3 < 100, will create new one.
2025-11-14 21:10:41,623 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 9f8975e9-7a43-46d3-912b-dc73cc89570d, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:41,624 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 9f8975e9-7a43-46d3-912b-dc73cc89570d, ghrun-alaktn3s7q.auto.internal:18008): request = {  }
2025-11-14 21:10:41,658 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 9f8975e9-7a43-46d3-912b-dc73cc89570d, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=" node_id: 1 }
2025-11-14 21:10:41,671 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, b9c2b2dc-5a7f-4c24-836a-c50630c31fb6, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:41,678 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, b9c2b2dc-5a7f-4c24-836a-c50630c31fb6, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=" }
2025-11-14 21:10:41,708 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, b9c2b2dc-5a7f-4c24-836a-c50630c31fb6, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:41,759 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:10:41,760 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6f5c19ae-bd0b-4182-a121-91bfeae7fdb3, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:41,770 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6f5c19ae-bd0b-4182-a121-91bfeae7fdb3, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:10:41,785 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6f5c19ae-bd0b-4182-a121-91bfeae7fdb3, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:41,761 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 4 < 100, will create new one.
2025-11-14 21:10:41,797 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, c9de9668-695f-480b-85ae-5b1b1950324b, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:41,797 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, c9de9668-695f-480b-85ae-5b1b1950324b, ghrun-alaktn3s7q.auto.internal:18008): request = {  }
2025-11-14 21:10:41,891 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, c9de9668-695f-480b-85ae-5b1b1950324b, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==" node_id: 1 }
2025-11-14 21:10:41,892 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 0f8db9ea-e68c-46e7-bfa3-a7aa10383daa, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:41,893 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 0f8db9ea-e68c-46e7-bfa3-a7aa10383daa, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==" }
2025-11-14 21:10:41,904 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 0f8db9ea-e68c-46e7-bfa3-a7aa10383daa, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:42,019 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:10:42,023 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c28be97b-246a-4ce9-bb74-e0c9a519fee4, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:42,024 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c28be97b-246a-4ce9-bb74-e0c9a519fee4, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:10:42,024 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c28be97b-246a-4ce9-bb74-e0c9a519fee4, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:42,025 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 5 < 100, will create new one.
2025-11-14 21:10:42,029 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 8f036051-21ed-4b34-a9a7-ed77c901ff85, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:42,030 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 8f036051-21ed-4b34-a9a7-ed77c901ff85, ghrun-alaktn3s7q.auto.internal:18008): request = {  }
2025-11-14 21:10:42,509 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 8f036051-21ed-4b34-a9a7-ed77c901ff85, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" node_id: 1 }
2025-11-14 21:10:42,511 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 88351e1e-2a25-4419-9e0a-8cc35b6d8e11, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:42,511 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 88351e1e-2a25-4419-9e0a-8cc35b6d8e11, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" }
2025-11-14 21:10:42,512 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 88351e1e-2a25-4419-9e0a-8cc35b6d8e11, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:43,251 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:10:43,252 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dc3ff8b7-13eb-4958-880b-cbf8a183ced5, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:43,252 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dc3ff8b7-13eb-4958-880b-cbf8a183ced5, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:10:43,253 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dc3ff8b7-13eb-4958-880b-cbf8a183ced5, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:43,254 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 6 < 100, will create new one.
2025-11-14 21:10:43,254 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 3a143fe4-763d-4599-b966-c4736d475d7e, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:43,254 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 3a143fe4-763d-4599-b966-c4736d475d7e, ghrun-alaktn3s7q.auto.internal:18008): request = {  }
2025-11-14 21:10:43,488 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 3a143fe4-763d-4599-b966-c4736d475d7e, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" node_id: 1 }
2025-11-14 21:10:43,495 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, a40119f1-3dca-48c3-aa91-f7ae4ef74326, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:43,495 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, a40119f1-3dca-48c3-aa91-f7ae4ef74326, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" }
2025-11-14 21:10:43,496 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, a40119f1-3dca-48c3-aa91-f7ae4ef74326, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:43,836 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:10:43,843 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3764a3a6-4a59-4fcd-bef5-d76c2242fd6d, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:43,871 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3764a3a6-4a59-4fcd-bef5-d76c2242fd6d, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:10:43,872 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3764a3a6-4a59-4fcd-bef5-d76c2242fd6d, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:43,845 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 7 < 100, will create new one.
2025-11-14 21:10:43,873 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, b30a4e65-2d91-44fb-a63a-546d31a4819c, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:43,873 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, b30a4e65-2d91-44fb-a63a-546d31a4819c, ghrun-alaktn3s7q.auto.internal:18008): request = {  }
2025-11-14 21:10:44,232 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, b30a4e65-2d91-44fb-a63a-546d31a4819c, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=" node_id: 1 }
2025-11-14 21:10:44,235 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 2e2daa0f-a89f-481b-ae12-1f8f26537501, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:44,235 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 2e2daa0f-a89f-481b-ae12-1f8f26537501, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=" }
2025-11-14 21:10:44,237 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 2e2daa0f-a89f-481b-ae12-1f8f26537501, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:44,878 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:10:44,879 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 347feeae-3583-490c-8601-76ab0b7d70e8, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:44,879 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 347feeae-3583-490c-8601-76ab0b7d70e8, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:10:44,880 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 347feeae-3583-490c-8601-76ab0b7d70e8, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:44,882 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 8 < 100, will create new one.
2025-11-14 21:10:44,895 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ba2f6084-54a8-4ccc-a180-764eb6d818e9, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:44,895 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ba2f6084-54a8-4ccc-a180-764eb6d818e9, ghrun-alaktn3s7q.auto.internal:18008): request = {  }
2025-11-14 21:10:45,019 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ba2f6084-54a8-4ccc-a180-764eb6d818e9, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" node_id: 1 }
2025-11-14 21:10:45,020 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, e239c7a5-bf6d-4c11-9b29-b893d809d8dd, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:45,020 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, e239c7a5-bf6d-4c11-9b29-b893d809d8dd, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" }
2025-11-14 21:10:45,021 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, e239c7a5-bf6d-4c11-9b29-b893d809d8dd, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:45,084 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:10:45,123 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 58b84934-1936-46f7-b315-c3f1649e7c16, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:45,124 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 58b84934-1936-46f7-b315-c3f1649e7c16, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:10:45,124 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 58b84934-1936-46f7-b315-c3f1649e7c16, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:45,125 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 9 < 100, will create new one.
2025-11-14 21:10:45,138 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, cc67709f-dfd8-4a8a-95fc-a52a6774d4ea, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:45,147 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, cc67709f-dfd8-4a8a-95fc-a52a6774d4ea, ghrun-alaktn3s7q.auto.internal:18008): request = {  }
2025-11-14 21:10:45,319 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, cc67709f-dfd8-4a8a-95fc-a52a6774d4ea, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==" node_id: 1 }
2025-11-14 21:10:45,335 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 2fdaecf4-671e-460f-8961-a1e4d90e10bb, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:45,339 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-14 21:10:45,363 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 2fdaecf4-671e-460f-8961-a1e4d90e10bb, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==" }
2025-11-14 21:10:45,371 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 2fdaecf4-671e-460f-8961-a1e4d90e10bb, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:45,371 - DEBUG - ya.test - _save_outputs: Command (pid 3659940) rc: 0
2025-11-14 21:10:45,382 - DEBUG - ya.test - _save_outputs: Command (pid 3659940) elapsed time (sec): 3.9561357498168945
2025-11-14 21:10:45,383 - DEBUG - ya.test - _save_outputs: Command (pid 3659940) wtime: 4.011
2025-11-14 21:10:45,388 - DEBUG - ya.test - _save_outputs: Command (pid 3659940) 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

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	      0	0	0	20	0	0	0	0

2025-11-14 21:10:45,389 - DEBUG - ya.test - _save_outputs: Command (pid 3659940) errors:

2025-11-14 21:10:45,476 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:10:45,489 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c7e640e7-4791-4996-9f7f-aaa3716c778a, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:10:45,489 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c7e640e7-4791-4996-9f7f-aaa3716c778a, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:10:45,502 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c7e640e7-4791-4996-9f7f-aaa3716c778a, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:10:46,307 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-14 21:10:46,307 - DEBUG - ya.test - _save_outputs: Command (pid 3660059) rc: 0
2025-11-14 21:10:46,314 - DEBUG - ya.test - _save_outputs: Command (pid 3660059) elapsed time (sec): 4.867131948471069
2025-11-14 21:10:46,315 - DEBUG - ya.test - _save_outputs: Command (pid 3660059) wtime: 4.876
2025-11-14 21:10:46,315 - DEBUG - ya.test - _save_outputs: Command (pid 3660059) 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	14	0	0	0	0

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	      0	0	0	24	0	0	0	0

2025-11-14 21:10:46,315 - DEBUG - ya.test - _save_outputs: Command (pid 3660059) errors:

2025-11-14 21:11:00,470 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:11:00,483 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:11:00,491 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3a25f37b-5f09-4e13-9c60-5f413cdd2b58, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:00,499 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3a25f37b-5f09-4e13-9c60-5f413cdd2b58, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:00,506 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3a25f37b-5f09-4e13-9c60-5f413cdd2b58, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:00,678 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:11:00,679 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:11:00,679 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bbd21eb4-78b3-44ac-9a73-0911800de6b4, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:00,680 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bbd21eb4-78b3-44ac-9a73-0911800de6b4, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:00,689 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bbd21eb4-78b3-44ac-9a73-0911800de6b4, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:00,975 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:11:00,979 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:11:00,980 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 34c476e3-69d0-44d0-b41b-c4a674e0e78b, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:00,981 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 34c476e3-69d0-44d0-b41b-c4a674e0e78b, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:00,983 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 34c476e3-69d0-44d0-b41b-c4a674e0e78b, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:01,056 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:11:01,056 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:11:01,057 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7a40e72e-ef05-4eeb-a70c-2801daaf22b3, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:01,057 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7a40e72e-ef05-4eeb-a70c-2801daaf22b3, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:01,072 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7a40e72e-ef05-4eeb-a70c-2801daaf22b3, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:01,122 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:11:01,122 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:11:01,123 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 329e5bd3-753d-47bc-96db-4bc35d9b6df7, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:01,126 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 329e5bd3-753d-47bc-96db-4bc35d9b6df7, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:01,127 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 329e5bd3-753d-47bc-96db-4bc35d9b6df7, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:01,566 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:11:01,568 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:11:01,569 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6f283d94-468b-49bf-bf82-cfb5874ac3ac, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:01,569 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6f283d94-468b-49bf-bf82-cfb5874ac3ac, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:01,570 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6f283d94-468b-49bf-bf82-cfb5874ac3ac, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:01,607 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:11:01,612 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:11:01,613 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 264cf47e-a2d6-40d8-8679-20bcf0c5431b, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:01,613 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 264cf47e-a2d6-40d8-8679-20bcf0c5431b, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:01,614 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 264cf47e-a2d6-40d8-8679-20bcf0c5431b, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:01,715 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:11:01,718 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:11:01,718 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6690f530-3a80-49a3-a812-f6f80b42c181, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:01,719 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6690f530-3a80-49a3-a812-f6f80b42c181, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:01,723 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:11:01,734 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:11:01,744 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6fc434c3-1f14-4d6f-be8a-820ed3b09566, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:01,745 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6fc434c3-1f14-4d6f-be8a-820ed3b09566, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:01,735 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:11:01,772 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:11:01,776 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 92d20927-0ac6-4b99-b0c7-1525ecb10d21, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:01,777 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 92d20927-0ac6-4b99-b0c7-1525ecb10d21, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:01,778 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 92d20927-0ac6-4b99-b0c7-1525ecb10d21, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:01,763 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6fc434c3-1f14-4d6f-be8a-820ed3b09566, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:01,736 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6690f530-3a80-49a3-a812-f6f80b42c181, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:01,831 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-14 21:11:01,831 - DEBUG - ya.test - _save_outputs: Command (pid 3660047) rc: 0
2025-11-14 21:11:01,832 - DEBUG - ya.test - _save_outputs: Command (pid 3660047) elapsed time (sec): 20.412416458129883
2025-11-14 21:11:01,832 - DEBUG - ya.test - _save_outputs: Command (pid 3660047) wtime: 20.428
2025-11-14 21:11:01,832 - DEBUG - ya.test - _save_outputs: Command (pid 3660047) 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	0	0	0	0	0
3	     10	10	0	0	17279	20095	20095	20095

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	     10	3.33333	0	0	17279	20095	20095	20095

2025-11-14 21:11:01,832 - DEBUG - ya.test - _save_outputs: Command (pid 3660047) errors:

2025-11-14 21:11:21,149 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:11:21,259 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:11:21,270 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 74377ba0-bf58-4671-9baf-fc59f3250e10, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:21,271 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 74377ba0-bf58-4671-9baf-fc59f3250e10, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:21,286 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 74377ba0-bf58-4671-9baf-fc59f3250e10, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:21,817 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:11:21,939 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:11:21,949 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1d11831a-8ba9-40f3-b60d-6de15dcee0a0, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:21,957 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1d11831a-8ba9-40f3-b60d-6de15dcee0a0, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:21,986 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1d11831a-8ba9-40f3-b60d-6de15dcee0a0, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:22,385 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:11:22,521 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:11:22,527 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3ce84726-6711-485c-8307-10bb5e9e7640, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:22,529 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3ce84726-6711-485c-8307-10bb5e9e7640, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:22,536 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3ce84726-6711-485c-8307-10bb5e9e7640, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:22,764 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:11:22,869 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:11:22,884 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 268cb5fb-44cb-4c9f-820e-10e8d34f826d, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:22,891 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 268cb5fb-44cb-4c9f-820e-10e8d34f826d, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:22,898 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 268cb5fb-44cb-4c9f-820e-10e8d34f826d, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:23,343 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:11:23,482 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:11:23,504 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ec0706b6-3f3d-4a8f-bb68-5e05f7b2fa62, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:23,505 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ec0706b6-3f3d-4a8f-bb68-5e05f7b2fa62, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:23,512 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:11:23,522 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ec0706b6-3f3d-4a8f-bb68-5e05f7b2fa62, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:23,609 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:11:23,624 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b7a4270d-ea12-4256-85b6-358859e514b4, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:23,628 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b7a4270d-ea12-4256-85b6-358859e514b4, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:23,642 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b7a4270d-ea12-4256-85b6-358859e514b4, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:23,660 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:11:23,745 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:11:23,748 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 28ed7a3d-e608-4340-96d4-d78780ce8ad4, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:23,749 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 28ed7a3d-e608-4340-96d4-d78780ce8ad4, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:23,752 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 28ed7a3d-e608-4340-96d4-d78780ce8ad4, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:23,760 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:11:23,851 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:11:23,852 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 76b64c29-cefe-40b6-a0fb-8ff6d3ad7ea8, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:23,855 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 76b64c29-cefe-40b6-a0fb-8ff6d3ad7ea8, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:23,858 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 76b64c29-cefe-40b6-a0fb-8ff6d3ad7ea8, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:23,858 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:11:23,948 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:11:23,956 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1bfaff44-2c2f-4818-8045-043ff2b9d19a, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:23,957 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1bfaff44-2c2f-4818-8045-043ff2b9d19a, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:23,961 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1bfaff44-2c2f-4818-8045-043ff2b9d19a, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:23,968 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:11:24,107 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:11:24,115 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2ac84791-d9f6-419c-add7-e7b6c8e7b42f, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:24,116 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2ac84791-d9f6-419c-add7-e7b6c8e7b42f, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:24,119 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2ac84791-d9f6-419c-add7-e7b6c8e7b42f, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:30,912 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:11:30,913 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:11:30,922 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4f29a89d-92d4-4dab-855e-6757f60c2573, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:30,929 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4f29a89d-92d4-4dab-855e-6757f60c2573, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(1))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:30,931 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4f29a89d-92d4-4dab-855e-6757f60c2573, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:31,040 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:11:31,041 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:11:31,045 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a2bc0a5f-ca53-4d9d-965c-07c88d5ae408, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:31,045 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a2bc0a5f-ca53-4d9d-965c-07c88d5ae408, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(9))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:31,059 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a2bc0a5f-ca53-4d9d-965c-07c88d5ae408, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:31,182 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:11:31,183 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:11:31,183 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 626ff0d2-6a6d-4e63-928d-098e5fbf3615, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:31,229 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 626ff0d2-6a6d-4e63-928d-098e5fbf3615, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(6))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:31,236 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:11:31,240 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 626ff0d2-6a6d-4e63-928d-098e5fbf3615, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:31,241 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:11:31,243 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 27b64761-f682-44a8-80e2-18ad9ea12a0d, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:31,244 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 27b64761-f682-44a8-80e2-18ad9ea12a0d, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(11)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:31,245 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 27b64761-f682-44a8-80e2-18ad9ea12a0d, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:31,472 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:11:31,475 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:11:31,476 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c209e2c1-b598-416a-8e6d-49752de5255f, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:31,488 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c209e2c1-b598-416a-8e6d-49752de5255f, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(1))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:31,492 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c209e2c1-b598-416a-8e6d-49752de5255f, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:31,479 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:11:31,496 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:11:31,497 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6535639c-2f81-4dde-977f-cb566894828c, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:31,498 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6535639c-2f81-4dde-977f-cb566894828c, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(11)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:31,476 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:11:31,499 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:11:31,500 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6535639c-2f81-4dde-977f-cb566894828c, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:31,508 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ab5fd3ef-7418-459c-a6de-13f808a30146, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:31,511 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ab5fd3ef-7418-459c-a6de-13f808a30146, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:31,513 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ab5fd3ef-7418-459c-a6de-13f808a30146, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:31,562 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:11:31,563 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:11:31,564 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7cd25944-491a-49af-8cdb-db50088a60b4, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:31,565 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7cd25944-491a-49af-8cdb-db50088a60b4, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:31,566 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7cd25944-491a-49af-8cdb-db50088a60b4, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:31,663 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:11:31,664 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:11:31,665 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7bef2803-522c-4df0-8b20-c525994b046e, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:31,671 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7bef2803-522c-4df0-8b20-c525994b046e, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(11)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:31,674 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7bef2803-522c-4df0-8b20-c525994b046e, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:31,841 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:11:31,841 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:11:31,842 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 70b55ccf-f4c0-436c-b199-7a336369a82b, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:31,842 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 70b55ccf-f4c0-436c-b199-7a336369a82b, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(5)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(4))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:31,844 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 70b55ccf-f4c0-436c-b199-7a336369a82b, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:34,255 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:11:34,259 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:11:34,275 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:11:34,283 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:11:34,299 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:11:34,307 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:11:34,323 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:11:34,324 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:11:34,324 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:11:34,324 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:11:34,370 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:11:34,371 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4ff83de8-809d-4025-8253-de82e7e51d54, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:34,377 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4ff83de8-809d-4025-8253-de82e7e51d54, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" 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-14 21:11:34,378 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4ff83de8-809d-4025-8253-de82e7e51d54, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:34,655 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:11:34,658 - INFO - root - test_log_deviation: check insert: 18500 13500
2025-11-14 21:11:34,681 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.4
2025-11-14 21:11:34,688 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-11-14 21:11:34,711 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-11-14 21:11:34,725 - DEBUG - ya.test - execute: Command pid: 3685507
2025-11-14 21:11:35,055 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) rc: 0
2025-11-14 21:11:35,055 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) elapsed time (sec): 0.32383108139038086
2025-11-14 21:11:35,055 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) idrss: 0
2025-11-14 21:11:35,056 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) inblock: 0
2025-11-14 21:11:35,056 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) isrss: 0
2025-11-14 21:11:35,056 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) ixrss: 0
2025-11-14 21:11:35,056 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) majflt: 0
2025-11-14 21:11:35,060 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) maxrss: 684424
2025-11-14 21:11:35,060 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) minflt: 3482
2025-11-14 21:11:35,060 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) msgrcv: 0
2025-11-14 21:11:35,061 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) msgsnd: 0
2025-11-14 21:11:35,061 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) nivcsw: 54
2025-11-14 21:11:35,061 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) nsignals: 0
2025-11-14 21:11:35,061 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) nswap: 0
2025-11-14 21:11:35,061 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) nvcsw: 132
2025-11-14 21:11:35,062 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) oublock: 8
2025-11-14 21:11:35,062 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) stime: 0.032995
2025-11-14 21:11:35,062 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) utime: 0.057284999999999996
2025-11-14 21:11:35,062 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) wtime: 0.333
2025-11-14 21:11:35,062 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) output:
Remove path log...
Remove path log...Ok

2025-11-14 21:11:35,062 - DEBUG - ya.test - _save_outputs: Command (pid 3685507) errors:

2025-11-14 21:11:35,065 - INFO - ya.test - pytest_runtest_teardown: Test teardown
