2025-10-27 12:38:03,494 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-27 12:38:03,494 - INFO - ya.test - pytest_runtest_setup: test_log_deviation[1051200]
2025-10-27 12:38:03,495 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-27 12:38:03,495 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-27 12:38:03,497 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.3
2025-10-27 12:38:03,500 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.3
2025-10-27 12:38:03,501 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-27 12:38:03,622 - DEBUG - ya.test - execute: Command pid: 3883689
2025-10-27 12:38:04,395 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) rc: 0
2025-10-27 12:38:04,396 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) elapsed time (sec): 0.7725563049316406
2025-10-27 12:38:04,397 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) idrss: 0
2025-10-27 12:38:04,398 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) inblock: 0
2025-10-27 12:38:04,398 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) isrss: 0
2025-10-27 12:38:04,398 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) ixrss: 0
2025-10-27 12:38:04,398 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) majflt: 0
2025-10-27 12:38:04,398 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) maxrss: 539764
2025-10-27 12:38:04,398 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) minflt: 16119
2025-10-27 12:38:04,399 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) msgrcv: 0
2025-10-27 12:38:04,399 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) msgsnd: 0
2025-10-27 12:38:04,399 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) nivcsw: 133
2025-10-27 12:38:04,399 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) nsignals: 0
2025-10-27 12:38:04,399 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) nswap: 0
2025-10-27 12:38:04,399 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) nvcsw: 20
2025-10-27 12:38:04,400 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) oublock: 8
2025-10-27 12:38:04,400 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) stime: 0.268505
2025-10-27 12:38:04,400 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) utime: 0.14116499999999998
2025-10-27 12:38:04,400 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) wtime: 0.81
2025-10-27 12:38:04,400 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) output:
Git info:
    Commit: 6d76348c7d05728a81789044c932883db81b8e8b
    Branch: 
    Author: Stanislav Yablonskiy <neyrox@ydb.tech>
    Summary: Merge 1ce5967b32d6870e99c17fd930730f80185016e0 into dee8772a087cf0572972e1fbbfa383c447444ec3

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

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

2025-10-27 12:38:04,401 - DEBUG - ya.test - _save_outputs: Command (pid 3883689) errors:

2025-10-27 12:38:04,403 - INFO - __tests__.test_log_scenario - _setup_ydb: Git info:
    Commit: 6d76348c7d05728a81789044c932883db81b8e8b
    Branch: 
    Author: Stanislav Yablonskiy <neyrox@ydb.tech>
    Summary: Merge 1ce5967b32d6870e99c17fd930730f80185016e0 into dee8772a087cf0572972e1fbbfa383c447444ec3

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

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

2025-10-27 12:38:04,655 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydbd/ydbd
2025-10-27 12:38:04,657 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/22443
2025-10-27 12:38:04,671 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/cpsh/0002e2/r3tmp/kikimr_cluster_d8s8g9m6
2025-10-27 12:38:04,768 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydbd/ydbd
2025-10-27 12:38:04,768 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/28057
2025-10-27 12:38:04,769 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29611
2025-10-27 12:38:04,769 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20473
2025-10-27 12:38:04,774 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/1845
2025-10-27 12:38:04,842 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/kikimr_configs"
2025-10-27 12:38:04,843 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/cpsh/0002e2/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/cpsh/0002e2/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/node_1/logfile_4ta__26o.log --grpc-port=28057 --mon-port=29611 --ic-port=22443 --module=1
2025-10-27 12:38:04,843 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:28057/1, disk_size 68719476736
2025-10-27 12:38:04,843 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/kikimr_configs"
2025-10-27 12:38:04,844 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/cpsh/0002e2/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/cpsh/0002e2/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/node_1/logfile_4ta__26o.log --grpc-port=28057 --mon-port=29611 --ic-port=22443 --module=1
2025-10-27 12:38:04,845 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk3/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/cpsh/0002e2/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/node_1/logfile_4ta__26o.log', '--grpc-port=28057', '--mon-port=29611', '--ic-port=22443', '--module=1')' in '/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/node_1' (no wait)
2025-10-27 12:38:04,861 - DEBUG - ya.test - execute: Command pid: 3884255
2025-10-27 12:38:04,901 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:28057/1
2025-10-27 12:38:04,902 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.4
2025-10-27 12:38:04,903 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.4
2025-10-27 12:38:04,903 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-10-27 12:38:04,904 - DEBUG - ya.test - execute: Command pid: 3884308
2025-10-27 12:38:05,669 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) rc: 0
2025-10-27 12:38:05,672 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) elapsed time (sec): 0.7175502777099609
2025-10-27 12:38:05,675 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) idrss: 0
2025-10-27 12:38:05,675 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) inblock: 0
2025-10-27 12:38:05,675 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) isrss: 0
2025-10-27 12:38:05,675 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) ixrss: 0
2025-10-27 12:38:05,675 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) majflt: 0
2025-10-27 12:38:05,689 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) maxrss: 539764
2025-10-27 12:38:05,689 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) minflt: 16300
2025-10-27 12:38:05,689 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) msgrcv: 0
2025-10-27 12:38:05,690 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) msgsnd: 0
2025-10-27 12:38:05,690 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) nivcsw: 130
2025-10-27 12:38:05,690 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) nsignals: 0
2025-10-27 12:38:05,690 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) nswap: 0
2025-10-27 12:38:05,690 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) nvcsw: 13
2025-10-27 12:38:05,690 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) oublock: 8
2025-10-27 12:38:05,693 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) stime: 0.278138
2025-10-27 12:38:05,693 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) utime: 0.137955
2025-10-27 12:38:05,694 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) wtime: 0.719
2025-10-27 12:38:05,694 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) output:
Git info:
    Commit: 6d76348c7d05728a81789044c932883db81b8e8b
    Branch: 
    Author: Stanislav Yablonskiy <neyrox@ydb.tech>
    Summary: Merge 1ce5967b32d6870e99c17fd930730f80185016e0 into dee8772a087cf0572972e1fbbfa383c447444ec3

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

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

2025-10-27 12:38:05,694 - DEBUG - ya.test - _save_outputs: Command (pid 3884308) errors:

2025-10-27 12:38:05,694 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 6d76348c7d05728a81789044c932883db81b8e8b
    Branch: 
    Author: Stanislav Yablonskiy <neyrox@ydb.tech>
    Summary: Merge 1ce5967b32d6870e99c17fd930730f80185016e0 into dee8772a087cf0572972e1fbbfa383c447444ec3
2025-10-27 12:38:07,915 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydbd/ydbd
2025-10-27 12:38:07,916 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:28057', '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: 22443\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-10-27 12:38:07,957 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.5
2025-10-27 12:38:07,958 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.5
2025-10-27 12:38:07,958 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:28057', '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: 22443\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-10-27 12:38:07,960 - DEBUG - ya.test - execute: Command pid: 3885607
2025-10-27 12:38:08,642 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) rc: 0
2025-10-27 12:38:08,643 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) elapsed time (sec): 0.6774029731750488
2025-10-27 12:38:08,643 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) idrss: 0
2025-10-27 12:38:08,643 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) inblock: 0
2025-10-27 12:38:08,643 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) isrss: 0
2025-10-27 12:38:08,644 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) ixrss: 0
2025-10-27 12:38:08,644 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) majflt: 0
2025-10-27 12:38:08,644 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) maxrss: 548024
2025-10-27 12:38:08,644 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) minflt: 18263
2025-10-27 12:38:08,644 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) msgrcv: 0
2025-10-27 12:38:08,644 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) msgsnd: 0
2025-10-27 12:38:08,645 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) nivcsw: 142
2025-10-27 12:38:08,645 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) nsignals: 0
2025-10-27 12:38:08,645 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) nswap: 0
2025-10-27 12:38:08,645 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) nvcsw: 114
2025-10-27 12:38:08,645 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) oublock: 8
2025-10-27 12:38:08,645 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) stime: 0.316531
2025-10-27 12:38:08,646 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) utime: 0.11619499999999999
2025-10-27 12:38:08,646 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) wtime: 0.682
2025-10-27 12:38:08,646 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-10-27 12:38:08,646 - DEBUG - ya.test - _save_outputs: Command (pid 3885607) errors:

2025-10-27 12:38:08,654 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydbd/ydbd
2025-10-27 12:38:08,655 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:28057', '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-10-27 12:38:08,667 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.6
2025-10-27 12:38:08,670 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.6
2025-10-27 12:38:08,682 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydbd/ydbd', '--server=grpc://localhost:28057', '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-10-27 12:38:08,685 - DEBUG - ya.test - execute: Command pid: 3885904
2025-10-27 12:38:09,476 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) rc: 0
2025-10-27 12:38:09,477 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) elapsed time (sec): 0.7898280620574951
2025-10-27 12:38:09,477 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) idrss: 0
2025-10-27 12:38:09,483 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) inblock: 0
2025-10-27 12:38:09,499 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) isrss: 0
2025-10-27 12:38:09,500 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) ixrss: 0
2025-10-27 12:38:09,513 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) majflt: 0
2025-10-27 12:38:09,514 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) maxrss: 548024
2025-10-27 12:38:09,514 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) minflt: 18267
2025-10-27 12:38:09,514 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) msgrcv: 0
2025-10-27 12:38:09,519 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) msgsnd: 0
2025-10-27 12:38:09,521 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) nivcsw: 172
2025-10-27 12:38:09,521 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) nsignals: 0
2025-10-27 12:38:09,540 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) nswap: 0
2025-10-27 12:38:09,540 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) nvcsw: 103
2025-10-27 12:38:09,540 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) oublock: 8
2025-10-27 12:38:09,540 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) stime: 0.297286
2025-10-27 12:38:09,541 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) utime: 0.14666099999999999
2025-10-27 12:38:09,541 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) wtime: 0.793
2025-10-27 12:38:09,541 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-10-27 12:38:09,541 - DEBUG - ya.test - _save_outputs: Command (pid 3885904) errors:

2025-10-27 12:38:09,542 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-10-27 12:38:09,557 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-10-27 12:38:09,583 - 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-10-27 12:38:09,588 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-27 12:38:09,591 - 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-10-27 12:38:09,601 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-27 12:38:09,602 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, df9d3d6e-197f-43a9-b928-4901fd2a0a15, localhost:28057): creating call state
2025-10-27 12:38:09,602 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, df9d3d6e-197f-43a9-b928-4901fd2a0a15, localhost:28057): request = { database: "/Root" }
2025-10-27 12:38:09,607 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, df9d3d6e-197f-43a9-b928-4901fd2a0a15, localhost:28057): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-5rmqn7spre.auto.internal" port: 28057 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-10-27 12:38:09,608 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-5rmqn7spre.auto.internal:28057, location 1, ssl: False>]>
2025-10-27 12:38:09,608 - 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-10-27 12:38:09,622 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_deviation[1051200])
2025-10-27 12:38:09,631 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb
2025-10-27 12:38:09,635 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb -e grpc://localhost:28057 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-10-27 12:38:09,636 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-10-27 12:38:09,637 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-10-27 12:38:09,638 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:28057', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-10-27 12:38:09,652 - DEBUG - ya.test - execute: Command pid: 3886156
2025-10-27 12:38:10,460 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) rc: 0
2025-10-27 12:38:10,461 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) elapsed time (sec): 0.7867031097412109
2025-10-27 12:38:10,475 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) idrss: 0
2025-10-27 12:38:10,476 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) inblock: 0
2025-10-27 12:38:10,480 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) isrss: 0
2025-10-27 12:38:10,480 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) ixrss: 0
2025-10-27 12:38:10,480 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) majflt: 0
2025-10-27 12:38:10,480 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) maxrss: 174668
2025-10-27 12:38:10,480 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) minflt: 3494
2025-10-27 12:38:10,481 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) msgrcv: 0
2025-10-27 12:38:10,481 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) msgsnd: 0
2025-10-27 12:38:10,481 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) nivcsw: 63
2025-10-27 12:38:10,481 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) nsignals: 0
2025-10-27 12:38:10,482 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) nswap: 0
2025-10-27 12:38:10,483 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) nvcsw: 144
2025-10-27 12:38:10,483 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) oublock: 8
2025-10-27 12:38:10,483 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) stime: 0.018899
2025-10-27 12:38:10,483 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) utime: 0.068037
2025-10-27 12:38:10,483 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) wtime: 0.822
2025-10-27 12:38:10,484 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) output:
Init tables ...
Init tables ...Ok

2025-10-27 12:38:10,484 - DEBUG - ya.test - _save_outputs: Command (pid 3886156) errors:

2025-10-27 12:38:10,484 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-10-27 12:38:10,485 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, ef2ceb34-b059-4972-9fd8-73f49b0e7c7b, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:10,492 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, ef2ceb34-b059-4972-9fd8-73f49b0e7c7b, ghrun-5rmqn7spre.auto.internal:28057): request = {  }
2025-10-27 12:38:10,495 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, ef2ceb34-b059-4972-9fd8-73f49b0e7c7b, ghrun-5rmqn7spre.auto.internal:28057): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=" node_id: 1 }
2025-10-27 12:38:10,495 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, af76610c-f6bb-4c0c-8f61-a98a6f5ea752, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:10,496 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, af76610c-f6bb-4c0c-8f61-a98a6f5ea752, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=" }
2025-10-27 12:38:10,497 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, af76610c-f6bb-4c0c-8f61-a98a6f5ea752, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:10,520 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:10,521 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 412bb9b8-09ed-4b0a-aace-4caa7c560bb2, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:10,529 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 412bb9b8-09ed-4b0a-aace-4caa7c560bb2, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=" 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-10-27 12:38:10,532 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 412bb9b8-09ed-4b0a-aace-4caa7c560bb2, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:10,590 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:10,602 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-10-27 12:38:10,610 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb -e grpc://localhost:28057 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 1051200 with wait = True
2025-10-27 12:38:10,633 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-10-27 12:38:10,665 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-10-27 12:38:10,692 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:28057', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '1051200']' in 'None' (waiting)
2025-10-27 12:38:10,714 - DEBUG - ya.test - execute: Command pid: 3886651
2025-10-27 12:38:15,551 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-27 12:38:15,551 - DEBUG - ya.test - _save_outputs: Command (pid 3886651) rc: 0
2025-10-27 12:38:15,552 - DEBUG - ya.test - _save_outputs: Command (pid 3886651) elapsed time (sec): 4.82974910736084
2025-10-27 12:38:15,552 - DEBUG - ya.test - _save_outputs: Command (pid 3886651) wtime: 4.85
2025-10-27 12:38:15,552 - DEBUG - ya.test - _save_outputs: Command (pid 3886651) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	      0	0	0	0	0	0	0	0
3	     10	10	0	0	4287	4511	4511	4511

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

2025-10-27 12:38:15,553 - DEBUG - ya.test - _save_outputs: Command (pid 3886651) errors:

2025-10-27 12:38:15,554 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:15,558 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b5dff3a6-c203-459e-97e2-5fd8716b662e, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:15,559 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b5dff3a6-c203-459e-97e2-5fd8716b662e, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:15,561 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b5dff3a6-c203-459e-97e2-5fd8716b662e, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:16,069 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:16,076 - INFO - root - test_log_deviation: Count rows after insert 5000 before wait
2025-10-27 12:38:16,077 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:16,077 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b4a670c9-beeb-4832-b50a-0eba918fd74c, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:16,078 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b4a670c9-beeb-4832-b50a-0eba918fd74c, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:16,084 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b4a670c9-beeb-4832-b50a-0eba918fd74c, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:16,198 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:16,199 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:16,199 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 86dc5b00-1f57-4b33-a172-ca3d5cec33e9, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:16,200 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 86dc5b00-1f57-4b33-a172-ca3d5cec33e9, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:16,200 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 86dc5b00-1f57-4b33-a172-ca3d5cec33e9, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:16,330 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:16,344 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-10-27 12:38:16,350 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb -e grpc://localhost:28057 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 1051200 with wait = True
2025-10-27 12:38:16,359 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-10-27 12:38:16,359 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-10-27 12:38:16,360 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:28057', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '1051200']' in 'None' (waiting)
2025-10-27 12:38:16,351 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-10-27 12:38:16,357 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:16,357 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-10-27 12:38:16,387 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb -e grpc://localhost:28057 -d /Root workload log --path log run upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 1051200 with wait = True
2025-10-27 12:38:16,386 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb -e grpc://localhost:28057 -d /Root workload log --path log run insert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 1051200 with wait = True
2025-10-27 12:38:16,386 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 783a1d8c-0cf1-4807-bfd4-2cda3ac62cb1, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:16,395 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 783a1d8c-0cf1-4807-bfd4-2cda3ac62cb1, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:16,389 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-10-27 12:38:16,410 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 2e50cacc-0ef7-48ef-8e7a-efb73ff38d25, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:16,370 - DEBUG - ya.test - execute: Command pid: 3888745
2025-10-27 12:38:16,396 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.3
2025-10-27 12:38:16,403 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 783a1d8c-0cf1-4807-bfd4-2cda3ac62cb1, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:16,415 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 2e50cacc-0ef7-48ef-8e7a-efb73ff38d25, ghrun-5rmqn7spre.auto.internal:28057): request = {  }
2025-10-27 12:38:16,391 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.2
2025-10-27 12:38:16,420 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-10-27 12:38:16,437 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-10-27 12:38:16,442 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 2e50cacc-0ef7-48ef-8e7a-efb73ff38d25, ghrun-5rmqn7spre.auto.internal:28057): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=" node_id: 1 }
2025-10-27 12:38:16,446 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:28057', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '1051200']' in 'None' (waiting)
2025-10-27 12:38:16,446 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:28057', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'insert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '1051200']' in 'None' (waiting)
2025-10-27 12:38:16,458 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 1345aa9e-6c9e-412d-bbd7-606b5127fc97, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:16,462 - DEBUG - ya.test - execute: Command pid: 3888769
2025-10-27 12:38:16,465 - DEBUG - ya.test - execute: Command pid: 3888772
2025-10-27 12:38:16,469 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 1345aa9e-6c9e-412d-bbd7-606b5127fc97, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=" }
2025-10-27 12:38:16,485 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 1345aa9e-6c9e-412d-bbd7-606b5127fc97, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:16,497 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:16,521 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 88ad10b6-d0c9-4b55-b57b-08d5926f76a0, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:16,522 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 88ad10b6-d0c9-4b55-b57b-08d5926f76a0, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:16,523 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 2 < 100, will create new one.
2025-10-27 12:38:16,531 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 525ff10d-4133-4a14-96e1-c55cf96eec92, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:16,531 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 525ff10d-4133-4a14-96e1-c55cf96eec92, ghrun-5rmqn7spre.auto.internal:28057): request = {  }
2025-10-27 12:38:16,524 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 88ad10b6-d0c9-4b55-b57b-08d5926f76a0, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:16,583 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 525ff10d-4133-4a14-96e1-c55cf96eec92, ghrun-5rmqn7spre.auto.internal:28057): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=" node_id: 1 }
2025-10-27 12:38:16,592 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 9c7f9243-fc09-4158-a3a0-7a09e8fb1f94, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:16,593 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 9c7f9243-fc09-4158-a3a0-7a09e8fb1f94, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=" }
2025-10-27 12:38:16,594 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 9c7f9243-fc09-4158-a3a0-7a09e8fb1f94, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:16,617 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:38:16,618 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f251b06f-c562-43b8-851f-1f0006c84187, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:16,618 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f251b06f-c562-43b8-851f-1f0006c84187, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:16,619 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f251b06f-c562-43b8-851f-1f0006c84187, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:16,620 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 3 < 100, will create new one.
2025-10-27 12:38:16,628 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 05b567d4-5581-476f-96ee-e52236b964a7, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:16,629 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 05b567d4-5581-476f-96ee-e52236b964a7, ghrun-5rmqn7spre.auto.internal:28057): request = {  }
2025-10-27 12:38:16,676 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 05b567d4-5581-476f-96ee-e52236b964a7, ghrun-5rmqn7spre.auto.internal:28057): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==" node_id: 1 }
2025-10-27 12:38:16,698 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 0fc08af9-793d-4d1d-af65-5f8a6b32150e, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:16,699 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 0fc08af9-793d-4d1d-af65-5f8a6b32150e, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==" }
2025-10-27 12:38:16,700 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 0fc08af9-793d-4d1d-af65-5f8a6b32150e, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:16,839 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==
2025-10-27 12:38:16,858 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3a95f55a-b005-4f0e-ae3c-5a482db533b6, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:16,859 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3a95f55a-b005-4f0e-ae3c-5a482db533b6, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:16,860 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3a95f55a-b005-4f0e-ae3c-5a482db533b6, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:16,861 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 4 < 100, will create new one.
2025-10-27 12:38:16,861 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 20da626d-bbe1-4bf1-8153-6f157808affa, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:16,862 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 20da626d-bbe1-4bf1-8153-6f157808affa, ghrun-5rmqn7spre.auto.internal:28057): request = {  }
2025-10-27 12:38:16,902 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 20da626d-bbe1-4bf1-8153-6f157808affa, ghrun-5rmqn7spre.auto.internal:28057): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=" node_id: 1 }
2025-10-27 12:38:16,902 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, d2274edb-e7df-40c8-94d7-1393bf3df99f, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:16,903 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, d2274edb-e7df-40c8-94d7-1393bf3df99f, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=" }
2025-10-27 12:38:16,907 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, d2274edb-e7df-40c8-94d7-1393bf3df99f, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:16,967 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:38:16,968 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eb95cee9-3bfd-4eae-9db9-0022df95f915, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:16,974 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eb95cee9-3bfd-4eae-9db9-0022df95f915, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:16,976 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 5 < 100, will create new one.
2025-10-27 12:38:16,979 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eb95cee9-3bfd-4eae-9db9-0022df95f915, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:16,983 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 45acfd7e-fa38-46f0-a496-bc41efb630d8, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:17,007 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 45acfd7e-fa38-46f0-a496-bc41efb630d8, ghrun-5rmqn7spre.auto.internal:28057): request = {  }
2025-10-27 12:38:17,198 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 45acfd7e-fa38-46f0-a496-bc41efb630d8, ghrun-5rmqn7spre.auto.internal:28057): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=" node_id: 1 }
2025-10-27 12:38:17,198 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, b0eda592-6ac2-4206-9f15-ace7f57352a4, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:17,209 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, b0eda592-6ac2-4206-9f15-ace7f57352a4, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=" }
2025-10-27 12:38:17,210 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, b0eda592-6ac2-4206-9f15-ace7f57352a4, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:18,368 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=
2025-10-27 12:38:18,379 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a7dae382-40c8-4bf6-a708-e5308c66436d, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:18,380 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a7dae382-40c8-4bf6-a708-e5308c66436d, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:18,381 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a7dae382-40c8-4bf6-a708-e5308c66436d, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:18,379 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 6 < 100, will create new one.
2025-10-27 12:38:18,386 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 22eac28b-3e8d-410f-b0c9-cf3247431338, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:18,394 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 22eac28b-3e8d-410f-b0c9-cf3247431338, ghrun-5rmqn7spre.auto.internal:28057): request = {  }
2025-10-27 12:38:18,849 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 22eac28b-3e8d-410f-b0c9-cf3247431338, ghrun-5rmqn7spre.auto.internal:28057): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=" node_id: 1 }
2025-10-27 12:38:18,873 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, a49cc573-b3e8-47e7-ab9e-976276f0d408, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:18,889 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, a49cc573-b3e8-47e7-ab9e-976276f0d408, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=" }
2025-10-27 12:38:18,906 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, a49cc573-b3e8-47e7-ab9e-976276f0d408, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:18,856 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:18,922 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:19,073 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:19,074 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 62ed8764-031b-4407-9797-95f401409f7a, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:19,075 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 62ed8764-031b-4407-9797-95f401409f7a, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:19,076 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 62ed8764-031b-4407-9797-95f401409f7a, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:19,076 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:19,078 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7e222dcb-f400-4d6f-979f-e69cdf36815d, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:19,079 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7e222dcb-f400-4d6f-979f-e69cdf36815d, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:19,079 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:19,081 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7e222dcb-f400-4d6f-979f-e69cdf36815d, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:19,085 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, eedfc616-08d1-4d5c-9dd9-9a16ba5ff9c8, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:19,085 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 7 < 100, will create new one.
2025-10-27 12:38:19,089 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 46244eee-318d-4425-b190-f9b5090d99e1, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:19,090 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 46244eee-318d-4425-b190-f9b5090d99e1, ghrun-5rmqn7spre.auto.internal:28057): request = {  }
2025-10-27 12:38:19,088 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, eedfc616-08d1-4d5c-9dd9-9a16ba5ff9c8, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:19,093 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, eedfc616-08d1-4d5c-9dd9-9a16ba5ff9c8, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:19,339 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 46244eee-318d-4425-b190-f9b5090d99e1, ghrun-5rmqn7spre.auto.internal:28057): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==" node_id: 1 }
2025-10-27 12:38:19,340 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 5ab78d9c-9514-405c-8790-6e6ae94982ee, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:19,341 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 5ab78d9c-9514-405c-8790-6e6ae94982ee, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==" }
2025-10-27 12:38:19,349 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 5ab78d9c-9514-405c-8790-6e6ae94982ee, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:19,485 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==
2025-10-27 12:38:19,486 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 629c4622-530a-4b09-9adc-686cb0b58da3, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:19,487 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 629c4622-530a-4b09-9adc-686cb0b58da3, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:19,488 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 629c4622-530a-4b09-9adc-686cb0b58da3, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:19,488 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 8 < 100, will create new one.
2025-10-27 12:38:19,494 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 87e1ae2b-7020-4fed-85b5-d58b14103688, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:19,499 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 87e1ae2b-7020-4fed-85b5-d58b14103688, ghrun-5rmqn7spre.auto.internal:28057): request = {  }
2025-10-27 12:38:19,658 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 87e1ae2b-7020-4fed-85b5-d58b14103688, ghrun-5rmqn7spre.auto.internal:28057): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=" node_id: 1 }
2025-10-27 12:38:19,658 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 6dcddb07-8ce1-4b86-acc7-527cf1b1bb3b, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:19,664 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 6dcddb07-8ce1-4b86-acc7-527cf1b1bb3b, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=" }
2025-10-27 12:38:19,674 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 6dcddb07-8ce1-4b86-acc7-527cf1b1bb3b, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:20,317 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-27 12:38:20,318 - DEBUG - ya.test - _save_outputs: Command (pid 3888772) rc: 0
2025-10-27 12:38:20,331 - DEBUG - ya.test - _save_outputs: Command (pid 3888772) elapsed time (sec): 3.834059953689575
2025-10-27 12:38:20,331 - DEBUG - ya.test - _save_outputs: Command (pid 3888772) wtime: 3.854
2025-10-27 12:38:20,336 - DEBUG - ya.test - _save_outputs: Command (pid 3888772) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	      0	0	0	10	0	0	0	0
3	      0	0	0	10	0	0	0	0

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

2025-10-27 12:38:20,342 - DEBUG - ya.test - _save_outputs: Command (pid 3888772) errors:

2025-10-27 12:38:20,404 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:38:20,405 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 03b857a0-d939-4c5a-ab79-2b73d61abea8, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:20,405 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 03b857a0-d939-4c5a-ab79-2b73d61abea8, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=" 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-10-27 12:38:20,406 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 03b857a0-d939-4c5a-ab79-2b73d61abea8, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:20,407 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 9 < 100, will create new one.
2025-10-27 12:38:20,407 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 68a08222-3232-49a3-a2ec-cfb5b7921088, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:20,408 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 68a08222-3232-49a3-a2ec-cfb5b7921088, ghrun-5rmqn7spre.auto.internal:28057): request = {  }
2025-10-27 12:38:20,537 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-27 12:38:20,538 - DEBUG - ya.test - _save_outputs: Command (pid 3888769) rc: 0
2025-10-27 12:38:20,538 - DEBUG - ya.test - _save_outputs: Command (pid 3888769) elapsed time (sec): 4.054208517074585
2025-10-27 12:38:20,538 - DEBUG - ya.test - _save_outputs: Command (pid 3888769) wtime: 4.077
2025-10-27 12:38:20,538 - DEBUG - ya.test - _save_outputs: Command (pid 3888769) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	      0	0	0	10	0	0	0	0
3	      0	0	0	10	0	0	0	0

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

2025-10-27 12:38:20,539 - DEBUG - ya.test - _save_outputs: Command (pid 3888769) errors:

2025-10-27 12:38:20,937 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 68a08222-3232-49a3-a2ec-cfb5b7921088, ghrun-5rmqn7spre.auto.internal:28057): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=" node_id: 1 }
2025-10-27 12:38:20,962 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, aee6acdd-e3e4-4abd-ad9f-2fa9ba1c536c, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:20,994 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, aee6acdd-e3e4-4abd-ad9f-2fa9ba1c536c, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=" }
2025-10-27 12:38:20,995 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, aee6acdd-e3e4-4abd-ad9f-2fa9ba1c536c, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:21,100 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=
2025-10-27 12:38:21,101 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fcb0bc9e-9bbf-4815-a45b-9eca3c1e2a2c, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:21,102 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fcb0bc9e-9bbf-4815-a45b-9eca3c1e2a2c, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:21,110 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fcb0bc9e-9bbf-4815-a45b-9eca3c1e2a2c, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:22,330 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:38:22,331 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:38:22,331 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, afe60a15-f92c-485a-aa30-8b1d1667ad59, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:22,336 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, afe60a15-f92c-485a-aa30-8b1d1667ad59, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:22,337 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, afe60a15-f92c-485a-aa30-8b1d1667ad59, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:22,876 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==
2025-10-27 12:38:22,889 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==
2025-10-27 12:38:22,917 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 01e091a3-a311-43e5-b54d-1ffa820abf56, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:22,918 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 01e091a3-a311-43e5-b54d-1ffa820abf56, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(5)" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:22,919 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 01e091a3-a311-43e5-b54d-1ffa820abf56, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:23,673 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:38:23,674 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:38:23,674 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e5a87463-82fc-44e8-bd5d-28035f553bf9, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:23,676 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e5a87463-82fc-44e8-bd5d-28035f553bf9, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=" 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-10-27 12:38:23,677 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e5a87463-82fc-44e8-bd5d-28035f553bf9, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:24,163 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:24,166 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:24,166 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c16602b9-35a7-4249-8981-53f57b2302c4, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:24,167 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c16602b9-35a7-4249-8981-53f57b2302c4, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=" 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-10-27 12:38:24,164 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=
2025-10-27 12:38:24,174 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=
2025-10-27 12:38:24,174 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 86d5bd8f-cf3d-4b78-8eaf-117371e8bc96, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:24,175 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 86d5bd8f-cf3d-4b78-8eaf-117371e8bc96, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=" 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-10-27 12:38:24,176 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 86d5bd8f-cf3d-4b78-8eaf-117371e8bc96, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:24,170 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c16602b9-35a7-4249-8981-53f57b2302c4, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:24,181 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:24,196 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:24,198 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2ea18855-2e82-44f7-ad16-ed0e8d298fe6, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:24,210 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2ea18855-2e82-44f7-ad16-ed0e8d298fe6, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:24,211 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2ea18855-2e82-44f7-ad16-ed0e8d298fe6, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:24,277 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==
2025-10-27 12:38:24,278 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==
2025-10-27 12:38:24,278 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6bd3e499-1740-4b2d-9a27-b50c75866b7d, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:24,279 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6bd3e499-1740-4b2d-9a27-b50c75866b7d, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:24,285 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6bd3e499-1740-4b2d-9a27-b50c75866b7d, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:24,287 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:24,288 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:24,291 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e5233770-4ce4-450a-8bdb-c4c1c48b4c9b, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:24,295 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e5233770-4ce4-450a-8bdb-c4c1c48b4c9b, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=" 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-10-27 12:38:24,298 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e5233770-4ce4-450a-8bdb-c4c1c48b4c9b, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:27,597 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:38:27,680 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:38:27,731 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d7fb5a3e-e02e-41e3-b3a1-61711da73004, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:27,750 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d7fb5a3e-e02e-41e3-b3a1-61711da73004, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=" 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-10-27 12:38:27,751 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d7fb5a3e-e02e-41e3-b3a1-61711da73004, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:28,567 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=
2025-10-27 12:38:28,592 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=
2025-10-27 12:38:28,593 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0b4124b3-1c47-49a0-84f0-ed8e0a4f91af, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:28,595 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0b4124b3-1c47-49a0-84f0-ed8e0a4f91af, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:28,597 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0b4124b3-1c47-49a0-84f0-ed8e0a4f91af, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:28,593 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:38:28,621 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:38:28,682 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7618b59f-b04c-41fb-82db-e6c45a4065d4, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:28,757 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7618b59f-b04c-41fb-82db-e6c45a4065d4, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:28,762 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7618b59f-b04c-41fb-82db-e6c45a4065d4, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:28,875 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:38:28,952 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:38:28,952 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9cee973b-13c1-4351-8077-813dc923c5b9, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:28,953 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9cee973b-13c1-4351-8077-813dc923c5b9, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=" 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-10-27 12:38:28,955 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9cee973b-13c1-4351-8077-813dc923c5b9, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:29,557 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==
2025-10-27 12:38:29,587 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==
2025-10-27 12:38:29,588 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6a17fef7-0dcc-48ba-8437-51974712f301, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:29,589 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6a17fef7-0dcc-48ba-8437-51974712f301, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(5)" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:29,601 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6a17fef7-0dcc-48ba-8437-51974712f301, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:29,766 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:29,788 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:29,790 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f2641b29-eb51-4509-96fc-e6ade028974d, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:29,791 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f2641b29-eb51-4509-96fc-e6ade028974d, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:29,792 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f2641b29-eb51-4509-96fc-e6ade028974d, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:30,206 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=
2025-10-27 12:38:30,226 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=
2025-10-27 12:38:30,228 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0d8d4c29-9148-4494-9de2-774b05d2a34f, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:30,241 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0d8d4c29-9148-4494-9de2-774b05d2a34f, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=" 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-10-27 12:38:30,258 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:30,304 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0d8d4c29-9148-4494-9de2-774b05d2a34f, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:30,311 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:30,312 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 80c66d9d-280e-4e08-834a-79b9c312bcc6, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:30,319 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 80c66d9d-280e-4e08-834a-79b9c312bcc6, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=" 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-10-27 12:38:30,334 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 80c66d9d-280e-4e08-834a-79b9c312bcc6, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:30,685 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==
2025-10-27 12:38:30,719 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==
2025-10-27 12:38:30,721 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 848d7788-6f6e-4e8a-a9d5-aa1489f6e464, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:30,722 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 848d7788-6f6e-4e8a-a9d5-aa1489f6e464, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:30,723 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 848d7788-6f6e-4e8a-a9d5-aa1489f6e464, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:30,724 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:30,813 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:30,821 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 497cd683-657b-45d9-afb3-2bddbdf0c5c8, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:30,849 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 497cd683-657b-45d9-afb3-2bddbdf0c5c8, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=" 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-10-27 12:38:30,862 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 497cd683-657b-45d9-afb3-2bddbdf0c5c8, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:31,898 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:38:31,899 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:38:31,899 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 31e165e6-beac-449f-ae0b-0218961ffd4f, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:31,900 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 31e165e6-beac-449f-ae0b-0218961ffd4f, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=" 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-10-27 12:38:31,906 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 31e165e6-beac-449f-ae0b-0218961ffd4f, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:31,910 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:38:31,919 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:38:31,920 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 29d4533f-d92a-41d6-8b2c-5b9127c623fe, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:31,920 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 29d4533f-d92a-41d6-8b2c-5b9127c623fe, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=" 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-10-27 12:38:31,921 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 29d4533f-d92a-41d6-8b2c-5b9127c623fe, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:32,077 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=
2025-10-27 12:38:32,078 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=
2025-10-27 12:38:32,078 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 44cb34a9-a9b9-465b-af2d-1b57062caf96, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:32,079 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 44cb34a9-a9b9-465b-af2d-1b57062caf96, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3))" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:32,088 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:32,093 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:32,089 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:38:32,096 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 70fd4aba-c4dc-4443-9f6b-565d775bce10, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:32,088 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 44cb34a9-a9b9-465b-af2d-1b57062caf96, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:32,097 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:38:32,107 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8c786e9d-8d0f-4542-88e4-d34c01b26d56, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:32,108 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8c786e9d-8d0f-4542-88e4-d34c01b26d56, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3))" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:32,109 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8c786e9d-8d0f-4542-88e4-d34c01b26d56, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:32,097 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 70fd4aba-c4dc-4443-9f6b-565d775bce10, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3))" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:32,131 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 70fd4aba-c4dc-4443-9f6b-565d775bce10, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:32,332 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==
2025-10-27 12:38:32,333 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==
2025-10-27 12:38:32,341 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 617b0529-0a9f-4565-b1ea-cdbcca0c557f, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:32,342 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 617b0529-0a9f-4565-b1ea-cdbcca0c557f, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(5))" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:32,343 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 617b0529-0a9f-4565-b1ea-cdbcca0c557f, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:32,386 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=
2025-10-27 12:38:32,392 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=
2025-10-27 12:38:32,393 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c0e50cd7-a68d-47a5-a5bb-aafdb94bf896, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:32,393 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c0e50cd7-a68d-47a5-a5bb-aafdb94bf896, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=" 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-10-27 12:38:32,394 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c0e50cd7-a68d-47a5-a5bb-aafdb94bf896, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:32,398 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:32,415 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:32,420 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f6a4f787-f887-4b0c-8b66-83704fc4d376, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:32,425 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f6a4f787-f887-4b0c-8b66-83704fc4d376, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=" 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-10-27 12:38:32,429 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f6a4f787-f887-4b0c-8b66-83704fc4d376, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:32,462 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:32,462 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:32,463 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 04da5fd6-49ec-4c76-9ff9-3186542363c0, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:32,463 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 04da5fd6-49ec-4c76-9ff9-3186542363c0, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=" 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-10-27 12:38:32,464 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 04da5fd6-49ec-4c76-9ff9-3186542363c0, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:32,535 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==
2025-10-27 12:38:32,538 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==
2025-10-27 12:38:32,538 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1b5977b5-9ad6-49ca-93a5-7c8dadd6099a, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:32,539 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1b5977b5-9ad6-49ca-93a5-7c8dadd6099a, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(6))" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:32,542 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1b5977b5-9ad6-49ca-93a5-7c8dadd6099a, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:32,754 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:38:32,775 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:38:32,869 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:38:32,878 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=
2025-10-27 12:38:32,896 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:33,142 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==
2025-10-27 12:38:33,161 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:33,170 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=
2025-10-27 12:38:33,187 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:33,202 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==
2025-10-27 12:38:33,984 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-27 12:38:33,985 - DEBUG - ya.test - _save_outputs: Command (pid 3888745) rc: 0
2025-10-27 12:38:33,985 - DEBUG - ya.test - _save_outputs: Command (pid 3888745) elapsed time (sec): 17.565155744552612
2025-10-27 12:38:33,985 - DEBUG - ya.test - _save_outputs: Command (pid 3888745) wtime: 17.625
2025-10-27 12:38:33,986 - DEBUG - ya.test - _save_outputs: Command (pid 3888745) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	      0	0	0	0	0	0	0	0
3	     10	10	0	0	17407	17407	17407	17407

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

2025-10-27 12:38:33,986 - DEBUG - ya.test - _save_outputs: Command (pid 3888745) errors:

2025-10-27 12:38:33,986 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:38:33,987 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e610bd4d-bc5b-449a-a544-5a5c864ecb2c, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:33,987 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e610bd4d-bc5b-449a-a544-5a5c864ecb2c, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:33,988 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e610bd4d-bc5b-449a-a544-5a5c864ecb2c, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:34,142 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:38:34,142 - INFO - root - test_log_deviation: check insert: 10000 5000
2025-10-27 12:38:34,151 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.4
2025-10-27 12:38:34,152 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-10-27 12:38:34,152 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:28057', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-10-27 12:38:34,153 - DEBUG - ya.test - execute: Command pid: 3896711
2025-10-27 12:38:34,390 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) rc: 0
2025-10-27 12:38:34,390 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) elapsed time (sec): 0.23528766632080078
2025-10-27 12:38:34,390 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) idrss: 0
2025-10-27 12:38:34,391 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) inblock: 0
2025-10-27 12:38:34,391 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) isrss: 0
2025-10-27 12:38:34,391 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) ixrss: 0
2025-10-27 12:38:34,391 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) majflt: 0
2025-10-27 12:38:34,391 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) maxrss: 230720
2025-10-27 12:38:34,392 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) minflt: 3502
2025-10-27 12:38:34,393 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) msgrcv: 0
2025-10-27 12:38:34,393 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) msgsnd: 0
2025-10-27 12:38:34,394 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) nivcsw: 34
2025-10-27 12:38:34,394 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) nsignals: 0
2025-10-27 12:38:34,395 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) nswap: 0
2025-10-27 12:38:34,396 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) nvcsw: 160
2025-10-27 12:38:34,396 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) oublock: 8
2025-10-27 12:38:34,396 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) stime: 0.051119
2025-10-27 12:38:34,396 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) utime: 0.034079
2025-10-27 12:38:34,397 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) wtime: 0.237
2025-10-27 12:38:34,397 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) output:
Remove path log...
Remove path log...Ok

2025-10-27 12:38:34,397 - DEBUG - ya.test - _save_outputs: Command (pid 3896711) errors:

2025-10-27 12:38:34,399 - INFO - ya.test - pytest_runtest_teardown: Test teardown
