2025-11-11 16:02:27,655 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 16:02:27,655 - INFO - ya.test - pytest_runtest_setup: test_log_deviation[1051200]
2025-11-11 16:02:27,656 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 16:02:27,656 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-11 16:02:27,657 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-11-11 16:02:27,658 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-11-11 16:02:27,658 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-11 16:02:27,665 - DEBUG - ya.test - execute: Command pid: 3192424
2025-11-11 16:02:30,564 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) rc: 0
2025-11-11 16:02:30,565 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) elapsed time (sec): 2.8974609375
2025-11-11 16:02:30,565 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) idrss: 0
2025-11-11 16:02:30,565 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) inblock: 0
2025-11-11 16:02:30,565 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) isrss: 0
2025-11-11 16:02:30,565 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) ixrss: 0
2025-11-11 16:02:30,566 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) majflt: 0
2025-11-11 16:02:30,566 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) maxrss: 543264
2025-11-11 16:02:30,566 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) minflt: 16228
2025-11-11 16:02:30,566 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) msgrcv: 0
2025-11-11 16:02:30,566 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) msgsnd: 0
2025-11-11 16:02:30,566 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) nivcsw: 699
2025-11-11 16:02:30,566 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) nsignals: 0
2025-11-11 16:02:30,567 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) nswap: 0
2025-11-11 16:02:30,567 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) nvcsw: 26
2025-11-11 16:02:30,567 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) oublock: 8
2025-11-11 16:02:30,567 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) stime: 1.9360680000000001
2025-11-11 16:02:30,567 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) utime: 0.117561
2025-11-11 16:02:30,567 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) wtime: 2.904
2025-11-11 16:02:30,592 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) output:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-l3u76ogmey
    Host information: 
        Linux ghrun-l3u76ogmey 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-11 16:02:30,592 - DEBUG - ya.test - _save_outputs: Command (pid 3192424) errors:

2025-11-11 16:02:30,593 - INFO - __tests__.test_log_scenario - _setup_ydb: Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-l3u76ogmey
    Host information: 
        Linux ghrun-l3u76ogmey 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-11 16:02:30,818 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydbd/ydbd
2025-11-11 16:02:30,819 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/27425
2025-11-11 16:02:30,825 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/72pg/000725/r3tmp/kikimr_cluster_ccg7g8yj
2025-11-11 16:02:30,976 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydbd/ydbd
2025-11-11 16:02:30,977 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/30334
2025-11-11 16:02:30,977 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/6304
2025-11-11 16:02:30,979 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24431
2025-11-11 16:02:30,980 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1212
2025-11-11 16:02:30,980 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/72pg/000725/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-11 16:02:30,981 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/72pg/000725/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/72pg/000725/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_bw1a9duq.log --grpc-port=30334 --mon-port=6304 --ic-port=27425
2025-11-11 16:02:30,981 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:30334/1, disk_size 68719476736
2025-11-11 16:02:30,981 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/72pg/000725/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-11 16:02:30,981 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/72pg/000725/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/72pg/000725/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_bw1a9duq.log --grpc-port=30334 --mon-port=6304 --ic-port=27425
2025-11-11 16:02:30,982 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/72pg/000725/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/72pg/000725/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_bw1a9duq.log', '--grpc-port=30334', '--mon-port=6304', '--ic-port=27425')' in '/home/runner/.ya/build/build_root/72pg/000725/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-11 16:02:31,000 - DEBUG - ya.test - execute: Command pid: 3195538
2025-11-11 16:02:31,001 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:30334/1
2025-11-11 16:02:31,002 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-11-11 16:02:31,003 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-11-11 16:02:31,003 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-11-11 16:02:31,012 - DEBUG - ya.test - execute: Command pid: 3195539
2025-11-11 16:02:36,796 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) rc: 0
2025-11-11 16:02:36,796 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) elapsed time (sec): 5.782876253128052
2025-11-11 16:02:36,797 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) idrss: 0
2025-11-11 16:02:36,797 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) inblock: 0
2025-11-11 16:02:36,797 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) isrss: 0
2025-11-11 16:02:36,797 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) ixrss: 0
2025-11-11 16:02:36,797 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) majflt: 0
2025-11-11 16:02:36,797 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) maxrss: 543264
2025-11-11 16:02:36,797 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) minflt: 16228
2025-11-11 16:02:36,797 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) msgrcv: 0
2025-11-11 16:02:36,797 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) msgsnd: 0
2025-11-11 16:02:36,798 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) nivcsw: 730
2025-11-11 16:02:36,798 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) nsignals: 0
2025-11-11 16:02:36,798 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) nswap: 0
2025-11-11 16:02:36,798 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) nvcsw: 59
2025-11-11 16:02:36,798 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) oublock: 8
2025-11-11 16:02:36,798 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) stime: 2.322008
2025-11-11 16:02:36,798 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) utime: 0.12310099999999999
2025-11-11 16:02:36,798 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) wtime: 5.793
2025-11-11 16:02:36,798 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) output:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a

Other info:
    Build by: runner
    Top src dir: /home/runner/actions_runner/_work/ydb/ydb
    Top build dir: /home/runner/.ya/build
    Hostname: ghrun-l3u76ogmey
    Host information: 
        Linux ghrun-l3u76ogmey 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-11 16:02:36,799 - DEBUG - ya.test - _save_outputs: Command (pid 3195539) errors:

2025-11-11 16:02:36,799 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: edc42c4313d1ecf4073f2f2b8dc05dd5cba26c17
    Branch: 
    Author: ubyte <himeraster@gmail.com>
    Summary: Merge 0e8f5d4b993a763788a819dc96a27139b4faa153 into d428e61cc4e3c2ff244c18f2eff147ecafd8254a
2025-11-11 16:02:53,255 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydbd/ydbd
2025-11-11 16:02:53,255 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:30334', '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: 27425\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-11-11 16:02:53,256 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-11-11 16:02:53,257 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-11-11 16:02:53,257 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:30334', '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: 27425\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-11 16:02:53,260 - DEBUG - ya.test - execute: Command pid: 3215995
2025-11-11 16:02:58,804 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) rc: 0
2025-11-11 16:02:58,819 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) elapsed time (sec): 5.542206764221191
2025-11-11 16:02:58,825 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) idrss: 0
2025-11-11 16:02:58,826 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) inblock: 0
2025-11-11 16:02:58,826 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) isrss: 0
2025-11-11 16:02:58,827 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) ixrss: 0
2025-11-11 16:02:58,827 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) majflt: 0
2025-11-11 16:02:58,827 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) maxrss: 551632
2025-11-11 16:02:58,827 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) minflt: 18373
2025-11-11 16:02:58,827 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) msgrcv: 0
2025-11-11 16:02:58,827 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) msgsnd: 0
2025-11-11 16:02:58,827 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) nivcsw: 692
2025-11-11 16:02:58,828 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) nsignals: 0
2025-11-11 16:02:58,828 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) nswap: 0
2025-11-11 16:02:58,828 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) nvcsw: 100
2025-11-11 16:02:58,828 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) oublock: 8
2025-11-11 16:02:58,828 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) stime: 1.907674
2025-11-11 16:02:58,828 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) utime: 0.12986899999999998
2025-11-11 16:02:58,828 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) wtime: 5.545
2025-11-11 16:02:58,829 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-11-11 16:02:58,829 - DEBUG - ya.test - _save_outputs: Command (pid 3215995) errors:

2025-11-11 16:02:58,832 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydbd/ydbd
2025-11-11 16:02:58,834 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:30334', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']
2025-11-11 16:02:58,835 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-11-11 16:02:58,835 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-11-11 16:02:58,836 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:30334', 'admin', 'blobstorage', 'config', 'invoke', '--proto=Command {\n  DefineStoragePool {\n    BoxId: 1\n    StoragePoolId: 1\n    Name: "dynamic_storage_pool:1"\n    ErasureSpecies: "none"\n    VDiskKind: "Default"\n    Kind: "hdd"\n    NumGroups: 2\n    PDiskFilter {\n      Property {\n        Type: ROT\n      }\n      Property {\n        Kind: 0\n      }\n    }\n  }\n}\n']' in 'None' (waiting)
2025-11-11 16:02:58,849 - DEBUG - ya.test - execute: Command pid: 3220182
2025-11-11 16:03:02,884 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) rc: 0
2025-11-11 16:03:02,885 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) elapsed time (sec): 4.013469934463501
2025-11-11 16:03:02,900 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) idrss: 0
2025-11-11 16:03:02,900 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) inblock: 0
2025-11-11 16:03:02,901 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) isrss: 0
2025-11-11 16:03:02,901 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) ixrss: 0
2025-11-11 16:03:02,901 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) majflt: 0
2025-11-11 16:03:02,901 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) maxrss: 551640
2025-11-11 16:03:02,901 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) minflt: 18369
2025-11-11 16:03:02,913 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) msgrcv: 0
2025-11-11 16:03:02,918 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) msgsnd: 0
2025-11-11 16:03:02,918 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) nivcsw: 484
2025-11-11 16:03:02,918 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) nsignals: 0
2025-11-11 16:03:02,918 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) nswap: 0
2025-11-11 16:03:02,918 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) nvcsw: 97
2025-11-11 16:03:02,919 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) oublock: 8
2025-11-11 16:03:02,919 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) stime: 0.924937
2025-11-11 16:03:02,919 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) utime: 0.13805199999999998
2025-11-11 16:03:02,919 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) wtime: 4.026
2025-11-11 16:03:02,919 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-11-11 16:03:02,931 - DEBUG - ya.test - _save_outputs: Command (pid 3220182) errors:

2025-11-11 16:03:02,931 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-11-11 16:03:02,992 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-11-11 16:03:03,035 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-11 16:03:03,038 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 16:03:03,042 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-11 16:03:03,079 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-11 16:03:03,080 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 06d153b2-3550-48c5-880d-190aeebbff14, localhost:30334): creating call state
2025-11-11 16:03:03,080 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 06d153b2-3550-48c5-880d-190aeebbff14, localhost:30334): request = { database: "/Root" }
2025-11-11 16:03:03,094 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 06d153b2-3550-48c5-880d-190aeebbff14, localhost:30334): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-l3u76ogmey.auto.internal" port: 30334 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-11 16:03:03,094 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-l3u76ogmey.auto.internal:30334, location 1, ssl: False>]>
2025-11-11 16:03:03,095 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-11 16:03:03,198 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_deviation[1051200])
2025-11-11 16:03:03,204 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb
2025-11-11 16:03:03,204 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb -e grpc://localhost:30334 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-11-11 16:03:03,205 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-11-11 16:03:03,266 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-11-11 16:03:03,286 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:30334', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-11-11 16:03:03,294 - DEBUG - ya.test - execute: Command pid: 3224190
2025-11-11 16:03:04,272 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) rc: 0
2025-11-11 16:03:04,273 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) elapsed time (sec): 0.9779369831085205
2025-11-11 16:03:04,273 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) idrss: 0
2025-11-11 16:03:04,274 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) inblock: 0
2025-11-11 16:03:04,274 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) isrss: 0
2025-11-11 16:03:04,274 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) ixrss: 0
2025-11-11 16:03:04,274 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) majflt: 0
2025-11-11 16:03:04,274 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) maxrss: 184684
2025-11-11 16:03:04,275 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) minflt: 3490
2025-11-11 16:03:04,275 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) msgrcv: 0
2025-11-11 16:03:04,275 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) msgsnd: 0
2025-11-11 16:03:04,275 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) nivcsw: 57
2025-11-11 16:03:04,275 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) nsignals: 0
2025-11-11 16:03:04,275 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) nswap: 0
2025-11-11 16:03:04,276 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) nvcsw: 128
2025-11-11 16:03:04,276 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) oublock: 8
2025-11-11 16:03:04,276 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) stime: 0.03251
2025-11-11 16:03:04,276 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) utime: 0.054183999999999996
2025-11-11 16:03:04,276 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) wtime: 0.986
2025-11-11 16:03:04,276 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) output:
Init tables ...
Init tables ...Ok

2025-11-11 16:03:04,277 - DEBUG - ya.test - _save_outputs: Command (pid 3224190) errors:

2025-11-11 16:03:04,277 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-11-11 16:03:04,278 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 2667f65f-3bdf-458f-95b4-0eb93f81c36f, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:04,278 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 2667f65f-3bdf-458f-95b4-0eb93f81c36f, ghrun-l3u76ogmey.auto.internal:30334): request = {  }
2025-11-11 16:03:04,313 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 2667f65f-3bdf-458f-95b4-0eb93f81c36f, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=" node_id: 1 }
2025-11-11 16:03:04,313 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, d25ea5c6-b0d0-4f1f-9bd6-8d4bd74ce671, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:04,314 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, d25ea5c6-b0d0-4f1f-9bd6-8d4bd74ce671, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=" }
2025-11-11 16:03:04,315 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, d25ea5c6-b0d0-4f1f-9bd6-8d4bd74ce671, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:04,371 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:03:04,371 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3bf72879-0e72-41ec-ab18-208381202428, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:04,372 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3bf72879-0e72-41ec-ab18-208381202428, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=" 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-11 16:03:04,373 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3bf72879-0e72-41ec-ab18-208381202428, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:04,432 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:03:04,433 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-11 16:03:04,433 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb -e grpc://localhost:30334 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 1051200 with wait = True
2025-11-11 16:03:04,434 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-11-11 16:03:04,435 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-11-11 16:03:04,436 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:30334', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '1051200']' in 'None' (waiting)
2025-11-11 16:03:04,447 - DEBUG - ya.test - execute: Command pid: 3225265
2025-11-11 16:03:09,107 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 16:03:09,107 - DEBUG - ya.test - _save_outputs: Command (pid 3225265) rc: 0
2025-11-11 16:03:09,110 - DEBUG - ya.test - _save_outputs: Command (pid 3225265) elapsed time (sec): 4.659867286682129
2025-11-11 16:03:09,114 - DEBUG - ya.test - _save_outputs: Command (pid 3225265) wtime: 4.672
2025-11-11 16:03:09,115 - DEBUG - ya.test - _save_outputs: Command (pid 3225265) 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	1543	1815	1815	1815
3	     16	16	0	0	1231	1439	1447	1447

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	     26	8.66667	0	0	1231	1679	1815	1815

2025-11-11 16:03:09,116 - DEBUG - ya.test - _save_outputs: Command (pid 3225265) errors:

2025-11-11 16:03:09,121 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:03:09,127 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b09852f9-9c60-4b60-b2dd-ac3d081d5658, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:09,139 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b09852f9-9c60-4b60-b2dd-ac3d081d5658, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:09,139 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b09852f9-9c60-4b60-b2dd-ac3d081d5658, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:11,888 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:03:11,888 - INFO - root - test_log_deviation: Count rows after insert 13000 before wait
2025-11-11 16:03:11,888 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:03:11,889 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 566bc097-6ad1-412d-819c-7add07e2ac05, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:11,889 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 566bc097-6ad1-412d-819c-7add07e2ac05, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:11,890 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 566bc097-6ad1-412d-819c-7add07e2ac05, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:13,477 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:03:13,495 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:03:13,496 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 70ba0753-dd7a-4698-b70a-bab9b1c430a4, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:13,498 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 70ba0753-dd7a-4698-b70a-bab9b1c430a4, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:13,518 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 70ba0753-dd7a-4698-b70a-bab9b1c430a4, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:15,800 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:03:15,805 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-11 16:03:15,812 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb -e grpc://localhost:30334 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 1051200 with wait = True
2025-11-11 16:03:15,814 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-11-11 16:03:15,806 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-11-11 16:03:15,824 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb -e grpc://localhost:30334 -d /Root workload log --path log run insert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 1051200 with wait = True
2025-11-11 16:03:15,807 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-11-11 16:03:15,838 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb -e grpc://localhost:30334 -d /Root workload log --path log run upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 1051200 with wait = True
2025-11-11 16:03:15,807 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:03:15,839 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-11-11 16:03:15,872 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:30334', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '1051200']' in 'None' (waiting)
2025-11-11 16:03:15,859 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.2
2025-11-11 16:03:15,859 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-11-11 16:03:15,883 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, d2fad946-92b4-455f-8913-258067fad326, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:15,883 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, d2fad946-92b4-455f-8913-258067fad326, ghrun-l3u76ogmey.auto.internal:30334): request = {  }
2025-11-11 16:03:15,879 - DEBUG - ya.test - execute: Command pid: 3236207
2025-11-11 16:03:15,864 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.3
2025-11-11 16:03:15,857 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e53a207e-d9df-44b2-864f-6867bb9c01f2, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:15,883 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-11-11 16:03:15,908 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-11-11 16:03:15,914 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e53a207e-d9df-44b2-864f-6867bb9c01f2, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:15,903 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, d2fad946-92b4-455f-8913-258067fad326, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi" node_id: 1 }
2025-11-11 16:03:15,936 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:30334', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '1051200']' in 'None' (waiting)
2025-11-11 16:03:15,927 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e53a207e-d9df-44b2-864f-6867bb9c01f2, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:15,936 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:30334', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'insert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '1051200']' in 'None' (waiting)
2025-11-11 16:03:15,969 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 9387471d-5339-48a0-8f73-ce2880d5e8b2, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:15,974 - DEBUG - ya.test - execute: Command pid: 3236299
2025-11-11 16:03:16,007 - DEBUG - ya.test - execute: Command pid: 3236306
2025-11-11 16:03:16,003 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 9387471d-5339-48a0-8f73-ce2880d5e8b2, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi" }
2025-11-11 16:03:16,037 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 9387471d-5339-48a0-8f73-ce2880d5e8b2, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:16,052 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi
2025-11-11 16:03:16,062 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 66f300b6-2f23-46b0-ad4c-629ef3d211cb, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,062 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 66f300b6-2f23-46b0-ad4c-629ef3d211cb, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:16,069 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 66f300b6-2f23-46b0-ad4c-629ef3d211cb, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:16,062 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 2 < 100, will create new one.
2025-11-11 16:03:16,080 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, cfa89925-dd83-4630-948e-4685171ed179, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,080 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, cfa89925-dd83-4630-948e-4685171ed179, ghrun-l3u76ogmey.auto.internal:30334): request = {  }
2025-11-11 16:03:16,087 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, cfa89925-dd83-4630-948e-4685171ed179, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==" node_id: 1 }
2025-11-11 16:03:16,088 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 66ddc3fe-9936-493d-aca4-1743564acec8, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,088 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 66ddc3fe-9936-493d-aca4-1743564acec8, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==" }
2025-11-11 16:03:16,089 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 66ddc3fe-9936-493d-aca4-1743564acec8, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:16,091 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:03:16,091 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2b04bc19-cd1d-4d63-bb1f-d3c76cb1d2ac, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,092 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2b04bc19-cd1d-4d63-bb1f-d3c76cb1d2ac, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:16,104 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2b04bc19-cd1d-4d63-bb1f-d3c76cb1d2ac, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:16,092 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 3 < 100, will create new one.
2025-11-11 16:03:16,113 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, abc702d4-23ad-4f0a-9287-3499ec7adeff, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,125 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, abc702d4-23ad-4f0a-9287-3499ec7adeff, ghrun-l3u76ogmey.auto.internal:30334): request = {  }
2025-11-11 16:03:16,130 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, abc702d4-23ad-4f0a-9287-3499ec7adeff, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=" node_id: 1 }
2025-11-11 16:03:16,136 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, bf65d110-6207-49b3-816d-f5ee70766805, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,144 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, bf65d110-6207-49b3-816d-f5ee70766805, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=" }
2025-11-11 16:03:16,145 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, bf65d110-6207-49b3-816d-f5ee70766805, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:16,165 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:03:16,166 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3302b39d-b030-4346-90ae-95c6803ca776, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,167 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3302b39d-b030-4346-90ae-95c6803ca776, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:16,168 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 4 < 100, will create new one.
2025-11-11 16:03:16,170 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3302b39d-b030-4346-90ae-95c6803ca776, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:16,171 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 73137f08-f83e-47f5-93e2-7e03c216bf2d, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,199 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 73137f08-f83e-47f5-93e2-7e03c216bf2d, ghrun-l3u76ogmey.auto.internal:30334): request = {  }
2025-11-11 16:03:16,321 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 73137f08-f83e-47f5-93e2-7e03c216bf2d, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=" node_id: 1 }
2025-11-11 16:03:16,322 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, c3c5e03a-a93b-4e58-b9ce-ab074c056a3a, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,322 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, c3c5e03a-a93b-4e58-b9ce-ab074c056a3a, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=" }
2025-11-11 16:03:16,323 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, c3c5e03a-a93b-4e58-b9ce-ab074c056a3a, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:16,430 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:03:16,431 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 364cc53c-8631-46d6-b60b-71e474569991, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,431 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 364cc53c-8631-46d6-b60b-71e474569991, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:16,432 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 364cc53c-8631-46d6-b60b-71e474569991, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:16,433 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 5 < 100, will create new one.
2025-11-11 16:03:16,433 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 61d9043d-b053-4a26-ad09-fca93c024ab0, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,434 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 61d9043d-b053-4a26-ad09-fca93c024ab0, ghrun-l3u76ogmey.auto.internal:30334): request = {  }
2025-11-11 16:03:16,477 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 61d9043d-b053-4a26-ad09-fca93c024ab0, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==" node_id: 1 }
2025-11-11 16:03:16,478 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, fda1f32b-f9a4-414a-a690-9ddf232d139f, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,479 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, fda1f32b-f9a4-414a-a690-9ddf232d139f, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==" }
2025-11-11 16:03:16,480 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, fda1f32b-f9a4-414a-a690-9ddf232d139f, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:16,533 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==
2025-11-11 16:03:16,534 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 856e3e21-5fb0-4e49-a36d-c66cb7f1fb75, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,535 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 856e3e21-5fb0-4e49-a36d-c66cb7f1fb75, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:16,540 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 856e3e21-5fb0-4e49-a36d-c66cb7f1fb75, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:16,535 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 6 < 100, will create new one.
2025-11-11 16:03:16,544 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 3f7fbe37-6f8d-4b0c-8da9-bde75fb10220, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,553 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 3f7fbe37-6f8d-4b0c-8da9-bde75fb10220, ghrun-l3u76ogmey.auto.internal:30334): request = {  }
2025-11-11 16:03:16,729 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 3f7fbe37-6f8d-4b0c-8da9-bde75fb10220, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=" node_id: 1 }
2025-11-11 16:03:16,734 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, a0b09aa0-6b20-44b2-b50e-e43f5e3c25f5, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,735 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, a0b09aa0-6b20-44b2-b50e-e43f5e3c25f5, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=" }
2025-11-11 16:03:16,735 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, a0b09aa0-6b20-44b2-b50e-e43f5e3c25f5, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:16,857 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:03:16,861 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c9bc351f-5abc-45e8-9fc7-b0bac667384c, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,861 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c9bc351f-5abc-45e8-9fc7-b0bac667384c, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:16,862 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 7 < 100, will create new one.
2025-11-11 16:03:16,872 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, c0b3b33a-e2bd-4798-b161-14769c24943d, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:16,884 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, c0b3b33a-e2bd-4798-b161-14769c24943d, ghrun-l3u76ogmey.auto.internal:30334): request = {  }
2025-11-11 16:03:16,869 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c9bc351f-5abc-45e8-9fc7-b0bac667384c, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:17,171 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, c0b3b33a-e2bd-4798-b161-14769c24943d, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=" node_id: 1 }
2025-11-11 16:03:17,188 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 4538ce0e-8e73-478a-b6d4-0417f4d6da28, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:17,189 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 4538ce0e-8e73-478a-b6d4-0417f4d6da28, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=" }
2025-11-11 16:03:17,190 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 4538ce0e-8e73-478a-b6d4-0417f4d6da28, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:17,569 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:03:17,570 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 51e6698b-a29a-43f9-92b3-e365dd6f6580, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:17,570 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 51e6698b-a29a-43f9-92b3-e365dd6f6580, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:17,571 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 8 < 100, will create new one.
2025-11-11 16:03:17,574 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 51e6698b-a29a-43f9-92b3-e365dd6f6580, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:17,593 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 15ccd8f7-150e-451d-942c-76c9d2a61e2a, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:17,626 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 15ccd8f7-150e-451d-942c-76c9d2a61e2a, ghrun-l3u76ogmey.auto.internal:30334): request = {  }
2025-11-11 16:03:17,789 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 15ccd8f7-150e-451d-942c-76c9d2a61e2a, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=" node_id: 1 }
2025-11-11 16:03:17,790 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 9fab6c59-089c-4f2f-9928-de5648810bc8, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:17,790 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 9fab6c59-089c-4f2f-9928-de5648810bc8, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=" }
2025-11-11 16:03:17,791 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 9fab6c59-089c-4f2f-9928-de5648810bc8, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:17,866 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=
2025-11-11 16:03:17,866 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4ed301fb-5e14-4056-b8d5-a8e61a46a69d, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:17,867 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4ed301fb-5e14-4056-b8d5-a8e61a46a69d, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:17,868 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4ed301fb-5e14-4056-b8d5-a8e61a46a69d, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:17,869 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 9 < 100, will create new one.
2025-11-11 16:03:17,869 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, fa3668be-6e83-4d9b-a45b-c359a8481a9f, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:17,869 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, fa3668be-6e83-4d9b-a45b-c359a8481a9f, ghrun-l3u76ogmey.auto.internal:30334): request = {  }
2025-11-11 16:03:17,978 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, fa3668be-6e83-4d9b-a45b-c359a8481a9f, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=" node_id: 1 }
2025-11-11 16:03:17,978 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 523d95fa-c2df-4203-92aa-2fed44b0d383, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:17,987 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 523d95fa-c2df-4203-92aa-2fed44b0d383, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=" }
2025-11-11 16:03:17,997 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 523d95fa-c2df-4203-92aa-2fed44b0d383, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:18,097 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=
2025-11-11 16:03:18,097 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7b29631e-d281-46a1-a140-8862e6695da7, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:18,098 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7b29631e-d281-46a1-a140-8862e6695da7, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:18,099 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7b29631e-d281-46a1-a140-8862e6695da7, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:20,988 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 16:03:21,020 - DEBUG - ya.test - _save_outputs: Command (pid 3236306) rc: 0
2025-11-11 16:03:21,021 - DEBUG - ya.test - _save_outputs: Command (pid 3236306) elapsed time (sec): 4.991313457489014
2025-11-11 16:03:21,021 - DEBUG - ya.test - _save_outputs: Command (pid 3236306) wtime: 5.035
2025-11-11 16:03:21,021 - DEBUG - ya.test - _save_outputs: Command (pid 3236306) 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	12	0	0	0	0

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

2025-11-11 16:03:21,021 - DEBUG - ya.test - _save_outputs: Command (pid 3236306) errors:

2025-11-11 16:03:21,054 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 16:03:21,054 - DEBUG - ya.test - _save_outputs: Command (pid 3236299) rc: 0
2025-11-11 16:03:21,054 - DEBUG - ya.test - _save_outputs: Command (pid 3236299) elapsed time (sec): 5.049788236618042
2025-11-11 16:03:21,055 - DEBUG - ya.test - _save_outputs: Command (pid 3236299) wtime: 5.085
2025-11-11 16:03:21,055 - DEBUG - ya.test - _save_outputs: Command (pid 3236299) 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	16	0	0	0	0

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

2025-11-11 16:03:21,055 - DEBUG - ya.test - _save_outputs: Command (pid 3236299) errors:

2025-11-11 16:03:37,108 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 16:03:37,124 - DEBUG - ya.test - _save_outputs: Command (pid 3236207) rc: 0
2025-11-11 16:03:37,124 - DEBUG - ya.test - _save_outputs: Command (pid 3236207) elapsed time (sec): 21.214520931243896
2025-11-11 16:03:37,125 - DEBUG - ya.test - _save_outputs: Command (pid 3236207) wtime: 21.236
2025-11-11 16:03:37,125 - DEBUG - ya.test - _save_outputs: Command (pid 3236207) 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	      4	4	0	6	18815	20351	20351	20351

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	      4	1.33333	0	6	18815	20351	20351	20351

2025-11-11 16:03:37,125 - DEBUG - ya.test - _save_outputs: Command (pid 3236207) errors:

2025-11-11 16:03:37,502 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=
2025-11-11 16:03:37,505 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=
2025-11-11 16:03:37,509 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 44036d9c-873e-405c-a684-31abdbd8e85e, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:37,503 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==
2025-11-11 16:03:37,514 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:03:37,530 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 44036d9c-873e-405c-a684-31abdbd8e85e, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=" 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-11 16:03:37,557 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:03:37,581 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==
2025-11-11 16:03:37,584 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 70f287ab-0ab7-4340-bc6e-dc99bc27035b, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:37,588 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 70f287ab-0ab7-4340-bc6e-dc99bc27035b, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==" 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-11 16:03:37,589 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 70f287ab-0ab7-4340-bc6e-dc99bc27035b, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:37,585 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:03:37,590 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, da097387-be27-471f-8e58-93268e98fb7c, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:37,583 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 44036d9c-873e-405c-a684-31abdbd8e85e, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:37,591 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, da097387-be27-471f-8e58-93268e98fb7c, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=" 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-11 16:03:37,593 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, da097387-be27-471f-8e58-93268e98fb7c, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:37,591 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:03:37,594 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 279f6a5c-bd2a-4f93-9fcd-2819c3dcbab2, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:37,594 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 279f6a5c-bd2a-4f93-9fcd-2819c3dcbab2, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:37,595 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 279f6a5c-bd2a-4f93-9fcd-2819c3dcbab2, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:37,659 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=
2025-11-11 16:03:37,659 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=
2025-11-11 16:03:37,660 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 36bc84e6-e88c-4c8c-965e-98970dd516f7, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:37,661 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 36bc84e6-e88c-4c8c-965e-98970dd516f7, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:37,661 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 36bc84e6-e88c-4c8c-965e-98970dd516f7, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:38,263 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:03:38,263 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:03:38,264 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e6e0f37d-5415-4f50-8776-912f6bc3464b, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:38,265 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e6e0f37d-5415-4f50-8776-912f6bc3464b, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:38,280 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e6e0f37d-5415-4f50-8776-912f6bc3464b, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:38,316 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:03:38,334 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:03:38,335 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0cd1bc4c-d940-4f72-926c-3b030a4fd21c, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:38,335 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0cd1bc4c-d940-4f72-926c-3b030a4fd21c, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:03:38,336 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0cd1bc4c-d940-4f72-926c-3b030a4fd21c, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:38,380 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:03:38,380 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:03:38,381 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9d50d71a-c5a2-410b-b8c9-3850247f5710, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:38,381 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9d50d71a-c5a2-410b-b8c9-3850247f5710, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=" 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-11 16:03:38,387 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9d50d71a-c5a2-410b-b8c9-3850247f5710, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:38,391 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi
2025-11-11 16:03:38,393 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi
2025-11-11 16:03:38,400 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a19d2f92-ac4b-4688-8d42-a594263b1710, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:38,392 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:03:38,405 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a19d2f92-ac4b-4688-8d42-a594263b1710, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi" 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-11 16:03:38,410 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a19d2f92-ac4b-4688-8d42-a594263b1710, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:03:38,408 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:03:38,418 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a8410b88-973e-4438-83e7-766f467e9fe8, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:03:38,424 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a8410b88-973e-4438-83e7-766f467e9fe8, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=" 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-11 16:03:38,425 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a8410b88-973e-4438-83e7-766f467e9fe8, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:11,366 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==
2025-11-11 16:04:11,541 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==
2025-11-11 16:04:11,542 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0dc29aec-3b07-4f27-9eea-89ce73816fd0, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:11,548 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0dc29aec-3b07-4f27-9eea-89ce73816fd0, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==" 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-11 16:04:11,571 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0dc29aec-3b07-4f27-9eea-89ce73816fd0, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:11,584 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:04:11,913 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:04:11,934 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 995381c3-eacd-49a5-854b-bc0838ec91fd, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:11,935 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 995381c3-eacd-49a5-854b-bc0838ec91fd, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=" 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-11 16:04:11,948 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 995381c3-eacd-49a5-854b-bc0838ec91fd, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:12,207 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:04:12,208 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:04:12,208 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:04:12,210 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=
2025-11-11 16:04:12,477 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:04:12,759 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fae2fada-f5aa-4643-b5de-b1ab7336ed7c, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:12,759 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:04:12,858 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 031f2cb4-1f5f-42df-a9c8-db009e6a6894, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:12,887 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 031f2cb4-1f5f-42df-a9c8-db009e6a6894, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:04:12,888 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 031f2cb4-1f5f-42df-a9c8-db009e6a6894, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:12,773 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==
2025-11-11 16:04:12,793 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:04:12,807 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:04:12,839 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi
2025-11-11 16:04:12,764 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fae2fada-f5aa-4643-b5de-b1ab7336ed7c, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:04:13,352 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fae2fada-f5aa-4643-b5de-b1ab7336ed7c, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:12,765 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=
2025-11-11 16:04:12,772 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:04:12,859 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:04:13,603 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:04:13,605 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 35cb9b09-7745-4534-a5d1-b2f6b7e83f5a, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:13,611 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 35cb9b09-7745-4534-a5d1-b2f6b7e83f5a, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:04:13,609 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=
2025-11-11 16:04:13,613 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 35cb9b09-7745-4534-a5d1-b2f6b7e83f5a, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:13,614 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 75057319-109c-4a8e-a802-dc07dc2e414f, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:13,638 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 75057319-109c-4a8e-a802-dc07dc2e414f, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=" 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-11 16:04:13,614 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=
2025-11-11 16:04:13,639 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 75057319-109c-4a8e-a802-dc07dc2e414f, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:13,645 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 48b27d1d-6078-4a79-8694-7481f52b7190, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:13,647 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 48b27d1d-6078-4a79-8694-7481f52b7190, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=" 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-11 16:04:13,663 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 48b27d1d-6078-4a79-8694-7481f52b7190, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:13,645 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:04:13,668 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3a0067b8-5bef-41be-b5c9-8ed22cdf1378, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:13,680 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3a0067b8-5bef-41be-b5c9-8ed22cdf1378, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=" 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-11 16:04:13,690 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3a0067b8-5bef-41be-b5c9-8ed22cdf1378, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:13,669 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==
2025-11-11 16:04:13,709 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, de1a1257-7c9b-4062-b402-142765a2a02f, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:13,711 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:04:13,733 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, de1a1257-7c9b-4062-b402-142765a2a02f, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==" 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-11 16:04:13,734 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ff2cba41-8495-4d1e-99cc-93e43eb36d9d, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:13,736 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:04:13,740 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, de1a1257-7c9b-4062-b402-142765a2a02f, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:13,746 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ff2cba41-8495-4d1e-99cc-93e43eb36d9d, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=" 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-11 16:04:13,765 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ff2cba41-8495-4d1e-99cc-93e43eb36d9d, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:13,749 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi
2025-11-11 16:04:13,749 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f0ff6f95-dfe5-441a-9c84-5cb024e91c11, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:13,793 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 001b7853-9a9f-4d3e-907c-7beea045cea2, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:13,794 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:04:13,794 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f0ff6f95-dfe5-441a-9c84-5cb024e91c11, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:04:13,802 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f0ff6f95-dfe5-441a-9c84-5cb024e91c11, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:13,796 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 962df2dd-4e33-467e-8c0b-128ae066cde0, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:13,808 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 962df2dd-4e33-467e-8c0b-128ae066cde0, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(8))" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:04:13,796 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 001b7853-9a9f-4d3e-907c-7beea045cea2, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi" 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-11 16:04:13,810 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 962df2dd-4e33-467e-8c0b-128ae066cde0, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:13,812 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 001b7853-9a9f-4d3e-907c-7beea045cea2, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:16,397 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi
2025-11-11 16:04:16,400 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=
2025-11-11 16:04:16,538 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:04:16,549 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:04:16,550 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi
2025-11-11 16:04:16,550 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6c108691-a8a8-42d1-9fcd-6cbbaaac62ab, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:16,564 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6c108691-a8a8-42d1-9fcd-6cbbaaac62ab, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(7))" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:04:16,570 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:04:16,573 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=
2025-11-11 16:04:16,588 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5f1cfef7-4f45-4f0d-9ecd-6b7a089ef752, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:16,588 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5f1cfef7-4f45-4f0d-9ecd-6b7a089ef752, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=" 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-11 16:04:16,590 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5f1cfef7-4f45-4f0d-9ecd-6b7a089ef752, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:16,572 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6c108691-a8a8-42d1-9fcd-6cbbaaac62ab, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:16,616 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=
2025-11-11 16:04:16,617 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:04:16,622 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0a396a82-2db4-4095-bc28-84e449ef7257, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:16,623 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0a396a82-2db4-4095-bc28-84e449ef7257, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=" 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-11 16:04:16,627 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0a396a82-2db4-4095-bc28-84e449ef7257, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:16,655 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:04:16,660 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:04:16,661 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 90468c4a-eb55-44fc-b0ed-d3783b3f8f79, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:16,670 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 90468c4a-eb55-44fc-b0ed-d3783b3f8f79, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==" 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-11 16:04:16,686 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 90468c4a-eb55-44fc-b0ed-d3783b3f8f79, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:16,820 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:04:16,820 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:04:16,821 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6bca0ef0-6ded-40d0-814e-3d40f60d4f95, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:16,821 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6bca0ef0-6ded-40d0-814e-3d40f60d4f95, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(7))" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:04:16,829 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6bca0ef0-6ded-40d0-814e-3d40f60d4f95, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:16,866 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==
2025-11-11 16:04:16,877 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=
2025-11-11 16:04:16,877 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ae8da002-db71-4d32-9de1-575b5384ae23, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:16,882 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ae8da002-db71-4d32-9de1-575b5384ae23, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=" 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-11 16:04:16,886 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ae8da002-db71-4d32-9de1-575b5384ae23, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:17,057 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=
2025-11-11 16:04:17,277 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:04:17,279 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:04:17,280 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a5214d7a-e257-4a31-9b0a-b034708de9cd, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:17,281 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a5214d7a-e257-4a31-9b0a-b034708de9cd, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(8))" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:04:17,290 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a5214d7a-e257-4a31-9b0a-b034708de9cd, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:17,744 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:04:17,747 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi
2025-11-11 16:04:17,752 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:04:17,755 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=
2025-11-11 16:04:17,756 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:04:17,762 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:04:17,779 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:04:17,794 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5c7ca544-446f-47d7-85b1-adce1ed6a07c, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:04:17,814 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5c7ca544-446f-47d7-85b1-adce1ed6a07c, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:04:17,850 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5c7ca544-446f-47d7-85b1-adce1ed6a07c, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:04:18,129 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:04:18,130 - INFO - root - test_log_deviation: check insert: 17985 13000
2025-11-11 16:04:18,132 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.4
2025-11-11 16:04:18,142 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-11-11 16:04:18,143 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:30334', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-11-11 16:04:18,153 - DEBUG - ya.test - execute: Command pid: 3274438
2025-11-11 16:04:18,805 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) rc: 0
2025-11-11 16:04:18,805 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) elapsed time (sec): 0.6345739364624023
2025-11-11 16:04:18,805 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) idrss: 0
2025-11-11 16:04:18,805 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) inblock: 7544
2025-11-11 16:04:18,806 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) isrss: 0
2025-11-11 16:04:18,806 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) ixrss: 0
2025-11-11 16:04:18,812 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) majflt: 2
2025-11-11 16:04:18,825 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) maxrss: 744152
2025-11-11 16:04:18,842 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) minflt: 3486
2025-11-11 16:04:18,843 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) msgrcv: 0
2025-11-11 16:04:18,850 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) msgsnd: 0
2025-11-11 16:04:18,850 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) nivcsw: 56
2025-11-11 16:04:18,851 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) nsignals: 0
2025-11-11 16:04:18,851 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) nswap: 0
2025-11-11 16:04:18,851 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) nvcsw: 115
2025-11-11 16:04:18,851 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) oublock: 8
2025-11-11 16:04:18,851 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) stime: 0.0327
2025-11-11 16:04:18,856 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) utime: 0.050866999999999996
2025-11-11 16:04:18,862 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) wtime: 0.66
2025-11-11 16:04:18,862 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) output:
Remove path log...
Remove path log...Ok

2025-11-11 16:04:18,884 - DEBUG - ya.test - _save_outputs: Command (pid 3274438) errors:

2025-11-11 16:04:18,889 - INFO - ya.test - pytest_runtest_teardown: Test teardown
