2025-11-12 08:28:59,898 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-12 08:28:59,902 - INFO - ya.test - pytest_runtest_setup: test_log_deviation[180]
2025-11-12 08:28:59,902 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-12 08:28:59,902 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-12 08:28:59,909 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_deviation[180])
2025-11-12 08:28:59,912 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/l2qw/0001cb/ydb/apps/ydb/ydb
2025-11-12 08:28:59,913 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/l2qw/0001cb/ydb/apps/ydb/ydb -e grpc://localhost:5575 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-11-12 08:28:59,926 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.5
2025-11-12 08:28:59,955 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.5
2025-11-12 08:28:59,973 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/l2qw/0001cb/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:5575', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-11-12 08:29:00,042 - DEBUG - ya.test - execute: Command pid: 3645546
2025-11-12 08:29:01,280 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) rc: 0
2025-11-12 08:29:01,280 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) elapsed time (sec): 1.2366955280303955
2025-11-12 08:29:01,291 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) idrss: 0
2025-11-12 08:29:01,316 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) inblock: 0
2025-11-12 08:29:01,323 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) isrss: 0
2025-11-12 08:29:01,355 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) ixrss: 0
2025-11-12 08:29:01,388 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) majflt: 0
2025-11-12 08:29:01,389 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) maxrss: 788384
2025-11-12 08:29:01,389 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) minflt: 3480
2025-11-12 08:29:01,389 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) msgrcv: 0
2025-11-12 08:29:01,389 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) msgsnd: 0
2025-11-12 08:29:01,389 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) nivcsw: 65
2025-11-12 08:29:01,390 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) nsignals: 0
2025-11-12 08:29:01,522 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) nswap: 0
2025-11-12 08:29:01,534 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) nvcsw: 134
2025-11-12 08:29:01,543 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) oublock: 8
2025-11-12 08:29:01,548 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) stime: 0.030913
2025-11-12 08:29:01,549 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) utime: 0.060703999999999994
2025-11-12 08:29:01,550 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) wtime: 1.287
2025-11-12 08:29:01,572 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) output:
Init tables ...
Init tables ...Ok

2025-11-12 08:29:01,573 - DEBUG - ya.test - _save_outputs: Command (pid 3645546) errors:

2025-11-12 08:29:01,573 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=
2025-11-12 08:29:01,574 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 793a249c-1fe3-49af-94e9-65386582a19e, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:01,589 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 793a249c-1fe3-49af-94e9-65386582a19e, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "ALTER OBJECT `//Root/log` (TYPE TABLE) SET (ACTION=UPSERT_OPTIONS, `SCAN_READER_POLICY_NAME`=`SIMPLE`) " } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:01,590 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 793a249c-1fe3-49af-94e9-65386582a19e, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:01,712 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=
2025-11-12 08:29:01,712 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-12 08:29:01,714 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/l2qw/0001cb/ydb/apps/ydb/ydb -e grpc://localhost:5575 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-12 08:29:01,720 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.6
2025-11-12 08:29:01,728 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.6
2025-11-12 08:29:01,730 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/l2qw/0001cb/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:5575', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-12 08:29:01,734 - DEBUG - ya.test - execute: Command pid: 3646342
2025-11-12 08:29:06,395 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-12 08:29:06,424 - DEBUG - ya.test - _save_outputs: Command (pid 3646342) rc: 0
2025-11-12 08:29:06,424 - DEBUG - ya.test - _save_outputs: Command (pid 3646342) elapsed time (sec): 4.6889731884002686
2025-11-12 08:29:06,426 - DEBUG - ya.test - _save_outputs: Command (pid 3646342) wtime: 4.691
2025-11-12 08:29:06,426 - DEBUG - ya.test - _save_outputs: Command (pid 3646342) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      1	1	0	0	791	791	791	791
2	      9	9	0	0	1591	1823	1823	1823
3	     17	17	0	0	1231	1327	1383	1383

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	     27	9	0	0	1239	1791	1823	1823

2025-11-12 08:29:06,427 - DEBUG - ya.test - _save_outputs: Command (pid 3646342) errors:

2025-11-12 08:29:06,439 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:29:06,440 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4d216536-44f2-434f-b2ce-332c13967d13, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:06,446 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4d216536-44f2-434f-b2ce-332c13967d13, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:06,447 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4d216536-44f2-434f-b2ce-332c13967d13, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:08,874 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:29:08,875 - INFO - root - test_log_deviation: Count rows after insert 13500 before wait
2025-11-12 08:29:08,875 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:29:08,888 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5afe02fc-091a-40f3-af1f-7cb34b478e2c, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:08,900 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5afe02fc-091a-40f3-af1f-7cb34b478e2c, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:08,901 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5afe02fc-091a-40f3-af1f-7cb34b478e2c, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:10,412 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:29:10,423 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==
2025-11-12 08:29:10,430 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3d595313-2849-467b-8c73-cdbc3df6231b, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:10,434 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3d595313-2849-467b-8c73-cdbc3df6231b, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:10,437 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3d595313-2849-467b-8c73-cdbc3df6231b, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:11,496 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==
2025-11-12 08:29:11,500 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-12 08:29:11,503 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/l2qw/0001cb/ydb/apps/ydb/ydb -e grpc://localhost:5575 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-12 08:29:11,510 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.7
2025-11-12 08:29:11,512 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-11-12 08:29:11,512 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/l2qw/0001cb/ydb/apps/ydb/ydb -e grpc://localhost:5575 -d /Root workload log --path log run insert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-12 08:29:11,518 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.7
2025-11-12 08:29:11,528 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-11-12 08:29:11,541 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/l2qw/0001cb/ydb/apps/ydb/ydb -e grpc://localhost:5575 -d /Root workload log --path log run upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-12 08:29:11,537 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.8
2025-11-12 08:29:11,540 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/l2qw/0001cb/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:5575', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-12 08:29:11,529 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==
2025-11-12 08:29:11,573 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.9
2025-11-12 08:29:11,574 - DEBUG - ya.test - execute: Command pid: 3648535
2025-11-12 08:29:11,587 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1aaf2f34-8517-4dcb-9cbb-f0fbb9616c38, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:11,593 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1aaf2f34-8517-4dcb-9cbb-f0fbb9616c38, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:11,587 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:29:11,592 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.9
2025-11-12 08:29:11,591 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.8
2025-11-12 08:29:11,598 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1aaf2f34-8517-4dcb-9cbb-f0fbb9616c38, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:11,601 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 224110e4-1952-49a2-be95-fbdd40ca5198, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:11,602 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:29:11,606 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/l2qw/0001cb/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:5575', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-12 08:29:11,607 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/l2qw/0001cb/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:5575', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'insert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-12 08:29:11,610 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 224110e4-1952-49a2-be95-fbdd40ca5198, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:11,618 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 299c6f75-f191-4aa2-8911-7e21bc0a2e59, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:11,619 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=
2025-11-12 08:29:11,642 - DEBUG - ya.test - execute: Command pid: 3648579
2025-11-12 08:29:11,648 - DEBUG - ya.test - execute: Command pid: 3648563
2025-11-12 08:29:11,650 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 224110e4-1952-49a2-be95-fbdd40ca5198, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:11,653 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 299c6f75-f191-4aa2-8911-7e21bc0a2e59, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:11,654 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ef7b1ad8-cfef-4f27-aba0-42b7698eb9fd, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:11,655 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:29:11,667 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 299c6f75-f191-4aa2-8911-7e21bc0a2e59, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:11,671 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ef7b1ad8-cfef-4f27-aba0-42b7698eb9fd, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:11,686 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ef7b1ad8-cfef-4f27-aba0-42b7698eb9fd, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:11,673 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=
2025-11-12 08:29:11,689 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f8dac6ea-2421-455c-9503-0dc31fa81f25, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:11,691 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f8dac6ea-2421-455c-9503-0dc31fa81f25, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:11,689 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=
2025-11-12 08:29:11,673 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e69d1dc0-7b34-4c28-939c-863682367aea, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:11,696 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e69d1dc0-7b34-4c28-939c-863682367aea, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:11,695 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 16299024-2711-4178-a0a8-6c85f3f11826, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:11,696 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:29:11,695 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f8dac6ea-2421-455c-9503-0dc31fa81f25, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:11,701 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e69d1dc0-7b34-4c28-939c-863682367aea, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:11,702 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 16299024-2711-4178-a0a8-6c85f3f11826, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:11,703 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2bd06f1b-dbcc-4ef9-8d39-96df2316f4cc, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:11,706 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:29:11,724 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2bd06f1b-dbcc-4ef9-8d39-96df2316f4cc, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:11,734 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2bd06f1b-dbcc-4ef9-8d39-96df2316f4cc, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:11,726 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 47ac08d2-9024-450d-ad9f-4341a7ad6a52, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:11,753 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 47ac08d2-9024-450d-ad9f-4341a7ad6a52, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:11,754 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 47ac08d2-9024-450d-ad9f-4341a7ad6a52, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:11,721 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 16299024-2711-4178-a0a8-6c85f3f11826, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:11,728 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==
2025-11-12 08:29:11,784 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 61c7d5f6-c7cc-4c2a-8e9b-a377ab630464, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:11,786 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 61c7d5f6-c7cc-4c2a-8e9b-a377ab630464, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:11,795 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 61c7d5f6-c7cc-4c2a-8e9b-a377ab630464, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:16,404 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-12 08:29:16,404 - DEBUG - ya.test - _save_outputs: Command (pid 3648579) rc: 0
2025-11-12 08:29:16,405 - DEBUG - ya.test - _save_outputs: Command (pid 3648579) elapsed time (sec): 4.744191646575928
2025-11-12 08:29:16,405 - DEBUG - ya.test - _save_outputs: Command (pid 3648579) wtime: 4.777
2025-11-12 08:29:16,405 - DEBUG - ya.test - _save_outputs: Command (pid 3648579) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	      0	0	0	10	0	0	0	0
3	      0	0	0	10	0	0	0	0

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

2025-11-12 08:29:16,439 - DEBUG - ya.test - _save_outputs: Command (pid 3648579) errors:

2025-11-12 08:29:17,012 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-12 08:29:17,012 - DEBUG - ya.test - _save_outputs: Command (pid 3648563) rc: 0
2025-11-12 08:29:17,013 - DEBUG - ya.test - _save_outputs: Command (pid 3648563) elapsed time (sec): 5.351626634597778
2025-11-12 08:29:17,033 - DEBUG - ya.test - _save_outputs: Command (pid 3648563) wtime: 5.392
2025-11-12 08:29:17,036 - DEBUG - ya.test - _save_outputs: Command (pid 3648563) 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	9	0	0	0	0
3	      0	0	0	11	0	0	0	0

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

2025-11-12 08:29:17,038 - DEBUG - ya.test - _save_outputs: Command (pid 3648563) errors:

2025-11-12 08:29:32,861 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-12 08:29:32,863 - DEBUG - ya.test - _save_outputs: Command (pid 3648535) rc: 0
2025-11-12 08:29:32,864 - DEBUG - ya.test - _save_outputs: Command (pid 3648535) elapsed time (sec): 21.270958423614502
2025-11-12 08:29:32,868 - DEBUG - ya.test - _save_outputs: Command (pid 3648535) wtime: 21.309
2025-11-12 08:29:32,869 - DEBUG - ya.test - _save_outputs: Command (pid 3648535) 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	10	0	0	0	0

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

2025-11-12 08:29:32,870 - DEBUG - ya.test - _save_outputs: Command (pid 3648535) errors:

2025-11-12 08:29:39,381 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:29:39,383 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:29:39,384 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 264ca483-42dc-4251-a2f4-18619b327862, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:39,384 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 264ca483-42dc-4251-a2f4-18619b327862, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:39,392 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 264ca483-42dc-4251-a2f4-18619b327862, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:39,951 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==
2025-11-12 08:29:39,954 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==
2025-11-12 08:29:39,958 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cc1c9e54-2324-4159-a699-ad3412f257c9, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:39,959 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cc1c9e54-2324-4159-a699-ad3412f257c9, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:39,960 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cc1c9e54-2324-4159-a699-ad3412f257c9, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:40,002 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:29:40,002 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:29:40,003 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8028e0c4-739a-468e-aa86-3e7ff37b404b, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:40,009 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8028e0c4-739a-468e-aa86-3e7ff37b404b, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:40,010 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8028e0c4-739a-468e-aa86-3e7ff37b404b, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:40,062 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=
2025-11-12 08:29:40,063 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=
2025-11-12 08:29:40,068 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b60a079e-a86c-458d-9649-432120665d35, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:40,075 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b60a079e-a86c-458d-9649-432120665d35, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:40,085 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b60a079e-a86c-458d-9649-432120665d35, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:40,327 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:29:40,332 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:29:40,332 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d48915b5-f5e5-447c-8b3b-489253ec1ce7, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:40,333 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d48915b5-f5e5-447c-8b3b-489253ec1ce7, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:40,335 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d48915b5-f5e5-447c-8b3b-489253ec1ce7, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:40,387 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=
2025-11-12 08:29:40,388 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=
2025-11-12 08:29:40,388 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9e81104b-3032-466a-9507-8f2dfe04b1de, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:40,394 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9e81104b-3032-466a-9507-8f2dfe04b1de, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:40,395 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9e81104b-3032-466a-9507-8f2dfe04b1de, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:40,782 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=
2025-11-12 08:29:40,784 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=
2025-11-12 08:29:40,785 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3ed8971e-a5d7-494c-bbfc-fa7614dcbc05, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:40,786 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3ed8971e-a5d7-494c-bbfc-fa7614dcbc05, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:40,789 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3ed8971e-a5d7-494c-bbfc-fa7614dcbc05, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:40,796 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:29:40,801 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:29:40,802 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7aca49f0-d149-4ad7-83f8-c0010bd1432a, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:40,804 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7aca49f0-d149-4ad7-83f8-c0010bd1432a, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:40,805 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7aca49f0-d149-4ad7-83f8-c0010bd1432a, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:41,052 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==
2025-11-12 08:29:41,052 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==
2025-11-12 08:29:41,053 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 221dce2e-6f09-415c-970e-c8cae7a31078, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:41,053 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 221dce2e-6f09-415c-970e-c8cae7a31078, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==" 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-11-12 08:29:41,054 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 221dce2e-6f09-415c-970e-c8cae7a31078, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:41,056 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:29:41,068 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:29:41,068 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4304d2b1-0de0-4c30-84f2-542dc468bf21, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:41,076 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4304d2b1-0de0-4c30-84f2-542dc468bf21, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:41,085 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4304d2b1-0de0-4c30-84f2-542dc468bf21, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:43,409 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:29:43,411 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:29:43,422 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 72411dd2-9daf-4e21-96e6-aa9dd09c66af, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:43,428 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 72411dd2-9daf-4e21-96e6-aa9dd09c66af, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:43,432 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 72411dd2-9daf-4e21-96e6-aa9dd09c66af, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:43,588 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:29:43,591 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:29:43,592 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c43d4706-0079-4f43-90bc-4539d98b8174, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:43,602 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c43d4706-0079-4f43-90bc-4539d98b8174, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:43,620 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c43d4706-0079-4f43-90bc-4539d98b8174, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:43,840 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:29:43,843 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:29:43,848 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 115510a9-e622-49ae-8bdc-7519f4d4015a, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:43,853 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=
2025-11-12 08:29:43,854 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 115510a9-e622-49ae-8bdc-7519f4d4015a, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:43,858 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=
2025-11-12 08:29:43,871 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 115510a9-e622-49ae-8bdc-7519f4d4015a, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:43,872 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7d4cfe52-67c6-4559-a0b2-f6146f1991dc, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:43,881 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7d4cfe52-67c6-4559-a0b2-f6146f1991dc, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:43,882 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7d4cfe52-67c6-4559-a0b2-f6146f1991dc, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:43,892 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:29:43,892 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:29:43,893 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7f4dc3cc-d141-472d-a95b-09587fd42e88, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:43,904 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7f4dc3cc-d141-472d-a95b-09587fd42e88, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(11)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:43,905 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7f4dc3cc-d141-472d-a95b-09587fd42e88, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:44,080 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==
2025-11-12 08:29:44,081 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==
2025-11-12 08:29:44,085 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 49585478-130a-4043-a39c-f74536b6ff59, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:44,106 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 49585478-130a-4043-a39c-f74536b6ff59, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:44,108 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 49585478-130a-4043-a39c-f74536b6ff59, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:44,120 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:29:44,122 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:29:44,122 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4214095b-97be-4700-848c-cec025d70dc1, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:44,124 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4214095b-97be-4700-848c-cec025d70dc1, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(8))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:44,141 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4214095b-97be-4700-848c-cec025d70dc1, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:44,452 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:29:44,452 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:29:44,453 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dad32045-e41a-4e2d-a7a7-01643befb66f, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:44,460 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dad32045-e41a-4e2d-a7a7-01643befb66f, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(8))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:44,461 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dad32045-e41a-4e2d-a7a7-01643befb66f, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:44,464 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:29:45,168 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:29:45,202 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:29:45,420 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==
2025-11-12 08:29:45,420 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:29:45,421 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6fb3b315-472e-4bf4-8d5b-d811154f074a, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:45,422 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6fb3b315-472e-4bf4-8d5b-d811154f074a, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(8))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:45,424 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6fb3b315-472e-4bf4-8d5b-d811154f074a, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:46,544 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=
2025-11-12 08:29:46,548 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:29:46,548 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a72cfe1b-1061-4d35-981b-a43ee6fba81b, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:46,560 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a72cfe1b-1061-4d35-981b-a43ee6fba81b, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(7))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:46,566 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a72cfe1b-1061-4d35-981b-a43ee6fba81b, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:46,572 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:29:47,803 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:29:48,259 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==
2025-11-12 08:29:48,293 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:29:48,328 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 422e32a3-fb82-463c-9e02-8acf16eb2cf2, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:48,338 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 422e32a3-fb82-463c-9e02-8acf16eb2cf2, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=" 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-11-12 08:29:48,353 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 422e32a3-fb82-463c-9e02-8acf16eb2cf2, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:48,485 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:29:48,579 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==
2025-11-12 08:29:48,600 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 469f4a71-90db-4782-b25e-a8f886b25663, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:48,601 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 469f4a71-90db-4782-b25e-a8f886b25663, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:48,605 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 469f4a71-90db-4782-b25e-a8f886b25663, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:48,581 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=
2025-11-12 08:29:48,720 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=
2025-11-12 08:29:48,721 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 63b6d773-a8fc-4500-8073-80f6f0119bfa, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:48,724 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:29:48,732 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 63b6d773-a8fc-4500-8073-80f6f0119bfa, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:48,865 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 63b6d773-a8fc-4500-8073-80f6f0119bfa, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:48,859 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:29:48,867 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f26fc76f-e6e7-4380-ba8b-23722ed78784, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:48,873 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f26fc76f-e6e7-4380-ba8b-23722ed78784, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:48,874 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f26fc76f-e6e7-4380-ba8b-23722ed78784, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:48,926 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=
2025-11-12 08:29:49,120 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:29:49,120 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5520a8a9-03e5-4615-bf06-c53325ed036d, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:49,129 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5520a8a9-03e5-4615-bf06-c53325ed036d, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:49,133 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5520a8a9-03e5-4615-bf06-c53325ed036d, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:49,752 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:29:49,752 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==
2025-11-12 08:29:49,753 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5c752e82-afec-4e9b-a415-07ea40d2c3a8, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:49,765 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5c752e82-afec-4e9b-a415-07ea40d2c3a8, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==" 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-11-12 08:29:49,771 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5c752e82-afec-4e9b-a415-07ea40d2c3a8, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:54,232 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==
2025-11-12 08:29:54,740 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:29:54,741 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:29:54,742 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 67d1f5b1-f038-4132-94fd-6166945f5648, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:54,743 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 67d1f5b1-f038-4132-94fd-6166945f5648, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:54,809 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 67d1f5b1-f038-4132-94fd-6166945f5648, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:54,844 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=
2025-11-12 08:29:54,876 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=
2025-11-12 08:29:54,877 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 741db235-d16d-4811-abc0-93440c9f3c61, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:54,884 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 741db235-d16d-4811-abc0-93440c9f3c61, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:54,915 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 741db235-d16d-4811-abc0-93440c9f3c61, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:54,856 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==
2025-11-12 08:29:54,856 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:29:54,917 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:29:54,922 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 87100084-8c4a-421c-8cbc-b67160e9d165, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:54,949 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 87100084-8c4a-421c-8cbc-b67160e9d165, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:54,922 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=
2025-11-12 08:29:55,013 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be60760a-1a2c-447a-99b4-ad6cd56c51e7, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:55,023 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be60760a-1a2c-447a-99b4-ad6cd56c51e7, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(1))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:55,028 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be60760a-1a2c-447a-99b4-ad6cd56c51e7, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:54,985 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 87100084-8c4a-421c-8cbc-b67160e9d165, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:56,797 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-12 08:29:56,797 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-12 08:29:56,802 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-12 08:29:56,803 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 6f69d3ae-a781-46ff-a9cc-77cdcadf8986, localhost:5575): creating call state
2025-11-12 08:29:56,803 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 6f69d3ae-a781-46ff-a9cc-77cdcadf8986, localhost:5575): request = { database: "/Root" }
2025-11-12 08:29:56,951 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 6f69d3ae-a781-46ff-a9cc-77cdcadf8986, localhost:5575): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-tqbhkoylkm.auto.internal" port: 5575 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-12 08:29:56,957 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-tqbhkoylkm.auto.internal:5575, location 1, ssl: False>]>
2025-11-12 08:29:56,957 - INFO - ydb.connection - close: Closing channel for endpoint localhost:5575
2025-11-12 08:29:58,616 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:29:58,620 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:29:58,627 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=
2025-11-12 08:29:58,638 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=
2025-11-12 08:29:58,644 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:29:58,645 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f1aff916-bccd-4685-9d84-1a8f7297f6a2, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:29:58,650 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f1aff916-bccd-4685-9d84-1a8f7297f6a2, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:29:58,651 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f1aff916-bccd-4685-9d84-1a8f7297f6a2, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:29:59,992 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:29:59,992 - INFO - root - test_log_deviation: check insert: 18500 13500
2025-11-12 08:30:00,010 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.10
2025-11-12 08:30:00,013 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.10
2025-11-12 08:30:00,029 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/l2qw/0001cb/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:5575', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-11-12 08:30:00,052 - DEBUG - ya.test - execute: Command pid: 3666950
2025-11-12 08:30:00,446 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) rc: 0
2025-11-12 08:30:00,446 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) elapsed time (sec): 0.37621450424194336
2025-11-12 08:30:00,447 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) idrss: 0
2025-11-12 08:30:00,447 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) inblock: 0
2025-11-12 08:30:00,447 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) isrss: 0
2025-11-12 08:30:00,447 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) ixrss: 0
2025-11-12 08:30:00,474 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) majflt: 0
2025-11-12 08:30:00,474 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) maxrss: 788384
2025-11-12 08:30:00,474 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) minflt: 3472
2025-11-12 08:30:00,474 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) msgrcv: 0
2025-11-12 08:30:00,481 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) msgsnd: 0
2025-11-12 08:30:00,503 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) nivcsw: 44
2025-11-12 08:30:00,528 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) nsignals: 0
2025-11-12 08:30:00,528 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) nswap: 0
2025-11-12 08:30:00,528 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) nvcsw: 134
2025-11-12 08:30:00,528 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) oublock: 8
2025-11-12 08:30:00,528 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) stime: 0.030362999999999998
2025-11-12 08:30:00,529 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) utime: 0.056930999999999995
2025-11-12 08:30:00,529 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) wtime: 0.4
2025-11-12 08:30:00,529 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) output:
Remove path log...
Remove path log...Ok

2025-11-12 08:30:00,529 - DEBUG - ya.test - _save_outputs: Command (pid 3666950) errors:

2025-11-12 08:30:00,534 - INFO - ya.test - pytest_runtest_teardown: Test teardown
