2025-12-12 17:18:32,057 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-12 17:18:32,058 - INFO - ya.test - pytest_runtest_setup: test_log_deviation[1051200]
2025-12-12 17:18:32,058 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-12 17:18:32,058 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-12 17:18:32,066 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.3
2025-12-12 17:18:32,067 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.3
2025-12-12 17:18:32,067 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-12 17:18:32,070 - DEBUG - ya.test - execute: Command pid: 193003
2025-12-12 17:18:32,510 - DEBUG - ya.test - _save_outputs: Command (pid 193003) rc: 0
2025-12-12 17:18:32,511 - DEBUG - ya.test - _save_outputs: Command (pid 193003) elapsed time (sec): 0.4371926784515381
2025-12-12 17:18:32,511 - DEBUG - ya.test - _save_outputs: Command (pid 193003) idrss: 0
2025-12-12 17:18:32,511 - DEBUG - ya.test - _save_outputs: Command (pid 193003) inblock: 16
2025-12-12 17:18:32,511 - DEBUG - ya.test - _save_outputs: Command (pid 193003) isrss: 0
2025-12-12 17:18:32,511 - DEBUG - ya.test - _save_outputs: Command (pid 193003) ixrss: 0
2025-12-12 17:18:32,511 - DEBUG - ya.test - _save_outputs: Command (pid 193003) majflt: 0
2025-12-12 17:18:32,512 - DEBUG - ya.test - _save_outputs: Command (pid 193003) maxrss: 544184
2025-12-12 17:18:32,512 - DEBUG - ya.test - _save_outputs: Command (pid 193003) minflt: 16232
2025-12-12 17:18:32,512 - DEBUG - ya.test - _save_outputs: Command (pid 193003) msgrcv: 0
2025-12-12 17:18:32,512 - DEBUG - ya.test - _save_outputs: Command (pid 193003) msgsnd: 0
2025-12-12 17:18:32,512 - DEBUG - ya.test - _save_outputs: Command (pid 193003) nivcsw: 107
2025-12-12 17:18:32,513 - DEBUG - ya.test - _save_outputs: Command (pid 193003) nsignals: 0
2025-12-12 17:18:32,513 - DEBUG - ya.test - _save_outputs: Command (pid 193003) nswap: 0
2025-12-12 17:18:32,513 - DEBUG - ya.test - _save_outputs: Command (pid 193003) nvcsw: 15
2025-12-12 17:18:32,513 - DEBUG - ya.test - _save_outputs: Command (pid 193003) oublock: 8
2025-12-12 17:18:32,513 - DEBUG - ya.test - _save_outputs: Command (pid 193003) stime: 0.197819
2025-12-12 17:18:32,513 - DEBUG - ya.test - _save_outputs: Command (pid 193003) utime: 0.122986
2025-12-12 17:18:32,513 - DEBUG - ya.test - _save_outputs: Command (pid 193003) wtime: 0.44
2025-12-12 17:18:32,514 - DEBUG - ya.test - _save_outputs: Command (pid 193003) output:
Git info:
    Commit: 1e60e344b55a9fb8c31a7f05c1806fa018d14d5e
    Branch: 
    Author: YDBot <ydbot@ydb.tech>
    Summary: Merge 13d20d320b4adfcaad71c0ad68bd89bf5e1f00a5 into 70510f510f754318d6ece3c98b4b57c0e4dbe999

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

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

2025-12-12 17:18:32,515 - DEBUG - ya.test - _save_outputs: Command (pid 193003) errors:

2025-12-12 17:18:32,515 - INFO - __tests__.test_log_scenario - _setup_ydb: Git info:
    Commit: 1e60e344b55a9fb8c31a7f05c1806fa018d14d5e
    Branch: 
    Author: YDBot <ydbot@ydb.tech>
    Summary: Merge 13d20d320b4adfcaad71c0ad68bd89bf5e1f00a5 into 70510f510f754318d6ece3c98b4b57c0e4dbe999

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

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

2025-12-12 17:18:32,654 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydbd/ydbd
2025-12-12 17:18:32,657 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3448
2025-12-12 17:18:32,658 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/cfdu/002020/r3tmp/kikimr_cluster_8nl0kuz1
2025-12-12 17:18:32,732 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydbd/ydbd
2025-12-12 17:18:32,733 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/8320
2025-12-12 17:18:32,733 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/20961
2025-12-12 17:18:32,734 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/13750
2025-12-12 17:18:32,734 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/3962
2025-12-12 17:18:32,735 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/cfdu/002020/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/kikimr_configs"
2025-12-12 17:18:32,740 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/cfdu/002020/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/cfdu/002020/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/node_1/logfile_qdligwhg.log --grpc-port=8320 --mon-port=20961 --ic-port=3448
2025-12-12 17:18:32,740 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:8320/1, disk_size 68719476736
2025-12-12 17:18:32,741 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/cfdu/002020/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/kikimr_configs"
2025-12-12 17:18:32,741 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/cfdu/002020/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/cfdu/002020/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/node_1/logfile_qdligwhg.log --grpc-port=8320 --mon-port=20961 --ic-port=3448
2025-12-12 17:18:32,742 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/cfdu/002020/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/cfdu/002020/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/node_1/logfile_qdligwhg.log', '--grpc-port=8320', '--mon-port=20961', '--ic-port=3448')' in '/home/runner/.ya/build/build_root/cfdu/002020/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk2/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_deviation.1051200/cluster/node_1' (no wait)
2025-12-12 17:18:32,755 - DEBUG - ya.test - execute: Command pid: 193415
2025-12-12 17:18:32,756 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:8320/1
2025-12-12 17:18:32,757 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.4
2025-12-12 17:18:32,757 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.4
2025-12-12 17:18:32,758 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-12 17:18:32,762 - DEBUG - ya.test - execute: Command pid: 193444
2025-12-12 17:18:33,440 - DEBUG - ya.test - _save_outputs: Command (pid 193444) rc: 0
2025-12-12 17:18:33,440 - DEBUG - ya.test - _save_outputs: Command (pid 193444) elapsed time (sec): 0.673255205154419
2025-12-12 17:18:33,440 - DEBUG - ya.test - _save_outputs: Command (pid 193444) idrss: 0
2025-12-12 17:18:33,440 - DEBUG - ya.test - _save_outputs: Command (pid 193444) inblock: 0
2025-12-12 17:18:33,441 - DEBUG - ya.test - _save_outputs: Command (pid 193444) isrss: 0
2025-12-12 17:18:33,441 - DEBUG - ya.test - _save_outputs: Command (pid 193444) ixrss: 0
2025-12-12 17:18:33,441 - DEBUG - ya.test - _save_outputs: Command (pid 193444) majflt: 0
2025-12-12 17:18:33,441 - DEBUG - ya.test - _save_outputs: Command (pid 193444) maxrss: 544184
2025-12-12 17:18:33,441 - DEBUG - ya.test - _save_outputs: Command (pid 193444) minflt: 16233
2025-12-12 17:18:33,441 - DEBUG - ya.test - _save_outputs: Command (pid 193444) msgrcv: 0
2025-12-12 17:18:33,441 - DEBUG - ya.test - _save_outputs: Command (pid 193444) msgsnd: 0
2025-12-12 17:18:33,442 - DEBUG - ya.test - _save_outputs: Command (pid 193444) nivcsw: 85
2025-12-12 17:18:33,442 - DEBUG - ya.test - _save_outputs: Command (pid 193444) nsignals: 0
2025-12-12 17:18:33,442 - DEBUG - ya.test - _save_outputs: Command (pid 193444) nswap: 0
2025-12-12 17:18:33,442 - DEBUG - ya.test - _save_outputs: Command (pid 193444) nvcsw: 13
2025-12-12 17:18:33,442 - DEBUG - ya.test - _save_outputs: Command (pid 193444) oublock: 8
2025-12-12 17:18:33,442 - DEBUG - ya.test - _save_outputs: Command (pid 193444) stime: 0.169063
2025-12-12 17:18:33,442 - DEBUG - ya.test - _save_outputs: Command (pid 193444) utime: 0.154035
2025-12-12 17:18:33,443 - DEBUG - ya.test - _save_outputs: Command (pid 193444) wtime: 0.678
2025-12-12 17:18:33,443 - DEBUG - ya.test - _save_outputs: Command (pid 193444) output:
Git info:
    Commit: 1e60e344b55a9fb8c31a7f05c1806fa018d14d5e
    Branch: 
    Author: YDBot <ydbot@ydb.tech>
    Summary: Merge 13d20d320b4adfcaad71c0ad68bd89bf5e1f00a5 into 70510f510f754318d6ece3c98b4b57c0e4dbe999

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

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

2025-12-12 17:18:33,443 - DEBUG - ya.test - _save_outputs: Command (pid 193444) errors:

2025-12-12 17:18:33,443 - INFO - ydb.tests.library.harness.kikimr_runner - start: Node 1 version:
Git info:
    Commit: 1e60e344b55a9fb8c31a7f05c1806fa018d14d5e
    Branch: 
    Author: YDBot <ydbot@ydb.tech>
    Summary: Merge 13d20d320b4adfcaad71c0ad68bd89bf5e1f00a5 into 70510f510f754318d6ece3c98b4b57c0e4dbe999
2025-12-12 17:18:35,709 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydbd/ydbd
2025-12-12 17:18:35,710 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8320', '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: 3448\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-12 17:18:35,711 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.5
2025-12-12 17:18:35,711 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.5
2025-12-12 17:18:35,712 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8320', '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: 3448\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-12 17:18:35,714 - DEBUG - ya.test - execute: Command pid: 194778
2025-12-12 17:18:36,303 - DEBUG - ya.test - _save_outputs: Command (pid 194778) rc: 0
2025-12-12 17:18:36,303 - DEBUG - ya.test - _save_outputs: Command (pid 194778) elapsed time (sec): 0.5886213779449463
2025-12-12 17:18:36,304 - DEBUG - ya.test - _save_outputs: Command (pid 194778) idrss: 0
2025-12-12 17:18:36,304 - DEBUG - ya.test - _save_outputs: Command (pid 194778) inblock: 0
2025-12-12 17:18:36,304 - DEBUG - ya.test - _save_outputs: Command (pid 194778) isrss: 0
2025-12-12 17:18:36,304 - DEBUG - ya.test - _save_outputs: Command (pid 194778) ixrss: 0
2025-12-12 17:18:36,304 - DEBUG - ya.test - _save_outputs: Command (pid 194778) majflt: 0
2025-12-12 17:18:36,304 - DEBUG - ya.test - _save_outputs: Command (pid 194778) maxrss: 552556
2025-12-12 17:18:36,305 - DEBUG - ya.test - _save_outputs: Command (pid 194778) minflt: 18374
2025-12-12 17:18:36,305 - DEBUG - ya.test - _save_outputs: Command (pid 194778) msgrcv: 0
2025-12-12 17:18:36,305 - DEBUG - ya.test - _save_outputs: Command (pid 194778) msgsnd: 0
2025-12-12 17:18:36,305 - DEBUG - ya.test - _save_outputs: Command (pid 194778) nivcsw: 154
2025-12-12 17:18:36,305 - DEBUG - ya.test - _save_outputs: Command (pid 194778) nsignals: 0
2025-12-12 17:18:36,305 - DEBUG - ya.test - _save_outputs: Command (pid 194778) nswap: 0
2025-12-12 17:18:36,306 - DEBUG - ya.test - _save_outputs: Command (pid 194778) nvcsw: 71
2025-12-12 17:18:36,306 - DEBUG - ya.test - _save_outputs: Command (pid 194778) oublock: 8
2025-12-12 17:18:36,306 - DEBUG - ya.test - _save_outputs: Command (pid 194778) stime: 0.22064899999999998
2025-12-12 17:18:36,306 - DEBUG - ya.test - _save_outputs: Command (pid 194778) utime: 0.155976
2025-12-12 17:18:36,306 - DEBUG - ya.test - _save_outputs: Command (pid 194778) wtime: 0.591
2025-12-12 17:18:36,306 - DEBUG - ya.test - _save_outputs: Command (pid 194778) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 4

2025-12-12 17:18:36,307 - DEBUG - ya.test - _save_outputs: Command (pid 194778) errors:

2025-12-12 17:18:36,308 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydbd/ydbd
2025-12-12 17:18:36,308 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8320', '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-12-12 17:18:36,309 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.6
2025-12-12 17:18:36,309 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.6
2025-12-12 17:18:36,310 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:8320', '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-12-12 17:18:36,311 - DEBUG - ya.test - execute: Command pid: 195082
2025-12-12 17:18:36,844 - DEBUG - ya.test - _save_outputs: Command (pid 195082) rc: 0
2025-12-12 17:18:36,844 - DEBUG - ya.test - _save_outputs: Command (pid 195082) elapsed time (sec): 0.5320756435394287
2025-12-12 17:18:36,844 - DEBUG - ya.test - _save_outputs: Command (pid 195082) idrss: 0
2025-12-12 17:18:36,844 - DEBUG - ya.test - _save_outputs: Command (pid 195082) inblock: 0
2025-12-12 17:18:36,844 - DEBUG - ya.test - _save_outputs: Command (pid 195082) isrss: 0
2025-12-12 17:18:36,845 - DEBUG - ya.test - _save_outputs: Command (pid 195082) ixrss: 0
2025-12-12 17:18:36,845 - DEBUG - ya.test - _save_outputs: Command (pid 195082) majflt: 0
2025-12-12 17:18:36,845 - DEBUG - ya.test - _save_outputs: Command (pid 195082) maxrss: 552532
2025-12-12 17:18:36,845 - DEBUG - ya.test - _save_outputs: Command (pid 195082) minflt: 18377
2025-12-12 17:18:36,845 - DEBUG - ya.test - _save_outputs: Command (pid 195082) msgrcv: 0
2025-12-12 17:18:36,845 - DEBUG - ya.test - _save_outputs: Command (pid 195082) msgsnd: 0
2025-12-12 17:18:36,845 - DEBUG - ya.test - _save_outputs: Command (pid 195082) nivcsw: 147
2025-12-12 17:18:36,845 - DEBUG - ya.test - _save_outputs: Command (pid 195082) nsignals: 0
2025-12-12 17:18:36,846 - DEBUG - ya.test - _save_outputs: Command (pid 195082) nswap: 0
2025-12-12 17:18:36,846 - DEBUG - ya.test - _save_outputs: Command (pid 195082) nvcsw: 91
2025-12-12 17:18:36,846 - DEBUG - ya.test - _save_outputs: Command (pid 195082) oublock: 8
2025-12-12 17:18:36,846 - DEBUG - ya.test - _save_outputs: Command (pid 195082) stime: 0.271777
2025-12-12 17:18:36,846 - DEBUG - ya.test - _save_outputs: Command (pid 195082) utime: 0.142361
2025-12-12 17:18:36,846 - DEBUG - ya.test - _save_outputs: Command (pid 195082) wtime: 0.533
2025-12-12 17:18:36,847 - DEBUG - ya.test - _save_outputs: Command (pid 195082) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-12 17:18:36,847 - DEBUG - ya.test - _save_outputs: Command (pid 195082) errors:

2025-12-12 17:18:36,847 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-12 17:18:36,973 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-12-12 17:18:36,997 - 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-12-12 17:18:36,999 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 17:18:37,000 - 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-12-12 17:18:37,018 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-12-12 17:18:37,018 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 84437e93-251a-40f7-913e-02777a4c96c5, localhost:8320): creating call state
2025-12-12 17:18:37,019 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 84437e93-251a-40f7-913e-02777a4c96c5, localhost:8320): request = { database: "/Root" }
2025-12-12 17:18:37,025 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 84437e93-251a-40f7-913e-02777a4c96c5, localhost:8320): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lmjjgjftxu.auto.internal" port: 8320 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-12-12 17:18:37,025 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lmjjgjftxu.auto.internal:8320, location 1, ssl: False>]>
2025-12-12 17:18:37,026 - 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-12-12 17:18:37,056 - INFO - ydb.connection - close: Closing channel for endpoint localhost:8320
2025-12-12 17:18:37,057 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_deviation[1051200])
2025-12-12 17:18:37,092 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydb/ydb
2025-12-12 17:18:37,092 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydb/ydb -e grpc://localhost:8320 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-12-12 17:18:37,093 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-12-12 17:18:37,093 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-12-12 17:18:37,094 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:8320', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-12-12 17:18:37,095 - DEBUG - ya.test - execute: Command pid: 195362
2025-12-12 17:18:37,667 - DEBUG - ya.test - _save_outputs: Command (pid 195362) rc: 0
2025-12-12 17:18:37,667 - DEBUG - ya.test - _save_outputs: Command (pid 195362) elapsed time (sec): 0.5682168006896973
2025-12-12 17:18:37,667 - DEBUG - ya.test - _save_outputs: Command (pid 195362) idrss: 0
2025-12-12 17:18:37,668 - DEBUG - ya.test - _save_outputs: Command (pid 195362) inblock: 0
2025-12-12 17:18:37,668 - DEBUG - ya.test - _save_outputs: Command (pid 195362) isrss: 0
2025-12-12 17:18:37,668 - DEBUG - ya.test - _save_outputs: Command (pid 195362) ixrss: 0
2025-12-12 17:18:37,668 - DEBUG - ya.test - _save_outputs: Command (pid 195362) majflt: 0
2025-12-12 17:18:37,668 - DEBUG - ya.test - _save_outputs: Command (pid 195362) maxrss: 181344
2025-12-12 17:18:37,668 - DEBUG - ya.test - _save_outputs: Command (pid 195362) minflt: 3497
2025-12-12 17:18:37,668 - DEBUG - ya.test - _save_outputs: Command (pid 195362) msgrcv: 0
2025-12-12 17:18:37,669 - DEBUG - ya.test - _save_outputs: Command (pid 195362) msgsnd: 0
2025-12-12 17:18:37,669 - DEBUG - ya.test - _save_outputs: Command (pid 195362) nivcsw: 54
2025-12-12 17:18:37,669 - DEBUG - ya.test - _save_outputs: Command (pid 195362) nsignals: 0
2025-12-12 17:18:37,669 - DEBUG - ya.test - _save_outputs: Command (pid 195362) nswap: 0
2025-12-12 17:18:37,669 - DEBUG - ya.test - _save_outputs: Command (pid 195362) nvcsw: 148
2025-12-12 17:18:37,669 - DEBUG - ya.test - _save_outputs: Command (pid 195362) oublock: 8
2025-12-12 17:18:37,670 - DEBUG - ya.test - _save_outputs: Command (pid 195362) stime: 0.015910999999999998
2025-12-12 17:18:37,670 - DEBUG - ya.test - _save_outputs: Command (pid 195362) utime: 0.060254999999999996
2025-12-12 17:18:37,670 - DEBUG - ya.test - _save_outputs: Command (pid 195362) wtime: 0.57
2025-12-12 17:18:37,670 - DEBUG - ya.test - _save_outputs: Command (pid 195362) output:
Init tables ...
Init tables ...Ok

2025-12-12 17:18:37,670 - DEBUG - ya.test - _save_outputs: Command (pid 195362) errors:

2025-12-12 17:18:37,671 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-12 17:18:37,672 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, aefbc6df-f643-4e00-9b91-5263becde74e, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:37,675 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, aefbc6df-f643-4e00-9b91-5263becde74e, ghrun-lmjjgjftxu.auto.internal:8320): request = {  }
2025-12-12 17:18:37,680 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, aefbc6df-f643-4e00-9b91-5263becde74e, ghrun-lmjjgjftxu.auto.internal:8320): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=" node_id: 1 }
2025-12-12 17:18:37,681 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 8d8168a3-591b-47af-a235-9e8232f15624, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:37,681 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 8d8168a3-591b-47af-a235-9e8232f15624, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=" }
2025-12-12 17:18:37,692 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 8d8168a3-591b-47af-a235-9e8232f15624, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:37,705 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:18:37,706 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9461c5f0-9def-4d76-86ff-20b5f5b72ef3, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:37,707 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9461c5f0-9def-4d76-86ff-20b5f5b72ef3, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=" 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-12-12 17:18:37,707 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9461c5f0-9def-4d76-86ff-20b5f5b72ef3, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:37,779 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:18:37,780 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-12-12 17:18:37,780 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydb/ydb -e grpc://localhost:8320 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 1051200 with wait = True
2025-12-12 17:18:37,781 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-12-12 17:18:37,782 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-12-12 17:18:37,782 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:8320', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '1051200']' in 'None' (waiting)
2025-12-12 17:18:37,803 - DEBUG - ya.test - execute: Command pid: 195646
2025-12-12 17:18:42,172 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-12-12 17:18:42,172 - DEBUG - ya.test - _save_outputs: Command (pid 195646) rc: 0
2025-12-12 17:18:42,173 - DEBUG - ya.test - _save_outputs: Command (pid 195646) elapsed time (sec): 4.368776082992554
2025-12-12 17:18:42,173 - DEBUG - ya.test - _save_outputs: Command (pid 195646) wtime: 4.39
2025-12-12 17:18:42,173 - DEBUG - ya.test - _save_outputs: Command (pid 195646) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	     10	10	0	0	1439	1743	1743	1743
3	     15	15	0	0	1415	1471	1503	1503

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	     25	8.33333	0	0	1423	1671	1743	1743

2025-12-12 17:18:42,173 - DEBUG - ya.test - _save_outputs: Command (pid 195646) errors:

2025-12-12 17:18:42,173 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:18:42,174 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f3d01775-e4c4-4a1a-b89f-c6384dc19b8c, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:42,174 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f3d01775-e4c4-4a1a-b89f-c6384dc19b8c, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:18:42,175 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f3d01775-e4c4-4a1a-b89f-c6384dc19b8c, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:43,728 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:18:43,728 - INFO - root - test_log_deviation: Count rows after insert 12500 before wait
2025-12-12 17:18:43,729 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:18:43,729 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b5cd2f62-596d-41b8-93ba-fc631ff4ee94, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:43,730 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b5cd2f62-596d-41b8-93ba-fc631ff4ee94, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:18:43,730 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b5cd2f62-596d-41b8-93ba-fc631ff4ee94, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:45,093 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:18:45,094 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:18:45,094 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, af67d421-244f-4eab-b31e-d2c22aa8e22b, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:45,095 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, af67d421-244f-4eab-b31e-d2c22aa8e22b, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:18:45,096 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, af67d421-244f-4eab-b31e-d2c22aa8e22b, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:46,469 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:18:46,471 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-12-12 17:18:46,472 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-12-12 17:18:46,472 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-12-12 17:18:46,473 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:18:46,475 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydb/ydb -e grpc://localhost:8320 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 1051200 with wait = True
2025-12-12 17:18:46,475 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydb/ydb -e grpc://localhost:8320 -d /Root workload log --path log run insert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 1051200 with wait = True
2025-12-12 17:18:46,475 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydb/ydb -e grpc://localhost:8320 -d /Root workload log --path log run upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 1051200 with wait = True
2025-12-12 17:18:46,476 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 705e827e-6cca-4162-8679-075ad6888e51, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,480 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 705e827e-6cca-4162-8679-075ad6888e51, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-12-12 17:18:46,477 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-12-12 17:18:46,476 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-12-12 17:18:46,481 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 705e827e-6cca-4162-8679-075ad6888e51, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:46,484 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.2
2025-12-12 17:18:46,485 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.3
2025-12-12 17:18:46,486 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, c2f4e1cd-7ff9-457c-8f66-4e6995eb7985, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,488 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, c2f4e1cd-7ff9-457c-8f66-4e6995eb7985, ghrun-lmjjgjftxu.auto.internal:8320): request = {  }
2025-12-12 17:18:46,489 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-12-12 17:18:46,493 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, c2f4e1cd-7ff9-457c-8f66-4e6995eb7985, ghrun-lmjjgjftxu.auto.internal:8320): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZWVjOTA2YzktODkxY2RiOTItOTJhNTgwMmItNjM4NzhmYzQ=" node_id: 1 }
2025-12-12 17:18:46,494 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 81a8a22c-63da-4dc4-b68c-343cdeafbe9a, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,494 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 81a8a22c-63da-4dc4-b68c-343cdeafbe9a, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=ZWVjOTA2YzktODkxY2RiOTItOTJhNTgwMmItNjM4NzhmYzQ=" }
2025-12-12 17:18:46,494 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 81a8a22c-63da-4dc4-b68c-343cdeafbe9a, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:46,497 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZWVjOTA2YzktODkxY2RiOTItOTJhNTgwMmItNjM4NzhmYzQ=
2025-12-12 17:18:46,497 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ddd7a029-941a-4b2a-a7a5-daa63d98e372, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,498 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 2 < 100, will create new one.
2025-12-12 17:18:46,499 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 1609425c-ede9-46c7-8d15-47f4d478e3c9, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,499 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 1609425c-ede9-46c7-8d15-47f4d478e3c9, ghrun-lmjjgjftxu.auto.internal:8320): request = {  }
2025-12-12 17:18:46,498 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ddd7a029-941a-4b2a-a7a5-daa63d98e372, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=ZWVjOTA2YzktODkxY2RiOTItOTJhNTgwMmItNjM4NzhmYzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-12-12 17:18:46,500 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ddd7a029-941a-4b2a-a7a5-daa63d98e372, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:46,502 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 1609425c-ede9-46c7-8d15-47f4d478e3c9, ghrun-lmjjgjftxu.auto.internal:8320): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=" node_id: 1 }
2025-12-12 17:18:46,502 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 8d0a8f88-dd8e-4530-9fdd-5d868bb46f2d, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,502 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 8d0a8f88-dd8e-4530-9fdd-5d868bb46f2d, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=" }
2025-12-12 17:18:46,503 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 8d0a8f88-dd8e-4530-9fdd-5d868bb46f2d, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:46,505 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-12-12 17:18:46,505 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:8320', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'insert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '1051200']' in 'None' (waiting)
2025-12-12 17:18:46,508 - DEBUG - ya.test - execute: Command pid: 200412
2025-12-12 17:18:46,509 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-12-12 17:18:46,510 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:8320', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '1051200']' in 'None' (waiting)
2025-12-12 17:18:46,510 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:8320', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '1051200']' in 'None' (waiting)
2025-12-12 17:18:46,511 - DEBUG - ya.test - execute: Command pid: 200413
2025-12-12 17:18:46,515 - DEBUG - ya.test - execute: Command pid: 200414
2025-12-12 17:18:46,516 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=
2025-12-12 17:18:46,537 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 507f18f8-6c36-4c1c-8a01-a9a70458a189, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,537 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 3 < 100, will create new one.
2025-12-12 17:18:46,538 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 507f18f8-6c36-4c1c-8a01-a9a70458a189, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-12-12 17:18:46,584 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 795f8a52-2395-4ed1-8b75-cc4a6e4b0170, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,585 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 795f8a52-2395-4ed1-8b75-cc4a6e4b0170, ghrun-lmjjgjftxu.auto.internal:8320): request = {  }
2025-12-12 17:18:46,586 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 507f18f8-6c36-4c1c-8a01-a9a70458a189, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:46,632 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 795f8a52-2395-4ed1-8b75-cc4a6e4b0170, ghrun-lmjjgjftxu.auto.internal:8320): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZTE1Zjk1OTctNzRiOTQyYTgtOGMwY2MxYWYtNGZiM2ZmMzY=" node_id: 1 }
2025-12-12 17:18:46,633 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, d3071a75-c205-46b9-bb15-f27e2198553b, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,633 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, d3071a75-c205-46b9-bb15-f27e2198553b, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=ZTE1Zjk1OTctNzRiOTQyYTgtOGMwY2MxYWYtNGZiM2ZmMzY=" }
2025-12-12 17:18:46,634 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, d3071a75-c205-46b9-bb15-f27e2198553b, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:46,640 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZTE1Zjk1OTctNzRiOTQyYTgtOGMwY2MxYWYtNGZiM2ZmMzY=
2025-12-12 17:18:46,641 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 63863bd9-fc17-4230-9a72-01af38e8a731, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,642 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 63863bd9-fc17-4230-9a72-01af38e8a731, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=ZTE1Zjk1OTctNzRiOTQyYTgtOGMwY2MxYWYtNGZiM2ZmMzY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-12-12 17:18:46,643 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 63863bd9-fc17-4230-9a72-01af38e8a731, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:46,643 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 4 < 100, will create new one.
2025-12-12 17:18:46,644 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 15304396-7569-47dd-b02e-a1ea3971b5e1, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,644 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 15304396-7569-47dd-b02e-a1ea3971b5e1, ghrun-lmjjgjftxu.auto.internal:8320): request = {  }
2025-12-12 17:18:46,702 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 15304396-7569-47dd-b02e-a1ea3971b5e1, ghrun-lmjjgjftxu.auto.internal:8320): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NWM5MDAwZTctMzJmYzEyZWUtOTk5Mzg1OGItNzYwZDQ3MjM=" node_id: 1 }
2025-12-12 17:18:46,703 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 9a03165d-5430-4521-ada2-e0fae1764916, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,703 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 9a03165d-5430-4521-ada2-e0fae1764916, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NWM5MDAwZTctMzJmYzEyZWUtOTk5Mzg1OGItNzYwZDQ3MjM=" }
2025-12-12 17:18:46,704 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 9a03165d-5430-4521-ada2-e0fae1764916, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:46,726 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NWM5MDAwZTctMzJmYzEyZWUtOTk5Mzg1OGItNzYwZDQ3MjM=
2025-12-12 17:18:46,727 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3bcbb933-31ec-457e-b409-c64b74a9d380, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,728 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3bcbb933-31ec-457e-b409-c64b74a9d380, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NWM5MDAwZTctMzJmYzEyZWUtOTk5Mzg1OGItNzYwZDQ3MjM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-12-12 17:18:46,728 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3bcbb933-31ec-457e-b409-c64b74a9d380, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:46,729 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 5 < 100, will create new one.
2025-12-12 17:18:46,730 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, bfffab68-d32b-41c9-bbcf-3c4043253c2f, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,730 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, bfffab68-d32b-41c9-bbcf-3c4043253c2f, ghrun-lmjjgjftxu.auto.internal:8320): request = {  }
2025-12-12 17:18:46,752 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, bfffab68-d32b-41c9-bbcf-3c4043253c2f, ghrun-lmjjgjftxu.auto.internal:8320): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NjMzMjBjYWEtOWRhOWNkOWYtNTgzOTkxZTAtYjViOGVmY2M=" node_id: 1 }
2025-12-12 17:18:46,753 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, d113ded8-ec58-44ed-9699-427752c4a2df, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,753 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, d113ded8-ec58-44ed-9699-427752c4a2df, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NjMzMjBjYWEtOWRhOWNkOWYtNTgzOTkxZTAtYjViOGVmY2M=" }
2025-12-12 17:18:46,754 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, d113ded8-ec58-44ed-9699-427752c4a2df, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:46,796 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NjMzMjBjYWEtOWRhOWNkOWYtNTgzOTkxZTAtYjViOGVmY2M=
2025-12-12 17:18:46,797 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5b99ded3-9488-49a2-aa65-8b1f3cd81d35, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,797 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5b99ded3-9488-49a2-aa65-8b1f3cd81d35, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NjMzMjBjYWEtOWRhOWNkOWYtNTgzOTkxZTAtYjViOGVmY2M=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-12-12 17:18:46,798 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5b99ded3-9488-49a2-aa65-8b1f3cd81d35, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:46,799 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 6 < 100, will create new one.
2025-12-12 17:18:46,799 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 0e282380-82c8-4ba3-8e32-29590f64e874, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,800 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 0e282380-82c8-4ba3-8e32-29590f64e874, ghrun-lmjjgjftxu.auto.internal:8320): request = {  }
2025-12-12 17:18:46,824 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 0e282380-82c8-4ba3-8e32-29590f64e874, ghrun-lmjjgjftxu.auto.internal:8320): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NzlkMzA0MmQtNGQwZGI0YmEtZWJjNWJhZTgtYzE5NDJhZTM=" node_id: 1 }
2025-12-12 17:18:46,825 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, a94ec2eb-2871-44b8-a451-be52701d686d, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,825 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, a94ec2eb-2871-44b8-a451-be52701d686d, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NzlkMzA0MmQtNGQwZGI0YmEtZWJjNWJhZTgtYzE5NDJhZTM=" }
2025-12-12 17:18:46,826 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, a94ec2eb-2871-44b8-a451-be52701d686d, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:46,901 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NzlkMzA0MmQtNGQwZGI0YmEtZWJjNWJhZTgtYzE5NDJhZTM=
2025-12-12 17:18:46,902 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 139f2255-c4c1-42a0-94b3-fc7774f164e5, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,903 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 139f2255-c4c1-42a0-94b3-fc7774f164e5, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NzlkMzA0MmQtNGQwZGI0YmEtZWJjNWJhZTgtYzE5NDJhZTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-12-12 17:18:46,904 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 139f2255-c4c1-42a0-94b3-fc7774f164e5, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:46,906 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 7 < 100, will create new one.
2025-12-12 17:18:46,906 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 7b1a363e-19b7-4acd-943d-37257daf0e4f, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:46,906 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 7b1a363e-19b7-4acd-943d-37257daf0e4f, ghrun-lmjjgjftxu.auto.internal:8320): request = {  }
2025-12-12 17:18:47,012 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 7b1a363e-19b7-4acd-943d-37257daf0e4f, ghrun-lmjjgjftxu.auto.internal:8320): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=Yzg3NWM1ZjktNjZlZGU1M2UtOWFjOTk2ODEtNzM2NjNiMDU=" node_id: 1 }
2025-12-12 17:18:47,101 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 12d1fc3a-89ab-48dc-8b8f-eac764a018a6, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:47,101 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 12d1fc3a-89ab-48dc-8b8f-eac764a018a6, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=Yzg3NWM1ZjktNjZlZGU1M2UtOWFjOTk2ODEtNzM2NjNiMDU=" }
2025-12-12 17:18:47,102 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 12d1fc3a-89ab-48dc-8b8f-eac764a018a6, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:47,420 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=Yzg3NWM1ZjktNjZlZGU1M2UtOWFjOTk2ODEtNzM2NjNiMDU=
2025-12-12 17:18:47,448 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a39279e0-ecf3-4292-88ab-c7a180a38af2, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:47,449 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a39279e0-ecf3-4292-88ab-c7a180a38af2, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=Yzg3NWM1ZjktNjZlZGU1M2UtOWFjOTk2ODEtNzM2NjNiMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-12-12 17:18:47,450 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a39279e0-ecf3-4292-88ab-c7a180a38af2, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:47,452 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 8 < 100, will create new one.
2025-12-12 17:18:47,452 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 536db400-137d-472f-8095-8d6b5c616ed8, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:47,452 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 536db400-137d-472f-8095-8d6b5c616ed8, ghrun-lmjjgjftxu.auto.internal:8320): request = {  }
2025-12-12 17:18:47,876 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 536db400-137d-472f-8095-8d6b5c616ed8, ghrun-lmjjgjftxu.auto.internal:8320): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YjczMmEwMjYtZWQwMWVhY2EtMmNiNzBlMDQtMjBjZjkz" node_id: 1 }
2025-12-12 17:18:47,877 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, ffa2ddbf-be71-4447-9b44-d691d0db6523, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:47,877 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, ffa2ddbf-be71-4447-9b44-d691d0db6523, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=YjczMmEwMjYtZWQwMWVhY2EtMmNiNzBlMDQtMjBjZjkz" }
2025-12-12 17:18:47,878 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, ffa2ddbf-be71-4447-9b44-d691d0db6523, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:48,502 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YjczMmEwMjYtZWQwMWVhY2EtMmNiNzBlMDQtMjBjZjkz
2025-12-12 17:18:48,556 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 020883dc-e561-4942-a73f-571cdb608cfa, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:48,557 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 020883dc-e561-4942-a73f-571cdb608cfa, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=YjczMmEwMjYtZWQwMWVhY2EtMmNiNzBlMDQtMjBjZjkz" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-12-12 17:18:48,557 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 9 < 100, will create new one.
2025-12-12 17:18:48,558 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, a63d139b-4ce8-4667-b0b1-d03e208fda6e, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:48,558 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, a63d139b-4ce8-4667-b0b1-d03e208fda6e, ghrun-lmjjgjftxu.auto.internal:8320): request = {  }
2025-12-12 17:18:48,559 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 020883dc-e561-4942-a73f-571cdb608cfa, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:48,668 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, a63d139b-4ce8-4667-b0b1-d03e208fda6e, ghrun-lmjjgjftxu.auto.internal:8320): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=" node_id: 1 }
2025-12-12 17:18:48,725 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 37f3afa3-a8cd-47c4-8a8c-a2ca6346cf71, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:48,725 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 37f3afa3-a8cd-47c4-8a8c-a2ca6346cf71, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=" }
2025-12-12 17:18:48,726 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 37f3afa3-a8cd-47c4-8a8c-a2ca6346cf71, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:48,857 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=
2025-12-12 17:18:48,857 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7dcbcfbb-75ab-420f-a08f-fcde1db733cf, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:18:48,858 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7dcbcfbb-75ab-420f-a08f-fcde1db733cf, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-12-12 17:18:48,859 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7dcbcfbb-75ab-420f-a08f-fcde1db733cf, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:18:50,408 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-12-12 17:18:50,438 - DEBUG - ya.test - _save_outputs: Command (pid 200413) rc: 0
2025-12-12 17:18:50,439 - DEBUG - ya.test - _save_outputs: Command (pid 200413) elapsed time (sec): 3.8998606204986572
2025-12-12 17:18:50,439 - DEBUG - ya.test - _save_outputs: Command (pid 200413) wtime: 3.925
2025-12-12 17:18:50,439 - DEBUG - ya.test - _save_outputs: Command (pid 200413) 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-12-12 17:18:50,439 - DEBUG - ya.test - _save_outputs: Command (pid 200413) errors:

2025-12-12 17:18:50,603 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-12-12 17:18:50,628 - DEBUG - ya.test - _save_outputs: Command (pid 200412) rc: 0
2025-12-12 17:18:50,629 - DEBUG - ya.test - _save_outputs: Command (pid 200412) elapsed time (sec): 4.114595413208008
2025-12-12 17:18:50,629 - DEBUG - ya.test - _save_outputs: Command (pid 200412) wtime: 4.119
2025-12-12 17:18:50,629 - DEBUG - ya.test - _save_outputs: Command (pid 200412) 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-12-12 17:18:50,629 - DEBUG - ya.test - _save_outputs: Command (pid 200412) errors:

2025-12-12 17:18:58,821 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-12-12 17:18:58,821 - DEBUG - ya.test - _save_outputs: Command (pid 200414) rc: 0
2025-12-12 17:18:58,822 - DEBUG - ya.test - _save_outputs: Command (pid 200414) elapsed time (sec): 12.2848060131073
2025-12-12 17:18:58,822 - DEBUG - ya.test - _save_outputs: Command (pid 200414) wtime: 12.31
2025-12-12 17:18:58,822 - DEBUG - ya.test - _save_outputs: Command (pid 200414) 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	11967	12031	12031	12031

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

2025-12-12 17:18:58,822 - DEBUG - ya.test - _save_outputs: Command (pid 200414) errors:

2025-12-12 17:19:06,778 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=
2025-12-12 17:19:06,787 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=
2025-12-12 17:19:06,787 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 89524fe5-1cdd-4b96-8635-fa068c69bb17, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:06,788 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 89524fe5-1cdd-4b96-8635-fa068c69bb17, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=" 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-12-12 17:19:06,789 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 89524fe5-1cdd-4b96-8635-fa068c69bb17, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:07,593 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=
2025-12-12 17:19:07,594 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=
2025-12-12 17:19:07,594 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 97c7f13e-0daf-49be-8667-f9cb8e8e5bfc, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:07,595 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 97c7f13e-0daf-49be-8667-f9cb8e8e5bfc, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:19:07,595 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 97c7f13e-0daf-49be-8667-f9cb8e8e5bfc, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:07,822 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjczMmEwMjYtZWQwMWVhY2EtMmNiNzBlMDQtMjBjZjkz
2025-12-12 17:19:07,822 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjczMmEwMjYtZWQwMWVhY2EtMmNiNzBlMDQtMjBjZjkz
2025-12-12 17:19:07,823 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 82fb2aff-adb8-4e37-ac94-dd9cfb75c281, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:07,823 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 82fb2aff-adb8-4e37-ac94-dd9cfb75c281, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=YjczMmEwMjYtZWQwMWVhY2EtMmNiNzBlMDQtMjBjZjkz" 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-12-12 17:19:07,824 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 82fb2aff-adb8-4e37-ac94-dd9cfb75c281, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:07,839 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:19:07,839 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:19:07,839 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3d4df5e1-0834-4f06-87ee-7e70af670f44, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:07,842 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjMzMjBjYWEtOWRhOWNkOWYtNTgzOTkxZTAtYjViOGVmY2M=
2025-12-12 17:19:07,875 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjMzMjBjYWEtOWRhOWNkOWYtNTgzOTkxZTAtYjViOGVmY2M=
2025-12-12 17:19:07,876 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6a554967-aede-4b0a-9555-7002253f87fe, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:07,876 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6a554967-aede-4b0a-9555-7002253f87fe, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NjMzMjBjYWEtOWRhOWNkOWYtNTgzOTkxZTAtYjViOGVmY2M=" 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-12-12 17:19:07,877 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6a554967-aede-4b0a-9555-7002253f87fe, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:07,863 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3d4df5e1-0834-4f06-87ee-7e70af670f44, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=" 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-12-12 17:19:07,878 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3d4df5e1-0834-4f06-87ee-7e70af670f44, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:07,908 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWVjOTA2YzktODkxY2RiOTItOTJhNTgwMmItNjM4NzhmYzQ=
2025-12-12 17:19:07,909 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWVjOTA2YzktODkxY2RiOTItOTJhNTgwMmItNjM4NzhmYzQ=
2025-12-12 17:19:07,909 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f9c06495-0ab0-4a96-92ca-fbad497c33c0, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:07,910 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f9c06495-0ab0-4a96-92ca-fbad497c33c0, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=ZWVjOTA2YzktODkxY2RiOTItOTJhNTgwMmItNjM4NzhmYzQ=" 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-12-12 17:19:07,910 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f9c06495-0ab0-4a96-92ca-fbad497c33c0, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:07,982 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWM5MDAwZTctMzJmYzEyZWUtOTk5Mzg1OGItNzYwZDQ3MjM=
2025-12-12 17:19:07,990 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWM5MDAwZTctMzJmYzEyZWUtOTk5Mzg1OGItNzYwZDQ3MjM=
2025-12-12 17:19:07,996 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4f181a21-58e0-40cd-b725-b9a9aedeb8e7, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:07,999 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4f181a21-58e0-40cd-b725-b9a9aedeb8e7, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NWM5MDAwZTctMzJmYzEyZWUtOTk5Mzg1OGItNzYwZDQ3MjM=" 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-12-12 17:19:07,999 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4f181a21-58e0-40cd-b725-b9a9aedeb8e7, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:08,000 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTE1Zjk1OTctNzRiOTQyYTgtOGMwY2MxYWYtNGZiM2ZmMzY=
2025-12-12 17:19:08,001 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTE1Zjk1OTctNzRiOTQyYTgtOGMwY2MxYWYtNGZiM2ZmMzY=
2025-12-12 17:19:08,001 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c6035617-0419-462e-ace6-753bd75d3374, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:08,002 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c6035617-0419-462e-ace6-753bd75d3374, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=ZTE1Zjk1OTctNzRiOTQyYTgtOGMwY2MxYWYtNGZiM2ZmMzY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:19:08,002 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c6035617-0419-462e-ace6-753bd75d3374, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:08,059 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzg3NWM1ZjktNjZlZGU1M2UtOWFjOTk2ODEtNzM2NjNiMDU=
2025-12-12 17:19:08,061 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzg3NWM1ZjktNjZlZGU1M2UtOWFjOTk2ODEtNzM2NjNiMDU=
2025-12-12 17:19:08,062 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 64f48407-56b1-4c54-8741-d02e2f9a0df0, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:08,062 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 64f48407-56b1-4c54-8741-d02e2f9a0df0, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=Yzg3NWM1ZjktNjZlZGU1M2UtOWFjOTk2ODEtNzM2NjNiMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:19:08,063 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 64f48407-56b1-4c54-8741-d02e2f9a0df0, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:08,087 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzlkMzA0MmQtNGQwZGI0YmEtZWJjNWJhZTgtYzE5NDJhZTM=
2025-12-12 17:19:08,087 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzlkMzA0MmQtNGQwZGI0YmEtZWJjNWJhZTgtYzE5NDJhZTM=
2025-12-12 17:19:08,088 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 68d889c2-57c5-40f0-8dd8-5a9a448c1836, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:08,088 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 68d889c2-57c5-40f0-8dd8-5a9a448c1836, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NzlkMzA0MmQtNGQwZGI0YmEtZWJjNWJhZTgtYzE5NDJhZTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:19:08,089 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 68d889c2-57c5-40f0-8dd8-5a9a448c1836, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:20,727 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=
2025-12-12 17:19:20,846 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=
2025-12-12 17:19:20,873 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9fe91000-579f-4f78-8585-499b83f22694, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:20,888 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9fe91000-579f-4f78-8585-499b83f22694, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:19:20,905 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9fe91000-579f-4f78-8585-499b83f22694, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:21,400 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=
2025-12-12 17:19:21,501 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=
2025-12-12 17:19:21,502 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 910c4314-95c6-48d3-ae1e-cecc396b25f5, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:21,519 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 910c4314-95c6-48d3-ae1e-cecc396b25f5, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=" 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-12-12 17:19:21,550 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 910c4314-95c6-48d3-ae1e-cecc396b25f5, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:21,908 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:19:21,996 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:19:22,001 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3ce518d2-ea40-4ce0-a562-804a0b07de8b, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:22,003 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3ce518d2-ea40-4ce0-a562-804a0b07de8b, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=" 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-12-12 17:19:22,008 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3ce518d2-ea40-4ce0-a562-804a0b07de8b, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:22,463 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjMzMjBjYWEtOWRhOWNkOWYtNTgzOTkxZTAtYjViOGVmY2M=
2025-12-12 17:19:22,546 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjMzMjBjYWEtOWRhOWNkOWYtNTgzOTkxZTAtYjViOGVmY2M=
2025-12-12 17:19:22,563 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2198c055-bd78-4f8a-9f98-5c78866155bd, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:22,589 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2198c055-bd78-4f8a-9f98-5c78866155bd, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NjMzMjBjYWEtOWRhOWNkOWYtNTgzOTkxZTAtYjViOGVmY2M=" 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-12-12 17:19:22,638 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2198c055-bd78-4f8a-9f98-5c78866155bd, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:22,934 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTE1Zjk1OTctNzRiOTQyYTgtOGMwY2MxYWYtNGZiM2ZmMzY=
2025-12-12 17:19:23,008 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjczMmEwMjYtZWQwMWVhY2EtMmNiNzBlMDQtMjBjZjkz
2025-12-12 17:19:23,008 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTE1Zjk1OTctNzRiOTQyYTgtOGMwY2MxYWYtNGZiM2ZmMzY=
2025-12-12 17:19:23,018 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWVjOTA2YzktODkxY2RiOTItOTJhNTgwMmItNjM4NzhmYzQ=
2025-12-12 17:19:23,148 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c60161de-941a-4d5e-8bf9-c5936f55fcb6, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:23,149 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjczMmEwMjYtZWQwMWVhY2EtMmNiNzBlMDQtMjBjZjkz
2025-12-12 17:19:23,152 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzg3NWM1ZjktNjZlZGU1M2UtOWFjOTk2ODEtNzM2NjNiMDU=
2025-12-12 17:19:23,226 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c60161de-941a-4d5e-8bf9-c5936f55fcb6, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=ZTE1Zjk1OTctNzRiOTQyYTgtOGMwY2MxYWYtNGZiM2ZmMzY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:19:23,227 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 206b8027-e9f1-4628-97ba-54be003fbc79, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:23,310 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 206b8027-e9f1-4628-97ba-54be003fbc79, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=YjczMmEwMjYtZWQwMWVhY2EtMmNiNzBlMDQtMjBjZjkz" 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-12-12 17:19:23,228 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=
2025-12-12 17:19:23,228 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWVjOTA2YzktODkxY2RiOTItOTJhNTgwMmItNjM4NzhmYzQ=
2025-12-12 17:19:23,313 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzg3NWM1ZjktNjZlZGU1M2UtOWFjOTk2ODEtNzM2NjNiMDU=
2025-12-12 17:19:23,313 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c60161de-941a-4d5e-8bf9-c5936f55fcb6, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:23,313 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bd6f86cb-5c6a-467d-9f91-94b2863ee68a, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:23,314 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=
2025-12-12 17:19:23,314 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1550ba45-d953-48cf-841d-be2a3af0e29a, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:23,317 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 206b8027-e9f1-4628-97ba-54be003fbc79, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:23,319 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bd6f86cb-5c6a-467d-9f91-94b2863ee68a, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=Yzg3NWM1ZjktNjZlZGU1M2UtOWFjOTk2ODEtNzM2NjNiMDU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:19:23,320 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b964c461-cb4d-4dbc-9ff2-78e8d4260c6b, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:23,321 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1550ba45-d953-48cf-841d-be2a3af0e29a, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=ZWVjOTA2YzktODkxY2RiOTItOTJhNTgwMmItNjM4NzhmYzQ=" 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-12-12 17:19:23,327 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bd6f86cb-5c6a-467d-9f91-94b2863ee68a, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:23,338 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b964c461-cb4d-4dbc-9ff2-78e8d4260c6b, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(9))" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:19:23,343 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1550ba45-d953-48cf-841d-be2a3af0e29a, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:23,345 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWM5MDAwZTctMzJmYzEyZWUtOTk5Mzg1OGItNzYwZDQ3MjM=
2025-12-12 17:19:23,368 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b964c461-cb4d-4dbc-9ff2-78e8d4260c6b, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:23,444 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWM5MDAwZTctMzJmYzEyZWUtOTk5Mzg1OGItNzYwZDQ3MjM=
2025-12-12 17:19:23,446 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 207a5fa7-99c2-402e-b915-5936d94cc0f2, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:23,446 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 207a5fa7-99c2-402e-b915-5936d94cc0f2, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NWM5MDAwZTctMzJmYzEyZWUtOTk5Mzg1OGItNzYwZDQ3MjM=" 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-12-12 17:19:23,447 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 207a5fa7-99c2-402e-b915-5936d94cc0f2, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:23,462 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzlkMzA0MmQtNGQwZGI0YmEtZWJjNWJhZTgtYzE5NDJhZTM=
2025-12-12 17:19:23,570 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzlkMzA0MmQtNGQwZGI0YmEtZWJjNWJhZTgtYzE5NDJhZTM=
2025-12-12 17:19:23,584 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1906f89d-2831-43ee-b5f7-112bc115ae2d, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:23,584 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1906f89d-2831-43ee-b5f7-112bc115ae2d, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NzlkMzA0MmQtNGQwZGI0YmEtZWJjNWJhZTgtYzE5NDJhZTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:19:23,585 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1906f89d-2831-43ee-b5f7-112bc115ae2d, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:26,293 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=
2025-12-12 17:19:26,668 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=
2025-12-12 17:19:26,669 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=
2025-12-12 17:19:26,669 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 79f3b044-a049-434e-a352-688fc760cf94, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:26,670 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 79f3b044-a049-434e-a352-688fc760cf94, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=" 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-12-12 17:19:26,671 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 79f3b044-a049-434e-a352-688fc760cf94, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:26,899 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:19:26,900 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=
2025-12-12 17:19:26,900 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6f301650-3576-4767-bc9c-46aae9600619, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:26,909 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6f301650-3576-4767-bc9c-46aae9600619, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=" 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-12-12 17:19:26,922 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6f301650-3576-4767-bc9c-46aae9600619, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:27,060 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjMzMjBjYWEtOWRhOWNkOWYtNTgzOTkxZTAtYjViOGVmY2M=
2025-12-12 17:19:27,065 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:19:27,066 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 708a2ba0-cefb-4017-a19b-7aed0d553f0c, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:27,066 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 708a2ba0-cefb-4017-a19b-7aed0d553f0c, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=" 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-12-12 17:19:27,067 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 708a2ba0-cefb-4017-a19b-7aed0d553f0c, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:27,441 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=
2025-12-12 17:19:27,471 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:19:30,001 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=
2025-12-12 17:19:30,246 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzg3NWM1ZjktNjZlZGU1M2UtOWFjOTk2ODEtNzM2NjNiMDU=
2025-12-12 17:19:30,247 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjMzMjBjYWEtOWRhOWNkOWYtNTgzOTkxZTAtYjViOGVmY2M=
2025-12-12 17:19:30,247 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6f006570-8aaa-4cfe-915d-cb1bc082f135, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:30,248 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6f006570-8aaa-4cfe-915d-cb1bc082f135, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NjMzMjBjYWEtOWRhOWNkOWYtNTgzOTkxZTAtYjViOGVmY2M=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(5)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(4))" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:19:30,248 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6f006570-8aaa-4cfe-915d-cb1bc082f135, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:30,249 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjczMmEwMjYtZWQwMWVhY2EtMmNiNzBlMDQtMjBjZjkz
2025-12-12 17:19:30,250 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=
2025-12-12 17:19:30,250 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f0308bc9-383f-440d-b3aa-e0d1db2537a6, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:30,251 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f0308bc9-383f-440d-b3aa-e0d1db2537a6, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=" 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-12-12 17:19:30,251 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f0308bc9-383f-440d-b3aa-e0d1db2537a6, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:30,262 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzlkMzA0MmQtNGQwZGI0YmEtZWJjNWJhZTgtYzE5NDJhZTM=
2025-12-12 17:19:30,268 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWVjOTA2YzktODkxY2RiOTItOTJhNTgwMmItNjM4NzhmYzQ=
2025-12-12 17:19:30,279 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWM5MDAwZTctMzJmYzEyZWUtOTk5Mzg1OGItNzYwZDQ3MjM=
2025-12-12 17:19:30,286 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:19:30,291 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c39d8010-495f-41c8-a2d5-1d96e1e46767, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:30,291 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=
2025-12-12 17:19:30,291 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c39d8010-495f-41c8-a2d5-1d96e1e46767, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(5)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(4))" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:19:30,292 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTE1Zjk1OTctNzRiOTQyYTgtOGMwY2MxYWYtNGZiM2ZmMzY=
2025-12-12 17:19:30,292 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9b3d87fa-a13d-40de-8d2a-b5649a0cffa2, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:30,292 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzg3NWM1ZjktNjZlZGU1M2UtOWFjOTk2ODEtNzM2NjNiMDU=
2025-12-12 17:19:30,294 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c39d8010-495f-41c8-a2d5-1d96e1e46767, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:30,295 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9b3d87fa-a13d-40de-8d2a-b5649a0cffa2, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=" 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-12-12 17:19:30,295 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 698adfb8-a65b-4a2e-829e-c6880295cc5f, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:30,296 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjczMmEwMjYtZWQwMWVhY2EtMmNiNzBlMDQtMjBjZjkz
2025-12-12 17:19:30,313 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2a66f762-a0d8-4f9d-aff1-723d0c44f3ce, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:30,312 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 698adfb8-a65b-4a2e-829e-c6880295cc5f, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=Yzg3NWM1ZjktNjZlZGU1M2UtOWFjOTk2ODEtNzM2NjNiMDU=" 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-12-12 17:19:30,306 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9b3d87fa-a13d-40de-8d2a-b5649a0cffa2, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:30,313 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2a66f762-a0d8-4f9d-aff1-723d0c44f3ce, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=YjczMmEwMjYtZWQwMWVhY2EtMmNiNzBlMDQtMjBjZjkz" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2))" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:19:30,315 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 698adfb8-a65b-4a2e-829e-c6880295cc5f, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:30,323 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2a66f762-a0d8-4f9d-aff1-723d0c44f3ce, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:31,362 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzI5OGIxY2YtN2IyODY3MDgtOGJhY2NlMWYtNmZmMTA0YjM=
2025-12-12 17:19:31,381 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZGJjNDZjZGUtNDMzNWFmZjUtYzZmMGE0MmUtZGE3MzhmM2U=
2025-12-12 17:19:31,382 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjMzMjBjYWEtOWRhOWNkOWYtNTgzOTkxZTAtYjViOGVmY2M=
2025-12-12 17:19:31,382 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjczMmEwMjYtZWQwMWVhY2EtMmNiNzBlMDQtMjBjZjkz
2025-12-12 17:19:31,383 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDBlYmNjN2ItMzU2NzA5NjgtNTU2YmE5MGItYzYxOGFlMjA=
2025-12-12 17:19:31,414 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzg3NWM1ZjktNjZlZGU1M2UtOWFjOTk2ODEtNzM2NjNiMDU=
2025-12-12 17:19:31,415 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzlkMzA0MmQtNGQwZGI0YmEtZWJjNWJhZTgtYzE5NDJhZTM=
2025-12-12 17:19:31,415 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ef852754-25fc-4b13-9964-97d586a63ef3, ghrun-lmjjgjftxu.auto.internal:8320): creating call state
2025-12-12 17:19:31,416 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ef852754-25fc-4b13-9964-97d586a63ef3, ghrun-lmjjgjftxu.auto.internal:8320): request = { session_id: "ydb://session/3?node_id=1&id=NzlkMzA0MmQtNGQwZGI0YmEtZWJjNWJhZTgtYzE5NDJhZTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:19:31,417 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ef852754-25fc-4b13-9964-97d586a63ef3, ghrun-lmjjgjftxu.auto.internal:8320): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:19:32,333 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzlkMzA0MmQtNGQwZGI0YmEtZWJjNWJhZTgtYzE5NDJhZTM=
2025-12-12 17:19:32,334 - INFO - root - test_log_deviation: check insert: 17500 12500
2025-12-12 17:19:32,358 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.4
2025-12-12 17:19:32,388 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-12-12 17:19:32,388 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002020/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:8320', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-12-12 17:19:32,428 - DEBUG - ya.test - execute: Command pid: 230332
2025-12-12 17:19:32,677 - DEBUG - ya.test - _save_outputs: Command (pid 230332) rc: 0
2025-12-12 17:19:32,677 - DEBUG - ya.test - _save_outputs: Command (pid 230332) elapsed time (sec): 0.21727991104125977
2025-12-12 17:19:32,677 - DEBUG - ya.test - _save_outputs: Command (pid 230332) idrss: 0
2025-12-12 17:19:32,677 - DEBUG - ya.test - _save_outputs: Command (pid 230332) inblock: 56
2025-12-12 17:19:32,678 - DEBUG - ya.test - _save_outputs: Command (pid 230332) isrss: 0
2025-12-12 17:19:32,678 - DEBUG - ya.test - _save_outputs: Command (pid 230332) ixrss: 0
2025-12-12 17:19:32,678 - DEBUG - ya.test - _save_outputs: Command (pid 230332) majflt: 3
2025-12-12 17:19:32,678 - DEBUG - ya.test - _save_outputs: Command (pid 230332) maxrss: 658520
2025-12-12 17:19:32,699 - DEBUG - ya.test - _save_outputs: Command (pid 230332) minflt: 3489
2025-12-12 17:19:32,705 - DEBUG - ya.test - _save_outputs: Command (pid 230332) msgrcv: 0
2025-12-12 17:19:32,706 - DEBUG - ya.test - _save_outputs: Command (pid 230332) msgsnd: 0
2025-12-12 17:19:32,706 - DEBUG - ya.test - _save_outputs: Command (pid 230332) nivcsw: 17
2025-12-12 17:19:32,706 - DEBUG - ya.test - _save_outputs: Command (pid 230332) nsignals: 0
2025-12-12 17:19:32,707 - DEBUG - ya.test - _save_outputs: Command (pid 230332) nswap: 0
2025-12-12 17:19:32,722 - DEBUG - ya.test - _save_outputs: Command (pid 230332) nvcsw: 199
2025-12-12 17:19:32,723 - DEBUG - ya.test - _save_outputs: Command (pid 230332) oublock: 8
2025-12-12 17:19:32,723 - DEBUG - ya.test - _save_outputs: Command (pid 230332) stime: 0.021967999999999998
2025-12-12 17:19:32,736 - DEBUG - ya.test - _save_outputs: Command (pid 230332) utime: 0.052725
2025-12-12 17:19:32,750 - DEBUG - ya.test - _save_outputs: Command (pid 230332) wtime: 0.219
2025-12-12 17:19:32,764 - DEBUG - ya.test - _save_outputs: Command (pid 230332) output:
Remove path log...
Remove path log...Ok

2025-12-12 17:19:32,764 - DEBUG - ya.test - _save_outputs: Command (pid 230332) errors:

2025-12-12 17:19:32,772 - INFO - ya.test - pytest_runtest_teardown: Test teardown
