2025-12-12 17:13:01,669 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-12 17:13:01,670 - INFO - ya.test - pytest_runtest_setup: test_log_uniform
2025-12-12 17:13:01,670 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-12-12 17:13:01,670 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-12-12 17:13:01,672 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out
2025-12-12 17:13:01,672 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err
2025-12-12 17:13:01,672 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-12 17:13:01,673 - DEBUG - ya.test - execute: Command pid: 11825
2025-12-12 17:13:02,470 - DEBUG - ya.test - _save_outputs: Command (pid 11825) rc: 0
2025-12-12 17:13:02,470 - DEBUG - ya.test - _save_outputs: Command (pid 11825) elapsed time (sec): 0.7853119373321533
2025-12-12 17:13:02,471 - DEBUG - ya.test - _save_outputs: Command (pid 11825) idrss: 0
2025-12-12 17:13:02,471 - DEBUG - ya.test - _save_outputs: Command (pid 11825) inblock: 0
2025-12-12 17:13:02,471 - DEBUG - ya.test - _save_outputs: Command (pid 11825) isrss: 0
2025-12-12 17:13:02,471 - DEBUG - ya.test - _save_outputs: Command (pid 11825) ixrss: 0
2025-12-12 17:13:02,471 - DEBUG - ya.test - _save_outputs: Command (pid 11825) majflt: 0
2025-12-12 17:13:02,471 - DEBUG - ya.test - _save_outputs: Command (pid 11825) maxrss: 544184
2025-12-12 17:13:02,472 - DEBUG - ya.test - _save_outputs: Command (pid 11825) minflt: 16232
2025-12-12 17:13:02,472 - DEBUG - ya.test - _save_outputs: Command (pid 11825) msgrcv: 0
2025-12-12 17:13:02,472 - DEBUG - ya.test - _save_outputs: Command (pid 11825) msgsnd: 0
2025-12-12 17:13:02,472 - DEBUG - ya.test - _save_outputs: Command (pid 11825) nivcsw: 9
2025-12-12 17:13:02,472 - DEBUG - ya.test - _save_outputs: Command (pid 11825) nsignals: 0
2025-12-12 17:13:02,472 - DEBUG - ya.test - _save_outputs: Command (pid 11825) nswap: 0
2025-12-12 17:13:02,473 - DEBUG - ya.test - _save_outputs: Command (pid 11825) nvcsw: 16
2025-12-12 17:13:02,473 - DEBUG - ya.test - _save_outputs: Command (pid 11825) oublock: 8
2025-12-12 17:13:02,473 - DEBUG - ya.test - _save_outputs: Command (pid 11825) stime: 0.652498
2025-12-12 17:13:02,473 - DEBUG - ya.test - _save_outputs: Command (pid 11825) utime: 0.084065
2025-12-12 17:13:02,473 - DEBUG - ya.test - _save_outputs: Command (pid 11825) wtime: 0.787
2025-12-12 17:13:02,473 - DEBUG - ya.test - _save_outputs: Command (pid 11825) 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:13:02,474 - DEBUG - ya.test - _save_outputs: Command (pid 11825) errors:

2025-12-12 17:13:02,474 - 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:13:02,543 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydbd/ydbd
2025-12-12 17:13:02,543 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/17642
2025-12-12 17:13:02,546 - DEBUG - ydb.tests.library.harness.kikimr_runner - start: Working directory: /home/runner/.ya/build/build_root/cfdu/002010/r3tmp/kikimr_cluster_zifohrgx
2025-12-12 17:13:02,576 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydbd/ydbd
2025-12-12 17:13:02,577 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/18341
2025-12-12 17:13:02,577 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/29146
2025-12-12 17:13:02,577 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/24830
2025-12-12 17:13:02,578 - DEBUG - library.python.filelock - acquire: Acquiring FileLock (blocking=False): /home/runner/.ya/build/port_sync_dir/19766
2025-12-12 17:13:02,578 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/cfdu/002010/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/kikimr_configs"
2025-12-12 17:13:02,579 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/cfdu/002010/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/002010/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/logfile_0bvel_8q.log --grpc-port=18341 --mon-port=29146 --ic-port=17642
2025-12-12 17:13:02,579 - DEBUG - ydb.tests.library.harness.kikimr_runner - format_pdisk: Formatting pdisk SectorMap:1:64 on node localhost:18341/1, disk_size 68719476736
2025-12-12 17:13:02,579 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: CFG_DIR_PATH="/home/runner/.ya/build/build_root/cfdu/002010/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/kikimr_configs"
2025-12-12 17:13:02,579 - INFO - ydb.tests.library.harness.kikimr_runner - __make_run_command: Final command: /home/runner/.ya/build/build_root/cfdu/002010/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/002010/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/logfile_0bvel_8q.log --grpc-port=18341 --mon-port=29146 --ic-port=17642
2025-12-12 17:13:02,580 - DEBUG - ya.test - execute: Executing '('/home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydbd/ydbd', 'server', '--suppress-version-check', '--yaml-config=/home/runner/.ya/build/build_root/cfdu/002010/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/kikimr_configs/config.yaml', '--node=1', '--log-file-name=/home/runner/.ya/build/build_root/cfdu/002010/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1/logfile_0bvel_8q.log', '--grpc-port=18341', '--mon-port=29146', '--ic-port=17642')' in '/home/runner/.ya/build/build_root/cfdu/002010/ydb/tests/olap/test-results/py3test/testing_out_stuff/chunk3/testing_out_stuff/test_log_scenario.py.TestLogScenario.test_log_uniform/cluster/node_1' (no wait)
2025-12-12 17:13:02,581 - DEBUG - ya.test - execute: Command pid: 12081
2025-12-12 17:13:02,582 - INFO - ydb.tests.library.harness.kikimr_runner - start: Started node localhost:18341/1
2025-12-12 17:13:02,583 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.0
2025-12-12 17:13:02,583 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.0
2025-12-12 17:13:02,583 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydbd/ydbd', '-V']' in 'None' (waiting)
2025-12-12 17:13:02,584 - DEBUG - ya.test - execute: Command pid: 12083
2025-12-12 17:13:03,592 - DEBUG - ya.test - _save_outputs: Command (pid 12083) rc: 0
2025-12-12 17:13:03,593 - DEBUG - ya.test - _save_outputs: Command (pid 12083) elapsed time (sec): 1.0069408416748047
2025-12-12 17:13:03,593 - DEBUG - ya.test - _save_outputs: Command (pid 12083) idrss: 0
2025-12-12 17:13:03,593 - DEBUG - ya.test - _save_outputs: Command (pid 12083) inblock: 0
2025-12-12 17:13:03,593 - DEBUG - ya.test - _save_outputs: Command (pid 12083) isrss: 0
2025-12-12 17:13:03,593 - DEBUG - ya.test - _save_outputs: Command (pid 12083) ixrss: 0
2025-12-12 17:13:03,593 - DEBUG - ya.test - _save_outputs: Command (pid 12083) majflt: 0
2025-12-12 17:13:03,594 - DEBUG - ya.test - _save_outputs: Command (pid 12083) maxrss: 544184
2025-12-12 17:13:03,594 - DEBUG - ya.test - _save_outputs: Command (pid 12083) minflt: 16232
2025-12-12 17:13:03,594 - DEBUG - ya.test - _save_outputs: Command (pid 12083) msgrcv: 0
2025-12-12 17:13:03,594 - DEBUG - ya.test - _save_outputs: Command (pid 12083) msgsnd: 0
2025-12-12 17:13:03,594 - DEBUG - ya.test - _save_outputs: Command (pid 12083) nivcsw: 14
2025-12-12 17:13:03,594 - DEBUG - ya.test - _save_outputs: Command (pid 12083) nsignals: 0
2025-12-12 17:13:03,594 - DEBUG - ya.test - _save_outputs: Command (pid 12083) nswap: 0
2025-12-12 17:13:03,595 - DEBUG - ya.test - _save_outputs: Command (pid 12083) nvcsw: 18
2025-12-12 17:13:03,595 - DEBUG - ya.test - _save_outputs: Command (pid 12083) oublock: 8
2025-12-12 17:13:03,595 - DEBUG - ya.test - _save_outputs: Command (pid 12083) stime: 0.8422259999999999
2025-12-12 17:13:03,595 - DEBUG - ya.test - _save_outputs: Command (pid 12083) utime: 0.11229599999999999
2025-12-12 17:13:03,595 - DEBUG - ya.test - _save_outputs: Command (pid 12083) wtime: 1.008
2025-12-12 17:13:03,595 - DEBUG - ya.test - _save_outputs: Command (pid 12083) 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:13:03,595 - DEBUG - ya.test - _save_outputs: Command (pid 12083) errors:

2025-12-12 17:13:03,596 - 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:13:16,560 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydbd/ydbd
2025-12-12 17:13:16,561 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18341', '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: 17642\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']
2025-12-12 17:13:16,562 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.1
2025-12-12 17:13:16,562 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.1
2025-12-12 17:13:16,562 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18341', '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: 17642\n      }\n      HostConfigId: 1\n    }\n  }\n}\n']' in 'None' (waiting)
2025-12-12 17:13:16,563 - DEBUG - ya.test - execute: Command pid: 15850
2025-12-12 17:13:18,154 - DEBUG - ya.test - _save_outputs: Command (pid 15850) rc: 0
2025-12-12 17:13:18,154 - DEBUG - ya.test - _save_outputs: Command (pid 15850) elapsed time (sec): 1.5193493366241455
2025-12-12 17:13:18,155 - DEBUG - ya.test - _save_outputs: Command (pid 15850) idrss: 0
2025-12-12 17:13:18,155 - DEBUG - ya.test - _save_outputs: Command (pid 15850) inblock: 0
2025-12-12 17:13:18,155 - DEBUG - ya.test - _save_outputs: Command (pid 15850) isrss: 0
2025-12-12 17:13:18,155 - DEBUG - ya.test - _save_outputs: Command (pid 15850) ixrss: 0
2025-12-12 17:13:18,155 - DEBUG - ya.test - _save_outputs: Command (pid 15850) majflt: 0
2025-12-12 17:13:18,158 - DEBUG - ya.test - _save_outputs: Command (pid 15850) maxrss: 552548
2025-12-12 17:13:18,158 - DEBUG - ya.test - _save_outputs: Command (pid 15850) minflt: 18377
2025-12-12 17:13:18,159 - DEBUG - ya.test - _save_outputs: Command (pid 15850) msgrcv: 0
2025-12-12 17:13:18,159 - DEBUG - ya.test - _save_outputs: Command (pid 15850) msgsnd: 0
2025-12-12 17:13:18,159 - DEBUG - ya.test - _save_outputs: Command (pid 15850) nivcsw: 86
2025-12-12 17:13:18,159 - DEBUG - ya.test - _save_outputs: Command (pid 15850) nsignals: 0
2025-12-12 17:13:18,159 - DEBUG - ya.test - _save_outputs: Command (pid 15850) nswap: 0
2025-12-12 17:13:18,160 - DEBUG - ya.test - _save_outputs: Command (pid 15850) nvcsw: 132
2025-12-12 17:13:18,160 - DEBUG - ya.test - _save_outputs: Command (pid 15850) oublock: 8
2025-12-12 17:13:18,160 - DEBUG - ya.test - _save_outputs: Command (pid 15850) stime: 1.272213
2025-12-12 17:13:18,160 - DEBUG - ya.test - _save_outputs: Command (pid 15850) utime: 0.114225
2025-12-12 17:13:18,160 - DEBUG - ya.test - _save_outputs: Command (pid 15850) wtime: 1.52
2025-12-12 17:13:18,160 - DEBUG - ya.test - _save_outputs: Command (pid 15850) output:
Status {
  Success: true
}
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 5

2025-12-12 17:13:18,160 - DEBUG - ya.test - _save_outputs: Command (pid 15850) errors:

2025-12-12 17:13:18,161 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydbd/ydbd
2025-12-12 17:13:18,162 - DEBUG - ydb.tests.library.harness.kikimr_runner - __call_kikimr_new_cli: Executing command = ['/home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18341', '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:13:18,162 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydbd.out.2
2025-12-12 17:13:18,163 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydbd.err.2
2025-12-12 17:13:18,163 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydbd/ydbd', '--server', 'grpc://localhost:18341', '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:13:18,164 - DEBUG - ya.test - execute: Command pid: 17109
2025-12-12 17:13:19,371 - DEBUG - ya.test - _save_outputs: Command (pid 17109) rc: 0
2025-12-12 17:13:19,372 - DEBUG - ya.test - _save_outputs: Command (pid 17109) elapsed time (sec): 1.2064869403839111
2025-12-12 17:13:19,372 - DEBUG - ya.test - _save_outputs: Command (pid 17109) idrss: 0
2025-12-12 17:13:19,372 - DEBUG - ya.test - _save_outputs: Command (pid 17109) inblock: 0
2025-12-12 17:13:19,372 - DEBUG - ya.test - _save_outputs: Command (pid 17109) isrss: 0
2025-12-12 17:13:19,372 - DEBUG - ya.test - _save_outputs: Command (pid 17109) ixrss: 0
2025-12-12 17:13:19,373 - DEBUG - ya.test - _save_outputs: Command (pid 17109) majflt: 0
2025-12-12 17:13:19,373 - DEBUG - ya.test - _save_outputs: Command (pid 17109) maxrss: 552576
2025-12-12 17:13:19,373 - DEBUG - ya.test - _save_outputs: Command (pid 17109) minflt: 18743
2025-12-12 17:13:19,373 - DEBUG - ya.test - _save_outputs: Command (pid 17109) msgrcv: 0
2025-12-12 17:13:19,373 - DEBUG - ya.test - _save_outputs: Command (pid 17109) msgsnd: 0
2025-12-12 17:13:19,373 - DEBUG - ya.test - _save_outputs: Command (pid 17109) nivcsw: 99
2025-12-12 17:13:19,373 - DEBUG - ya.test - _save_outputs: Command (pid 17109) nsignals: 0
2025-12-12 17:13:19,373 - DEBUG - ya.test - _save_outputs: Command (pid 17109) nswap: 0
2025-12-12 17:13:19,374 - DEBUG - ya.test - _save_outputs: Command (pid 17109) nvcsw: 109
2025-12-12 17:13:19,374 - DEBUG - ya.test - _save_outputs: Command (pid 17109) oublock: 8
2025-12-12 17:13:19,374 - DEBUG - ya.test - _save_outputs: Command (pid 17109) stime: 0.9422189999999999
2025-12-12 17:13:19,374 - DEBUG - ya.test - _save_outputs: Command (pid 17109) utime: 0.144792
2025-12-12 17:13:19,374 - DEBUG - ya.test - _save_outputs: Command (pid 17109) wtime: 1.208
2025-12-12 17:13:19,374 - DEBUG - ya.test - _save_outputs: Command (pid 17109) output:
Status {
  Success: true
}
Success: true
ConfigTxSeqNo: 6

2025-12-12 17:13:19,374 - DEBUG - ya.test - _save_outputs: Command (pid 17109) errors:

2025-12-12 17:13:19,375 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Binding storage pools to domain Root: {'dynamic_storage_pool:1': 'hdd'}
2025-12-12 17:13:19,421 - INFO - ydb.tests.library.harness.kikimr_runner - __run: Cluster started and initialized
2025-12-12 17:13:19,432 - 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:13:19,434 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 17:13:19,435 - 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:13:19,464 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-12-12 17:13:19,464 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, db56a403-31fd-4458-b3fd-0e8531937973, localhost:18341): creating call state
2025-12-12 17:13:19,465 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, db56a403-31fd-4458-b3fd-0e8531937973, localhost:18341): request = { database: "/Root" }
2025-12-12 17:13:19,472 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, db56a403-31fd-4458-b3fd-0e8531937973, localhost:18341): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lmjjgjftxu.auto.internal" port: 18341 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:13:19,473 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lmjjgjftxu.auto.internal:18341, location 1, ssl: False>]>
2025-12-12 17:13:19,473 - 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:13:19,488 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18341
2025-12-12 17:13:19,490 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_uniform)
2025-12-12 17:13:19,491 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydb/ydb
2025-12-12 17:13:19,491 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydb/ydb -e grpc://localhost:18341 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-12-12 17:13:19,492 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out
2025-12-12 17:13:19,492 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err
2025-12-12 17:13:19,492 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18341', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-12-12 17:13:19,493 - DEBUG - ya.test - execute: Command pid: 18152
2025-12-12 17:13:19,921 - DEBUG - ya.test - _save_outputs: Command (pid 18152) rc: 0
2025-12-12 17:13:19,921 - DEBUG - ya.test - _save_outputs: Command (pid 18152) elapsed time (sec): 0.42636942863464355
2025-12-12 17:13:19,921 - DEBUG - ya.test - _save_outputs: Command (pid 18152) idrss: 0
2025-12-12 17:13:19,921 - DEBUG - ya.test - _save_outputs: Command (pid 18152) inblock: 0
2025-12-12 17:13:19,922 - DEBUG - ya.test - _save_outputs: Command (pid 18152) isrss: 0
2025-12-12 17:13:19,922 - DEBUG - ya.test - _save_outputs: Command (pid 18152) ixrss: 0
2025-12-12 17:13:19,922 - DEBUG - ya.test - _save_outputs: Command (pid 18152) majflt: 0
2025-12-12 17:13:19,922 - DEBUG - ya.test - _save_outputs: Command (pid 18152) maxrss: 174512
2025-12-12 17:13:19,922 - DEBUG - ya.test - _save_outputs: Command (pid 18152) minflt: 3502
2025-12-12 17:13:19,922 - DEBUG - ya.test - _save_outputs: Command (pid 18152) msgrcv: 0
2025-12-12 17:13:19,922 - DEBUG - ya.test - _save_outputs: Command (pid 18152) msgsnd: 0
2025-12-12 17:13:19,923 - DEBUG - ya.test - _save_outputs: Command (pid 18152) nivcsw: 33
2025-12-12 17:13:19,923 - DEBUG - ya.test - _save_outputs: Command (pid 18152) nsignals: 0
2025-12-12 17:13:19,923 - DEBUG - ya.test - _save_outputs: Command (pid 18152) nswap: 0
2025-12-12 17:13:19,923 - DEBUG - ya.test - _save_outputs: Command (pid 18152) nvcsw: 159
2025-12-12 17:13:19,923 - DEBUG - ya.test - _save_outputs: Command (pid 18152) oublock: 8
2025-12-12 17:13:19,923 - DEBUG - ya.test - _save_outputs: Command (pid 18152) stime: 0.038519
2025-12-12 17:13:19,923 - DEBUG - ya.test - _save_outputs: Command (pid 18152) utime: 0.045918999999999995
2025-12-12 17:13:19,923 - DEBUG - ya.test - _save_outputs: Command (pid 18152) wtime: 0.428
2025-12-12 17:13:19,924 - DEBUG - ya.test - _save_outputs: Command (pid 18152) output:
Init tables ...
Init tables ...Ok

2025-12-12 17:13:19,924 - DEBUG - ya.test - _save_outputs: Command (pid 18152) errors:

2025-12-12 17:13:19,924 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-12-12 17:13:19,924 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydb/ydb -e grpc://localhost:18341 -d /Root workload log --path log run bulk_upsert --seconds 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-12-12 17:13:19,925 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.0
2025-12-12 17:13:19,925 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.0
2025-12-12 17:13:19,926 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18341', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-12-12 17:13:19,926 - DEBUG - ya.test - execute: Command pid: 18390
2025-12-12 17:13:50,172 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-12-12 17:13:50,172 - DEBUG - ya.test - _save_outputs: Command (pid 18390) rc: 0
2025-12-12 17:13:50,173 - DEBUG - ya.test - _save_outputs: Command (pid 18390) elapsed time (sec): 30.227715492248535
2025-12-12 17:13:50,173 - DEBUG - ya.test - _save_outputs: Command (pid 18390) wtime: 30.246
2025-12-12 17:13:50,173 - DEBUG - ya.test - _save_outputs: Command (pid 18390) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	    415	415	0	0	21	36	40	44
2	    433	433	0	0	21	33	46	51
3	    421	421	0	0	23	33	35	39
4	    377	377	0	0	23	46	55	70
5	    430	430	0	0	20	29	110	115
6	    461	461	0	0	20	27	37	44
7	    480	480	0	0	20	23	27	31
8	    485	485	0	0	20	22	25	30
9	    474	474	0	0	20	24	32	36
10	    475	475	0	0	20	24	38	40
11	    393	393	0	0	20	52	116	118
12	    436	436	0	0	20	43	93	107
13	    476	476	0	0	20	22	39	48
14	    456	456	0	0	20	34	55	58
15	    479	479	0	0	20	23	30	31
16	    480	480	0	0	20	24	26	28
17	    450	450	0	0	20	29	50	51
18	    407	407	0	0	20	35	134	136
19	    339	339	0	0	21	43	112	131
20	    387	387	0	0	22	64	131	171
21	    390	390	0	0	22	35	112	115
22	    402	402	0	0	22	35	98	103
23	    466	466	0	0	20	27	32	41
24	    483	483	0	0	20	25	26	28
25	    485	485	0	0	20	22	25	29
26	    457	457	0	0	20	27	47	50
27	    383	383	0	0	21	32	76	84
28	    287	287	0	0	29	104	167	171
29	    427	427	0	0	21	36	44	46
30	    447	447	0	0	21	38	59	64

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	  12981	432.7	0	0	20	33	66	171

2025-12-12 17:13:50,173 - DEBUG - ya.test - _save_outputs: Command (pid 18390) errors:

2025-12-12 17:13:50,174 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 0 < 100, will create new one.
2025-12-12 17:13:50,175 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 0f60dd49-6319-4862-9ae7-ad93ab3190d6, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:13:50,175 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 0f60dd49-6319-4862-9ae7-ad93ab3190d6, ghrun-lmjjgjftxu.auto.internal:18341): request = {  }
2025-12-12 17:13:50,182 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 0f60dd49-6319-4862-9ae7-ad93ab3190d6, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=" node_id: 1 }
2025-12-12 17:13:50,183 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 8050c5f5-b98b-48cb-804d-71168d8bc212, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:13:50,183 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 8050c5f5-b98b-48cb-804d-71168d8bc212, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=" }
2025-12-12 17:13:50,184 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 8050c5f5-b98b-48cb-804d-71168d8bc212, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:13:50,187 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:13:50,188 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 578b11d8-97bc-4284-ace0-caa2e92813cc, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:13:50,188 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 578b11d8-97bc-4284-ace0-caa2e92813cc, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=" 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:13:50,189 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 578b11d8-97bc-4284-ace0-caa2e92813cc, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:13:56,215 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:13:56,215 - INFO - root - test_log_uniform: Count rows after insert 12973 before wait
2025-12-12 17:13:56,216 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:13:56,216 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2c489d1b-fef0-411c-ab8a-90e042748737, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:13:56,216 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2c489d1b-fef0-411c-ab8a-90e042748737, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=" 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:13:56,217 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2c489d1b-fef0-411c-ab8a-90e042748737, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:00,056 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:14:00,056 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:14:00,058 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1e1c6bc7-c723-4311-80ae-13b5ef1dbcb3, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:00,059 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1e1c6bc7-c723-4311-80ae-13b5ef1dbcb3, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=" 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:14:00,060 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1e1c6bc7-c723-4311-80ae-13b5ef1dbcb3, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:03,212 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:14:03,213 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-12-12 17:14:03,213 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydb/ydb -e grpc://localhost:18341 -d /Root workload log --path log run bulk_upsert --seconds 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-12-12 17:14:03,215 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.1
2025-12-12 17:14:03,215 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.1
2025-12-12 17:14:03,215 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18341', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-12-12 17:14:03,216 - DEBUG - ya.test - execute: Command pid: 50704
2025-12-12 17:14:03,220 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-12-12 17:14:03,221 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-12-12 17:14:03,221 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:14:03,222 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydb/ydb -e grpc://localhost:18341 -d /Root workload log --path log run insert --seconds 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-12-12 17:14:03,223 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydb/ydb -e grpc://localhost:18341 -d /Root workload log --path log run upsert --seconds 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-12-12 17:14:03,224 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b99ea347-5bb6-465e-9d31-ef15f05138ad, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:03,227 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b99ea347-5bb6-465e-9d31-ef15f05138ad, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=" 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:14:03,224 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 1 < 100, will create new one.
2025-12-12 17:14:03,237 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, c9795f4e-72f5-4aee-b9e6-276d5132a978, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:03,237 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, c9795f4e-72f5-4aee-b9e6-276d5132a978, ghrun-lmjjgjftxu.auto.internal:18341): request = {  }
2025-12-12 17:14:03,238 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.2
2025-12-12 17:14:03,239 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.2
2025-12-12 17:14:03,239 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18341', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'insert', '--seconds', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-12-12 17:14:03,228 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b99ea347-5bb6-465e-9d31-ef15f05138ad, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:03,249 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, c9795f4e-72f5-4aee-b9e6-276d5132a978, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZmUzYzMzMGMtZWQwMGIxMWEtM2Q3OGU0YmMtYjFmNDdmNWI=" node_id: 1 }
2025-12-12 17:14:03,250 - DEBUG - ya.test - execute: Command pid: 50726
2025-12-12 17:14:03,251 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.3
2025-12-12 17:14:03,256 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.3
2025-12-12 17:14:03,257 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18341', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'upsert', '--seconds', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-12-12 17:14:03,259 - DEBUG - ya.test - execute: Command pid: 50790
2025-12-12 17:14:03,254 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 5531b911-7f26-4c36-89be-d70768719b05, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:03,260 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 5531b911-7f26-4c36-89be-d70768719b05, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZmUzYzMzMGMtZWQwMGIxMWEtM2Q3OGU0YmMtYjFmNDdmNWI=" }
2025-12-12 17:14:03,260 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 5531b911-7f26-4c36-89be-d70768719b05, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:03,266 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZmUzYzMzMGMtZWQwMGIxMWEtM2Q3OGU0YmMtYjFmNDdmNWI=
2025-12-12 17:14:03,268 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9526546b-fdc8-4a97-a071-d3e79adfbbf4, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:03,269 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9526546b-fdc8-4a97-a071-d3e79adfbbf4, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZmUzYzMzMGMtZWQwMGIxMWEtM2Q3OGU0YmMtYjFmNDdmNWI=" 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:14:03,270 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9526546b-fdc8-4a97-a071-d3e79adfbbf4, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:03,271 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 2 < 100, will create new one.
2025-12-12 17:14:03,272 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, b2e21944-2041-4e1e-8e11-ae9de8cffcd1, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:03,272 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, b2e21944-2041-4e1e-8e11-ae9de8cffcd1, ghrun-lmjjgjftxu.auto.internal:18341): request = {  }
2025-12-12 17:14:03,281 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, b2e21944-2041-4e1e-8e11-ae9de8cffcd1, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NjYyNzNmYzktODNhYjczODItMzhiM2FmMDYtZDY3NjA4OWY=" node_id: 1 }
2025-12-12 17:14:03,281 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, cd63ebf5-7acd-4f5b-a18b-c3e3ea3fd2f0, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:03,282 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, cd63ebf5-7acd-4f5b-a18b-c3e3ea3fd2f0, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=NjYyNzNmYzktODNhYjczODItMzhiM2FmMDYtZDY3NjA4OWY=" }
2025-12-12 17:14:03,283 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, cd63ebf5-7acd-4f5b-a18b-c3e3ea3fd2f0, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:03,296 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NjYyNzNmYzktODNhYjczODItMzhiM2FmMDYtZDY3NjA4OWY=
2025-12-12 17:14:03,297 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8f0d9d4f-48d3-4053-8a01-0e088af3a485, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:03,297 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8f0d9d4f-48d3-4053-8a01-0e088af3a485, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=NjYyNzNmYzktODNhYjczODItMzhiM2FmMDYtZDY3NjA4OWY=" 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:14:03,298 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8f0d9d4f-48d3-4053-8a01-0e088af3a485, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:03,299 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 3 < 100, will create new one.
2025-12-12 17:14:03,299 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 8f28fd7d-6993-4a4d-a09b-4fc2f692c5e1, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:03,300 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 8f28fd7d-6993-4a4d-a09b-4fc2f692c5e1, ghrun-lmjjgjftxu.auto.internal:18341): request = {  }
2025-12-12 17:14:03,302 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 8f28fd7d-6993-4a4d-a09b-4fc2f692c5e1, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=" node_id: 1 }
2025-12-12 17:14:03,303 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 65c3a44e-683f-4f07-9389-92294e031a98, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:03,303 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 65c3a44e-683f-4f07-9389-92294e031a98, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=" }
2025-12-12 17:14:03,304 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 65c3a44e-683f-4f07-9389-92294e031a98, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:03,424 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=
2025-12-12 17:14:03,425 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d57bcb2b-43ee-44ee-854c-4f2dc523897d, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:03,425 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d57bcb2b-43ee-44ee-854c-4f2dc523897d, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=" 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:14:03,426 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d57bcb2b-43ee-44ee-854c-4f2dc523897d, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:03,427 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 4 < 100, will create new one.
2025-12-12 17:14:03,427 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 7a8d7f03-2357-4cfb-8242-99bccab49df5, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:03,427 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 7a8d7f03-2357-4cfb-8242-99bccab49df5, ghrun-lmjjgjftxu.auto.internal:18341): request = {  }
2025-12-12 17:14:03,990 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 7a8d7f03-2357-4cfb-8242-99bccab49df5, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=Mzg3ZjliZTYtYzI5Y2YxYzYtYzI0NmY3OWQtYzYzYmM2NmY=" node_id: 1 }
2025-12-12 17:14:03,991 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, f8052573-f815-41a0-b660-36c74460d8eb, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:03,991 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, f8052573-f815-41a0-b660-36c74460d8eb, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=Mzg3ZjliZTYtYzI5Y2YxYzYtYzI0NmY3OWQtYzYzYmM2NmY=" }
2025-12-12 17:14:03,993 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, f8052573-f815-41a0-b660-36c74460d8eb, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:04,142 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=Mzg3ZjliZTYtYzI5Y2YxYzYtYzI0NmY3OWQtYzYzYmM2NmY=
2025-12-12 17:14:04,143 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8020de30-60c6-48e9-a5fe-0ca69430745f, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:04,144 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8020de30-60c6-48e9-a5fe-0ca69430745f, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=Mzg3ZjliZTYtYzI5Y2YxYzYtYzI0NmY3OWQtYzYzYmM2NmY=" 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:14:04,145 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8020de30-60c6-48e9-a5fe-0ca69430745f, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:04,146 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 5 < 100, will create new one.
2025-12-12 17:14:04,147 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 4b5a656d-7656-417a-a486-b8d647cdba41, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:04,147 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 4b5a656d-7656-417a-a486-b8d647cdba41, ghrun-lmjjgjftxu.auto.internal:18341): request = {  }
2025-12-12 17:14:04,383 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 4b5a656d-7656-417a-a486-b8d647cdba41, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YmRiYzAwMmEtMzJkNjBhNTctNzI4ZGU0YzYtNzk2NjhiMWY=" node_id: 1 }
2025-12-12 17:14:04,383 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 69e700c0-b437-4a38-9d30-9646b939fc8b, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:04,384 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 69e700c0-b437-4a38-9d30-9646b939fc8b, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=YmRiYzAwMmEtMzJkNjBhNTctNzI4ZGU0YzYtNzk2NjhiMWY=" }
2025-12-12 17:14:04,384 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 69e700c0-b437-4a38-9d30-9646b939fc8b, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:04,545 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YmRiYzAwMmEtMzJkNjBhNTctNzI4ZGU0YzYtNzk2NjhiMWY=
2025-12-12 17:14:04,545 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fa1db61e-a5d5-4022-ae1a-b0ecb1cba86a, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:04,546 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fa1db61e-a5d5-4022-ae1a-b0ecb1cba86a, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=YmRiYzAwMmEtMzJkNjBhNTctNzI4ZGU0YzYtNzk2NjhiMWY=" 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:14:04,547 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fa1db61e-a5d5-4022-ae1a-b0ecb1cba86a, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:04,548 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 6 < 100, will create new one.
2025-12-12 17:14:04,549 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 1e7104fd-edb6-4c32-af64-09361773d3df, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:04,549 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 1e7104fd-edb6-4c32-af64-09361773d3df, ghrun-lmjjgjftxu.auto.internal:18341): request = {  }
2025-12-12 17:14:04,585 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 1e7104fd-edb6-4c32-af64-09361773d3df, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==" node_id: 1 }
2025-12-12 17:14:04,585 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, d1d930c0-b11d-4a68-9d13-2037b508c4ba, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:04,586 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, d1d930c0-b11d-4a68-9d13-2037b508c4ba, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==" }
2025-12-12 17:14:04,586 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, d1d930c0-b11d-4a68-9d13-2037b508c4ba, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:04,948 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==
2025-12-12 17:14:04,949 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 949ccf8b-3812-4307-91de-490e7a96e991, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:04,949 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 949ccf8b-3812-4307-91de-490e7a96e991, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==" 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:14:04,950 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 949ccf8b-3812-4307-91de-490e7a96e991, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:04,953 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 7 < 100, will create new one.
2025-12-12 17:14:04,986 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 3df336e4-cae8-4853-85a8-c9b366ab3cb6, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:04,987 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 3df336e4-cae8-4853-85a8-c9b366ab3cb6, ghrun-lmjjgjftxu.auto.internal:18341): request = {  }
2025-12-12 17:14:05,090 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 3df336e4-cae8-4853-85a8-c9b366ab3cb6, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=ZDBlM2RjMjUtOTVlNDJjMmQtODQ4ZjQ0NTktNjI5MTg2MTM=" node_id: 1 }
2025-12-12 17:14:05,090 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 952bac66-3009-4d67-9435-dfc958036cf9, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:05,090 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 952bac66-3009-4d67-9435-dfc958036cf9, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZDBlM2RjMjUtOTVlNDJjMmQtODQ4ZjQ0NTktNjI5MTg2MTM=" }
2025-12-12 17:14:05,091 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 952bac66-3009-4d67-9435-dfc958036cf9, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:05,317 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=ZDBlM2RjMjUtOTVlNDJjMmQtODQ4ZjQ0NTktNjI5MTg2MTM=
2025-12-12 17:14:05,317 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cd2fcc22-e5b7-45f4-82de-44c533da123a, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:05,318 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cd2fcc22-e5b7-45f4-82de-44c533da123a, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZDBlM2RjMjUtOTVlNDJjMmQtODQ4ZjQ0NTktNjI5MTg2MTM=" 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:14:05,319 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cd2fcc22-e5b7-45f4-82de-44c533da123a, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:05,320 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 8 < 100, will create new one.
2025-12-12 17:14:05,320 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, 96af7143-ddf2-4cc1-b6da-95b485a98ec4, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:05,321 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, 96af7143-ddf2-4cc1-b6da-95b485a98ec4, ghrun-lmjjgjftxu.auto.internal:18341): request = {  }
2025-12-12 17:14:05,640 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, 96af7143-ddf2-4cc1-b6da-95b485a98ec4, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=Y2Q3NzJhZDgtZjUxNTAxOWYtZWM1ZGE4NGMtYzM2Y2M5NGI=" node_id: 1 }
2025-12-12 17:14:05,641 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 941a0d06-aa60-4dc8-8fe6-8c5e7f23a122, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:05,641 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 941a0d06-aa60-4dc8-8fe6-8c5e7f23a122, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=Y2Q3NzJhZDgtZjUxNTAxOWYtZWM1ZGE4NGMtYzM2Y2M5NGI=" }
2025-12-12 17:14:05,642 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 941a0d06-aa60-4dc8-8fe6-8c5e7f23a122, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:05,681 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=Y2Q3NzJhZDgtZjUxNTAxOWYtZWM1ZGE4NGMtYzM2Y2M5NGI=
2025-12-12 17:14:05,681 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e408ea54-39fd-4396-b7a1-ee7a58b3e04b, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:05,682 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e408ea54-39fd-4396-b7a1-ee7a58b3e04b, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=Y2Q3NzJhZDgtZjUxNTAxOWYtZWM1ZGE4NGMtYzM2Y2M5NGI=" 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:14:05,684 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e408ea54-39fd-4396-b7a1-ee7a58b3e04b, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:05,684 - DEBUG - ydb.query.pool - acquire: Session pool is not large enough: 9 < 100, will create new one.
2025-12-12 17:14:05,686 - DEBUG - ydb.connection - _prepare_call: RpcState(CreateSession, d2e4a87a-8867-4f79-8bb6-83c501a6221a, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:05,686 - DEBUG - ydb.connection - _log_request: RpcState(CreateSession, d2e4a87a-8867-4f79-8bb6-83c501a6221a, ghrun-lmjjgjftxu.auto.internal:18341): request = {  }
2025-12-12 17:14:06,011 - DEBUG - ydb.connection - _log_response: RpcState(CreateSession, d2e4a87a-8867-4f79-8bb6-83c501a6221a, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS session_id: "ydb://session/3?node_id=1&id=M2ZlNDc2MGItMTBhMzYzYTUtZDM2YTEyODItMjRlMjdjYg==" node_id: 1 }
2025-12-12 17:14:06,011 - DEBUG - ydb.connection - _prepare_call: RpcState(AttachSession, 9ef6e7b9-b526-46cf-9dcf-a657546c5cfe, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:06,012 - DEBUG - ydb.connection - _log_request: RpcState(AttachSession, 9ef6e7b9-b526-46cf-9dcf-a657546c5cfe, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=M2ZlNDc2MGItMTBhMzYzYTUtZDM2YTEyODItMjRlMjdjYg==" }
2025-12-12 17:14:06,012 - DEBUG - ydb.connection - _log_response: RpcState(AttachSession, 9ef6e7b9-b526-46cf-9dcf-a657546c5cfe, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:06,037 - DEBUG - ydb.query.pool - _create_new_session: New session was created for pool. Session id: ydb://session/3?node_id=1&id=M2ZlNDc2MGItMTBhMzYzYTUtZDM2YTEyODItMjRlMjdjYg==
2025-12-12 17:14:06,037 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0c1dbc49-d135-4e4a-af21-b5675095ed28, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:06,038 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0c1dbc49-d135-4e4a-af21-b5675095ed28, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=M2ZlNDc2MGItMTBhMzYzYTUtZDM2YTEyODItMjRlMjdjYg==" 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:14:06,043 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0c1dbc49-d135-4e4a-af21-b5675095ed28, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:34,572 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-12-12 17:14:34,573 - DEBUG - ya.test - _save_outputs: Command (pid 50704) rc: 0
2025-12-12 17:14:34,573 - DEBUG - ya.test - _save_outputs: Command (pid 50704) elapsed time (sec): 31.355485677719116
2025-12-12 17:14:34,573 - DEBUG - ya.test - _save_outputs: Command (pid 50704) wtime: 31.357
2025-12-12 17:14:34,573 - DEBUG - ya.test - _save_outputs: Command (pid 50704) 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	      0	0	0	0	0	0	0	0
4	      0	0	0	0	0	0	0	0
5	      8	8	0	0	4767	4991	4991	4991
6	      2	2	0	0	5119	5151	5151	5151
7	      0	0	0	0	0	0	0	0
8	      9	9	0	0	2671	2735	2735	2735
9	      1	1	0	0	3023	3023	3023	3023
10	      0	0	0	0	0	0	0	0
11	      0	0	0	0	0	0	0	0
12	      4	4	0	0	3855	4351	4351	4351
13	      6	6	0	0	4511	4639	4639	4639
14	      0	0	0	0	0	0	0	0
15	      0	0	0	0	0	0	0	0
16	      1	1	0	0	4047	4047	4047	4047
17	      9	9	0	0	4671	5471	5471	5471
18	      0	0	0	0	0	0	0	0
19	      1	1	0	0	3743	3743	3743	3743
20	     19	19	0	0	899	2543	2559	2559
21	     40	40	0	0	247	319	327	327
22	     20	20	0	0	377	583	643	643
23	     12	12	0	0	615	735	835	835
24	     29	29	0	0	501	903	923	923
25	    110	110	0	0	97	129	133	133
26	     96	96	0	0	97	140	147	147
27	     48	48	0	0	159	435	453	453
28	     52	52	0	0	183	259	287	289
29	     49	49	0	0	169	293	321	321
30	     47	47	0	0	305	427	451	451

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    563	18.7667	0	0	163	3855	4895	5471

2025-12-12 17:14:34,573 - DEBUG - ya.test - _save_outputs: Command (pid 50704) errors:

2025-12-12 17:14:34,620 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-12-12 17:14:34,621 - DEBUG - ya.test - _save_outputs: Command (pid 50790) rc: 0
2025-12-12 17:14:34,621 - DEBUG - ya.test - _save_outputs: Command (pid 50790) elapsed time (sec): 31.36107563972473
2025-12-12 17:14:34,622 - DEBUG - ya.test - _save_outputs: Command (pid 50790) wtime: 31.363
2025-12-12 17:14:34,622 - DEBUG - ya.test - _save_outputs: Command (pid 50790) 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
4	      0	0	0	10	0	0	0	0
5	      0	0	0	10	0	0	0	0
6	      0	0	0	0	0	0	0	0
7	      0	0	0	10	0	0	0	0
8	      0	0	0	10	0	0	0	0
9	      0	0	0	10	0	0	0	0
10	      0	0	0	10	0	0	0	0
11	      0	0	0	10	0	0	0	0
12	      0	0	0	10	0	0	0	0
13	      0	0	0	1	0	0	0	0
14	      0	0	0	9	0	0	0	0
15	      0	0	0	10	0	0	0	0
16	      0	0	0	10	0	0	0	0
17	      0	0	0	10	0	0	0	0
18	      0	0	0	1	0	0	0	0
19	      0	0	0	10	0	0	0	0
20	      9	9	0	10	711	743	743	743
21	     30	30	0	0	313	363	387	387
22	     15	15	0	0	497	687	747	747
23	     12	12	0	0	767	839	867	867
24	     22	22	0	2	515	995	999	999
25	     83	83	0	0	125	157	175	176
26	     75	75	0	0	123	163	175	180
27	     40	40	0	0	183	481	485	485
28	     43	43	0	0	228	313	321	321
29	     42	42	0	0	227	349	365	365
30	     37	37	0	0	421	515	535	535

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    408	13.6	0	163	191	743	923	999

2025-12-12 17:14:34,622 - DEBUG - ya.test - _save_outputs: Command (pid 50790) errors:

2025-12-12 17:14:34,668 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-12-12 17:14:34,669 - DEBUG - ya.test - _save_outputs: Command (pid 50726) rc: 0
2025-12-12 17:14:34,669 - DEBUG - ya.test - _save_outputs: Command (pid 50726) elapsed time (sec): 31.413413047790527
2025-12-12 17:14:34,670 - DEBUG - ya.test - _save_outputs: Command (pid 50726) wtime: 31.429
2025-12-12 17:14:34,670 - DEBUG - ya.test - _save_outputs: Command (pid 50726) 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
4	      0	0	0	10	0	0	0	0
5	      0	0	0	10	0	0	0	0
6	      0	0	0	0	0	0	0	0
7	      0	0	0	10	0	0	0	0
8	      0	0	0	10	0	0	0	0
9	      0	0	0	10	0	0	0	0
10	      0	0	0	10	0	0	0	0
11	      0	0	0	10	0	0	0	0
12	      0	0	0	10	0	0	0	0
13	      0	0	0	5	0	0	0	0
14	      0	0	0	5	0	0	0	0
15	      0	0	0	10	0	0	0	0
16	      0	0	0	10	0	0	0	0
17	      0	0	0	10	0	0	0	0
18	      0	0	0	1	0	0	0	0
19	      0	0	0	10	0	0	0	0
20	      8	8	0	10	683	807	807	807
21	     22	22	0	0	371	531	703	703
22	     14	14	0	0	611	763	819	819
23	      5	5	0	5	847	979	979	979
24	      8	8	0	7	619	1039	1039	1039
25	     51	51	0	0	214	287	307	437
26	     48	48	0	0	195	259	289	289
27	     25	25	0	0	333	539	543	543
28	     30	30	0	0	323	511	599	599
29	     27	27	0	1	313	443	497	497
30	     29	29	0	0	531	695	735	735

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    267	8.9	0	174	307	743	931	1039

2025-12-12 17:14:34,670 - DEBUG - ya.test - _save_outputs: Command (pid 50726) errors:

2025-12-12 17:14:45,448 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-12-12 17:14:45,448 - 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:14:45,475 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-12-12 17:14:45,475 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 6d344c7a-476b-4502-9a4e-904ac3e715d6, localhost:18341): creating call state
2025-12-12 17:14:45,476 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 6d344c7a-476b-4502-9a4e-904ac3e715d6, localhost:18341): request = { database: "/Root" }
2025-12-12 17:14:45,492 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 6d344c7a-476b-4502-9a4e-904ac3e715d6, localhost:18341): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-lmjjgjftxu.auto.internal" port: 18341 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:14:45,497 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-lmjjgjftxu.auto.internal:18341, location 1, ssl: False>]>
2025-12-12 17:14:45,544 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18341
2025-12-12 17:14:53,016 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=
2025-12-12 17:14:53,024 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=
2025-12-12 17:14:53,024 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 927b59c4-f22c-474e-8e83-64870710eed0, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:53,048 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 927b59c4-f22c-474e-8e83-64870710eed0, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=" 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:14:53,051 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 927b59c4-f22c-474e-8e83-64870710eed0, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:53,638 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:14:53,638 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:14:53,652 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8e742164-a200-4940-a804-b0eecb31e51a, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:53,652 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8e742164-a200-4940-a804-b0eecb31e51a, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:14:53,726 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8e742164-a200-4940-a804-b0eecb31e51a, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:54,267 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Mzg3ZjliZTYtYzI5Y2YxYzYtYzI0NmY3OWQtYzYzYmM2NmY=
2025-12-12 17:14:54,274 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Mzg3ZjliZTYtYzI5Y2YxYzYtYzI0NmY3OWQtYzYzYmM2NmY=
2025-12-12 17:14:54,275 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be2e6dab-dfe7-4a01-a8ed-94ec1ed3996c, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:54,276 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be2e6dab-dfe7-4a01-a8ed-94ec1ed3996c, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=Mzg3ZjliZTYtYzI5Y2YxYzYtYzI0NmY3OWQtYzYzYmM2NmY=" 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:14:54,281 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be2e6dab-dfe7-4a01-a8ed-94ec1ed3996c, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:54,293 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmUzYzMzMGMtZWQwMGIxMWEtM2Q3OGU0YmMtYjFmNDdmNWI=
2025-12-12 17:14:54,293 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmUzYzMzMGMtZWQwMGIxMWEtM2Q3OGU0YmMtYjFmNDdmNWI=
2025-12-12 17:14:54,294 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 08de8e43-928b-48c5-a98f-05dbc7f65d60, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:54,294 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 08de8e43-928b-48c5-a98f-05dbc7f65d60, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZmUzYzMzMGMtZWQwMGIxMWEtM2Q3OGU0YmMtYjFmNDdmNWI=" 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:14:54,297 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 08de8e43-928b-48c5-a98f-05dbc7f65d60, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:54,698 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDBlM2RjMjUtOTVlNDJjMmQtODQ4ZjQ0NTktNjI5MTg2MTM=
2025-12-12 17:14:54,756 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDBlM2RjMjUtOTVlNDJjMmQtODQ4ZjQ0NTktNjI5MTg2MTM=
2025-12-12 17:14:54,757 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0f261783-9afe-414d-933c-e7ff190364d8, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:54,827 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0f261783-9afe-414d-933c-e7ff190364d8, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZDBlM2RjMjUtOTVlNDJjMmQtODQ4ZjQ0NTktNjI5MTg2MTM=" 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:14:54,827 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0f261783-9afe-414d-933c-e7ff190364d8, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:55,332 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2Q3NzJhZDgtZjUxNTAxOWYtZWM1ZGE4NGMtYzM2Y2M5NGI=
2025-12-12 17:14:55,332 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2Q3NzJhZDgtZjUxNTAxOWYtZWM1ZGE4NGMtYzM2Y2M5NGI=
2025-12-12 17:14:55,332 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, becc8570-d28a-4e36-9953-e6c345d60d69, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:55,333 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, becc8570-d28a-4e36-9953-e6c345d60d69, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=Y2Q3NzJhZDgtZjUxNTAxOWYtZWM1ZGE4NGMtYzM2Y2M5NGI=" 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:14:55,334 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, becc8570-d28a-4e36-9953-e6c345d60d69, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:55,427 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjYyNzNmYzktODNhYjczODItMzhiM2FmMDYtZDY3NjA4OWY=
2025-12-12 17:14:55,427 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjYyNzNmYzktODNhYjczODItMzhiM2FmMDYtZDY3NjA4OWY=
2025-12-12 17:14:55,428 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b079264e-6658-4ecc-aa2a-58640b27ffd3, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:55,428 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b079264e-6658-4ecc-aa2a-58640b27ffd3, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=NjYyNzNmYzktODNhYjczODItMzhiM2FmMDYtZDY3NjA4OWY=" 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:14:55,429 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b079264e-6658-4ecc-aa2a-58640b27ffd3, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:55,569 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmRiYzAwMmEtMzJkNjBhNTctNzI4ZGU0YzYtNzk2NjhiMWY=
2025-12-12 17:14:55,624 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmRiYzAwMmEtMzJkNjBhNTctNzI4ZGU0YzYtNzk2NjhiMWY=
2025-12-12 17:14:55,624 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9ec7bc68-e69d-4159-8afd-2f91195cb742, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:55,625 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9ec7bc68-e69d-4159-8afd-2f91195cb742, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=YmRiYzAwMmEtMzJkNjBhNTctNzI4ZGU0YzYtNzk2NjhiMWY=" 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:14:55,641 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9ec7bc68-e69d-4159-8afd-2f91195cb742, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:55,788 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2ZlNDc2MGItMTBhMzYzYTUtZDM2YTEyODItMjRlMjdjYg==
2025-12-12 17:14:55,797 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2ZlNDc2MGItMTBhMzYzYTUtZDM2YTEyODItMjRlMjdjYg==
2025-12-12 17:14:55,816 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 28e99cb4-2a32-407c-8328-eddf596a5b93, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:55,817 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 28e99cb4-2a32-407c-8328-eddf596a5b93, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=M2ZlNDc2MGItMTBhMzYzYTUtZDM2YTEyODItMjRlMjdjYg==" 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:14:55,837 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 28e99cb4-2a32-407c-8328-eddf596a5b93, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:14:56,596 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==
2025-12-12 17:14:56,596 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==
2025-12-12 17:14:56,597 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 33e9fef2-7f82-4004-af76-7a86c37dfd26, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:14:56,598 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 33e9fef2-7f82-4004-af76-7a86c37dfd26, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==" 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:14:56,647 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 33e9fef2-7f82-4004-af76-7a86c37dfd26, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:00,450 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=
2025-12-12 17:15:00,486 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=
2025-12-12 17:15:00,487 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 48a2e0c2-a5d4-405b-851a-aa55488479c0, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:00,487 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 48a2e0c2-a5d4-405b-851a-aa55488479c0, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=" 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:15:00,488 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 48a2e0c2-a5d4-405b-851a-aa55488479c0, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:01,095 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=
2025-12-12 17:15:01,095 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=
2025-12-12 17:15:01,096 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c9770f0c-55da-4092-8904-6ce4a38637ca, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:01,096 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c9770f0c-55da-4092-8904-6ce4a38637ca, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=" 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:15:01,097 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c9770f0c-55da-4092-8904-6ce4a38637ca, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:01,400 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=
2025-12-12 17:15:01,802 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:15:01,868 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=
2025-12-12 17:15:02,029 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8c686b51-7e64-4b11-9a36-555967c78209, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:02,030 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8c686b51-7e64-4b11-9a36-555967c78209, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:15:02,042 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8c686b51-7e64-4b11-9a36-555967c78209, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:02,468 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=
2025-12-12 17:15:02,468 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:15:02,469 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e3a3bf2c-88cd-4c33-a963-ecda012c0317, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:02,472 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e3a3bf2c-88cd-4c33-a963-ecda012c0317, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(1))" } stats_mode: STATS_MODE_NONE }
2025-12-12 17:15:02,473 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e3a3bf2c-88cd-4c33-a963-ecda012c0317, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:02,736 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:15:04,074 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==
2025-12-12 17:15:04,110 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=
2025-12-12 17:15:04,110 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dfff7946-336a-4306-a98b-043a3f5f1501, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:04,111 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dfff7946-336a-4306-a98b-043a3f5f1501, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=" 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:15:04,113 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dfff7946-336a-4306-a98b-043a3f5f1501, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:04,485 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=
2025-12-12 17:15:04,486 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:15:04,486 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 356ca7b5-2100-41ec-9352-dc3a6d7315f9, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:04,487 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 356ca7b5-2100-41ec-9352-dc3a6d7315f9, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=" 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:15:04,487 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 356ca7b5-2100-41ec-9352-dc3a6d7315f9, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:04,656 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:15:04,799 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2ZlNDc2MGItMTBhMzYzYTUtZDM2YTEyODItMjRlMjdjYg==
2025-12-12 17:15:04,844 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==
2025-12-12 17:15:04,845 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f97461e9-3fe2-493c-be1a-bb77ae84b5ff, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:04,846 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f97461e9-3fe2-493c-be1a-bb77ae84b5ff, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==" 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:15:04,855 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f97461e9-3fe2-493c-be1a-bb77ae84b5ff, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:05,187 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmRiYzAwMmEtMzJkNjBhNTctNzI4ZGU0YzYtNzk2NjhiMWY=
2025-12-12 17:15:05,228 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=
2025-12-12 17:15:05,248 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d339b336-7d98-45cf-a05d-aecc13af92a3, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:05,248 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d339b336-7d98-45cf-a05d-aecc13af92a3, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=" 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:15:05,256 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==
2025-12-12 17:15:05,257 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:15:05,257 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7612aef4-9fbe-44ac-8c1f-450e471d69d5, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:05,258 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7612aef4-9fbe-44ac-8c1f-450e471d69d5, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=" 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:15:05,259 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d339b336-7d98-45cf-a05d-aecc13af92a3, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:05,265 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7612aef4-9fbe-44ac-8c1f-450e471d69d5, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:05,494 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjYyNzNmYzktODNhYjczODItMzhiM2FmMDYtZDY3NjA4OWY=
2025-12-12 17:15:05,549 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:15:05,554 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2ZlNDc2MGItMTBhMzYzYTUtZDM2YTEyODItMjRlMjdjYg==
2025-12-12 17:15:05,555 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4759db0c-4141-4eff-aefe-6cba253ad205, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:05,556 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4759db0c-4141-4eff-aefe-6cba253ad205, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=M2ZlNDc2MGItMTBhMzYzYTUtZDM2YTEyODItMjRlMjdjYg==" 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:15:05,571 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4759db0c-4141-4eff-aefe-6cba253ad205, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:05,615 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2Q3NzJhZDgtZjUxNTAxOWYtZWM1ZGE4NGMtYzM2Y2M5NGI=
2025-12-12 17:15:05,823 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=
2025-12-12 17:15:05,825 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmUzYzMzMGMtZWQwMGIxMWEtM2Q3OGU0YmMtYjFmNDdmNWI=
2025-12-12 17:15:05,829 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Mzg3ZjliZTYtYzI5Y2YxYzYtYzI0NmY3OWQtYzYzYmM2NmY=
2025-12-12 17:15:05,932 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmRiYzAwMmEtMzJkNjBhNTctNzI4ZGU0YzYtNzk2NjhiMWY=
2025-12-12 17:15:05,934 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2ZlNDc2MGItMTBhMzYzYTUtZDM2YTEyODItMjRlMjdjYg==
2025-12-12 17:15:06,098 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e8e1b231-d7c5-4867-bdf0-7df921e010bb, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:06,102 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==
2025-12-12 17:15:06,119 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 926fafbd-b6ba-407f-b78d-782e6f8c7d53, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:06,119 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 926fafbd-b6ba-407f-b78d-782e6f8c7d53, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==" 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:15:06,118 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e8e1b231-d7c5-4867-bdf0-7df921e010bb, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=YmRiYzAwMmEtMzJkNjBhNTctNzI4ZGU0YzYtNzk2NjhiMWY=" 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:15:06,121 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjYyNzNmYzktODNhYjczODItMzhiM2FmMDYtZDY3NjA4OWY=
2025-12-12 17:15:06,124 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 926fafbd-b6ba-407f-b78d-782e6f8c7d53, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:06,125 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e8e1b231-d7c5-4867-bdf0-7df921e010bb, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:06,124 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 66580469-ab51-4e93-b294-ee86ab614db2, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:06,132 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 66580469-ab51-4e93-b294-ee86ab614db2, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=NjYyNzNmYzktODNhYjczODItMzhiM2FmMDYtZDY3NjA4OWY=" 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:15:06,133 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 66580469-ab51-4e93-b294-ee86ab614db2, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:06,128 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:15:06,133 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f27e9597-1803-4e6c-9d4f-2a9c0317e45b, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:06,139 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f27e9597-1803-4e6c-9d4f-2a9c0317e45b, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=" 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:15:06,139 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2Q3NzJhZDgtZjUxNTAxOWYtZWM1ZGE4NGMtYzM2Y2M5NGI=
2025-12-12 17:15:06,142 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 684e3f02-8f61-4ade-9887-4b4d35277d29, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:06,142 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 684e3f02-8f61-4ade-9887-4b4d35277d29, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=Y2Q3NzJhZDgtZjUxNTAxOWYtZWM1ZGE4NGMtYzM2Y2M5NGI=" 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:15:06,142 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f27e9597-1803-4e6c-9d4f-2a9c0317e45b, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:06,143 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 684e3f02-8f61-4ade-9887-4b4d35277d29, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:06,202 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDBlM2RjMjUtOTVlNDJjMmQtODQ4ZjQ0NTktNjI5MTg2MTM=
2025-12-12 17:15:06,272 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=
2025-12-12 17:15:06,273 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a0c8f05b-bb25-4b43-802b-51f67dcccb6c, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:06,274 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a0c8f05b-bb25-4b43-802b-51f67dcccb6c, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=" 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:15:06,275 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a0c8f05b-bb25-4b43-802b-51f67dcccb6c, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:06,331 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==
2025-12-12 17:15:07,123 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2Q3NzJhZDgtZjUxNTAxOWYtZWM1ZGE4NGMtYzM2Y2M5NGI=
2025-12-12 17:15:07,215 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=
2025-12-12 17:15:07,215 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZmUzYzMzMGMtZWQwMGIxMWEtM2Q3OGU0YmMtYjFmNDdmNWI=
2025-12-12 17:15:07,216 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, abb97218-cc93-4275-806d-4fa4baf5dd70, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:07,216 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, abb97218-cc93-4275-806d-4fa4baf5dd70, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZmUzYzMzMGMtZWQwMGIxMWEtM2Q3OGU0YmMtYjFmNDdmNWI=" 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:15:07,217 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, abb97218-cc93-4275-806d-4fa4baf5dd70, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:07,221 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmRiYzAwMmEtMzJkNjBhNTctNzI4ZGU0YzYtNzk2NjhiMWY=
2025-12-12 17:15:07,222 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Mzg3ZjliZTYtYzI5Y2YxYzYtYzI0NmY3OWQtYzYzYmM2NmY=
2025-12-12 17:15:07,222 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ef70e687-bf0a-4e26-bd06-2901774d506c, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:07,222 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ef70e687-bf0a-4e26-bd06-2901774d506c, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=Mzg3ZjliZTYtYzI5Y2YxYzYtYzI0NmY3OWQtYzYzYmM2NmY=" 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:15:07,223 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ef70e687-bf0a-4e26-bd06-2901774d506c, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:07,252 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=
2025-12-12 17:15:07,256 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=M2ZlNDc2MGItMTBhMzYzYTUtZDM2YTEyODItMjRlMjdjYg==
2025-12-12 17:15:07,255 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjYyNzNmYzktODNhYjczODItMzhiM2FmMDYtZDY3NjA4OWY=
2025-12-12 17:15:07,255 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZmUzYzMzMGMtZWQwMGIxMWEtM2Q3OGU0YmMtYjFmNDdmNWI=
2025-12-12 17:15:07,257 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1a8fbde0-ed0a-41cd-b414-3bf27d310eea, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:07,257 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDBlM2RjMjUtOTVlNDJjMmQtODQ4ZjQ0NTktNjI5MTg2MTM=
2025-12-12 17:15:07,267 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1a8fbde0-ed0a-41cd-b414-3bf27d310eea, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=M2ZlNDc2MGItMTBhMzYzYTUtZDM2YTEyODItMjRlMjdjYg==" 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:15:07,275 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ab6f2b21-3827-47c9-80c0-f2752dfd084e, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:07,280 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ab6f2b21-3827-47c9-80c0-f2752dfd084e, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZDBlM2RjMjUtOTVlNDJjMmQtODQ4ZjQ0NTktNjI5MTg2MTM=" 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:15:07,281 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ab6f2b21-3827-47c9-80c0-f2752dfd084e, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:07,279 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1a8fbde0-ed0a-41cd-b414-3bf27d310eea, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:07,420 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDBlM2RjMjUtOTVlNDJjMmQtODQ4ZjQ0NTktNjI5MTg2MTM=
2025-12-12 17:15:07,421 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Mzg3ZjliZTYtYzI5Y2YxYzYtYzI0NmY3OWQtYzYzYmM2NmY=
2025-12-12 17:15:07,423 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=M2ZlNDc2MGItMTBhMzYzYTUtZDM2YTEyODItMjRlMjdjYg==
2025-12-12 17:15:07,424 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==
2025-12-12 17:15:07,424 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 905ebb8b-9e12-47ee-aff7-5eab124380ce, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:07,424 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 905ebb8b-9e12-47ee-aff7-5eab124380ce, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==" 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:15:07,425 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 905ebb8b-9e12-47ee-aff7-5eab124380ce, ghrun-lmjjgjftxu.auto.internal:18341): response = { <_MultiThreadedRendezvous object> }
2025-12-12 17:15:07,904 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==
2025-12-12 17:15:07,986 - INFO - root - test_log_uniform: check insert: 9710 12973
2025-12-12 17:15:07,987 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.4
2025-12-12 17:15:07,988 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.4
2025-12-12 17:15:07,988 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cfdu/002010/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18341', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-12-12 17:15:07,989 - DEBUG - ya.test - execute: Command pid: 82594
2025-12-12 17:15:08,136 - DEBUG - ya.test - _save_outputs: Command (pid 82594) rc: 0
2025-12-12 17:15:08,137 - DEBUG - ya.test - _save_outputs: Command (pid 82594) elapsed time (sec): 0.1468198299407959
2025-12-12 17:15:08,137 - DEBUG - ya.test - _save_outputs: Command (pid 82594) idrss: 0
2025-12-12 17:15:08,137 - DEBUG - ya.test - _save_outputs: Command (pid 82594) inblock: 0
2025-12-12 17:15:08,137 - DEBUG - ya.test - _save_outputs: Command (pid 82594) isrss: 0
2025-12-12 17:15:08,138 - DEBUG - ya.test - _save_outputs: Command (pid 82594) ixrss: 0
2025-12-12 17:15:08,138 - DEBUG - ya.test - _save_outputs: Command (pid 82594) majflt: 0
2025-12-12 17:15:08,138 - DEBUG - ya.test - _save_outputs: Command (pid 82594) maxrss: 354940
2025-12-12 17:15:08,138 - DEBUG - ya.test - _save_outputs: Command (pid 82594) minflt: 3484
2025-12-12 17:15:08,139 - DEBUG - ya.test - _save_outputs: Command (pid 82594) msgrcv: 0
2025-12-12 17:15:08,139 - DEBUG - ya.test - _save_outputs: Command (pid 82594) msgsnd: 0
2025-12-12 17:15:08,139 - DEBUG - ya.test - _save_outputs: Command (pid 82594) nivcsw: 16
2025-12-12 17:15:08,139 - DEBUG - ya.test - _save_outputs: Command (pid 82594) nsignals: 0
2025-12-12 17:15:08,139 - DEBUG - ya.test - _save_outputs: Command (pid 82594) nswap: 0
2025-12-12 17:15:08,140 - DEBUG - ya.test - _save_outputs: Command (pid 82594) nvcsw: 160
2025-12-12 17:15:08,140 - DEBUG - ya.test - _save_outputs: Command (pid 82594) oublock: 8
2025-12-12 17:15:08,140 - DEBUG - ya.test - _save_outputs: Command (pid 82594) stime: 0.016961999999999998
2025-12-12 17:15:08,140 - DEBUG - ya.test - _save_outputs: Command (pid 82594) utime: 0.046645
2025-12-12 17:15:08,140 - DEBUG - ya.test - _save_outputs: Command (pid 82594) wtime: 0.148
2025-12-12 17:15:08,141 - DEBUG - ya.test - _save_outputs: Command (pid 82594) output:
Remove path log...
Remove path log...Ok

2025-12-12 17:15:08,141 - DEBUG - ya.test - _save_outputs: Command (pid 82594) errors:

2025-12-12 17:15:08,143 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-12-12 17:15:08,144 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, dc58d5af-7cea-4866-bdc4-f44fa7fbfc25, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:08,144 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, dc58d5af-7cea-4866-bdc4-f44fa7fbfc25, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=Y2Q3NzJhZDgtZjUxNTAxOWYtZWM1ZGE4NGMtYzM2Y2M5NGI=" }
2025-12-12 17:15:08,219 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, dc58d5af-7cea-4866-bdc4-f44fa7fbfc25, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS }
2025-12-12 17:15:08,240 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, d2f75934-4386-46dd-9220-ba8255acd149, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:08,241 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, d2f75934-4386-46dd-9220-ba8255acd149, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=NDRlOWIyNjEtMjNlNDI1ZGItY2M4MzFlNTktYzMxYjVkMmQ=" }
2025-12-12 17:15:08,243 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, d2f75934-4386-46dd-9220-ba8255acd149, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS }
2025-12-12 17:15:08,253 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, e68664e5-c9be-49bf-81bb-1c94434042f4, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:08,264 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, e68664e5-c9be-49bf-81bb-1c94434042f4, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=YmRiYzAwMmEtMzJkNjBhNTctNzI4ZGU0YzYtNzk2NjhiMWY=" }
2025-12-12 17:15:08,276 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, e68664e5-c9be-49bf-81bb-1c94434042f4, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS }
2025-12-12 17:15:08,277 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, aa4531a0-0c4a-47ea-a251-65b25a564c87, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:08,277 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, aa4531a0-0c4a-47ea-a251-65b25a564c87, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZGUwMmZiMzgtY2U4Y2U1YzktOWY2NzcxYTEtZjU4N2VhYzY=" }
2025-12-12 17:15:08,279 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, aa4531a0-0c4a-47ea-a251-65b25a564c87, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS }
2025-12-12 17:15:08,280 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 69cab1a7-fb92-43a4-848c-ca984674e347, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:08,281 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 69cab1a7-fb92-43a4-848c-ca984674e347, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZmUzYzMzMGMtZWQwMGIxMWEtM2Q3OGU0YmMtYjFmNDdmNWI=" }
2025-12-12 17:15:08,282 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 69cab1a7-fb92-43a4-848c-ca984674e347, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS }
2025-12-12 17:15:08,283 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 40968ea1-e83e-4812-b3e5-9f49fe3f5258, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:08,283 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 40968ea1-e83e-4812-b3e5-9f49fe3f5258, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=NjYyNzNmYzktODNhYjczODItMzhiM2FmMDYtZDY3NjA4OWY=" }
2025-12-12 17:15:08,285 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 40968ea1-e83e-4812-b3e5-9f49fe3f5258, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS }
2025-12-12 17:15:08,285 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 272473d7-a53c-48dd-b878-68cd3e25d7dc, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:08,286 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 272473d7-a53c-48dd-b878-68cd3e25d7dc, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=ZDBlM2RjMjUtOTVlNDJjMmQtODQ4ZjQ0NTktNjI5MTg2MTM=" }
2025-12-12 17:15:08,287 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 272473d7-a53c-48dd-b878-68cd3e25d7dc, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS }
2025-12-12 17:15:08,287 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 7c008ba6-bdc9-4c3b-9b70-b484b9bcc004, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:08,288 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 7c008ba6-bdc9-4c3b-9b70-b484b9bcc004, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=Mzg3ZjliZTYtYzI5Y2YxYzYtYzI0NmY3OWQtYzYzYmM2NmY=" }
2025-12-12 17:15:08,293 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 7c008ba6-bdc9-4c3b-9b70-b484b9bcc004, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS }
2025-12-12 17:15:08,296 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, b4fb08d7-5802-4175-b8da-7efb21f1c1e6, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:08,296 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, b4fb08d7-5802-4175-b8da-7efb21f1c1e6, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=M2ZlNDc2MGItMTBhMzYzYTUtZDM2YTEyODItMjRlMjdjYg==" }
2025-12-12 17:15:08,305 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, b4fb08d7-5802-4175-b8da-7efb21f1c1e6, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS }
2025-12-12 17:15:08,358 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, fb51cb13-8063-4c61-b45e-db2cb490c552, ghrun-lmjjgjftxu.auto.internal:18341): creating call state
2025-12-12 17:15:08,358 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, fb51cb13-8063-4c61-b45e-db2cb490c552, ghrun-lmjjgjftxu.auto.internal:18341): request = { session_id: "ydb://session/3?node_id=1&id=YzhmMGZhNzgtNjZkNjk1NDQtOGM2ODA4My0yMDJlNGZjOQ==" }
2025-12-12 17:15:08,363 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, fb51cb13-8063-4c61-b45e-db2cb490c552, ghrun-lmjjgjftxu.auto.internal:18341): response = { status: SUCCESS }
2025-12-12 17:15:08,364 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-12-12 17:15:08,365 - DEBUG - ydb.topic - close: Close topic client
2025-12-12 17:15:08,365 - DEBUG - ydb.topic - close: Topic client was closed
2025-12-12 17:15:08,367 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18341
2025-12-12 17:15:08,368 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-lmjjgjftxu.auto.internal:18341
2025-12-12 17:15:08,369 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-12-12 17:15:20,417 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:18341/1
2025-12-12 17:15:20,426 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19766
2025-12-12 17:15:20,427 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24830
2025-12-12 17:15:20,427 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/29146
2025-12-12 17:15:20,427 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18341
2025-12-12 17:15:20,427 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17642
