2025-11-14 21:12:31,816 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 21:12:31,816 - INFO - ya.test - pytest_runtest_setup: test_log_uniform
2025-11-14 21:12:31,817 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 21:12:31,817 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-14 21:12:31,819 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_uniform)
2025-11-14 21:12:31,823 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb
2025-11-14 21:12:31,824 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb -e grpc://localhost:18008 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-11-14 21:12:31,827 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.11
2025-11-14 21:12:31,828 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.11
2025-11-14 21:12:31,828 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-11-14 21:12:31,833 - DEBUG - ya.test - execute: Command pid: 3717515
2025-11-14 21:12:32,718 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) rc: 0
2025-11-14 21:12:32,723 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) elapsed time (sec): 0.8806638717651367
2025-11-14 21:12:32,723 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) idrss: 0
2025-11-14 21:12:32,724 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) inblock: 0
2025-11-14 21:12:32,724 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) isrss: 0
2025-11-14 21:12:32,732 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) ixrss: 0
2025-11-14 21:12:32,732 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) majflt: 0
2025-11-14 21:12:32,732 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) maxrss: 684424
2025-11-14 21:12:32,732 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) minflt: 3485
2025-11-14 21:12:32,733 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) msgrcv: 0
2025-11-14 21:12:32,733 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) msgsnd: 0
2025-11-14 21:12:32,759 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) nivcsw: 38
2025-11-14 21:12:32,759 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) nsignals: 0
2025-11-14 21:12:32,760 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) nswap: 0
2025-11-14 21:12:32,760 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) nvcsw: 171
2025-11-14 21:12:32,760 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) oublock: 8
2025-11-14 21:12:32,760 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) stime: 0.031514
2025-11-14 21:12:32,760 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) utime: 0.043332999999999997
2025-11-14 21:12:32,760 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) wtime: 0.886
2025-11-14 21:12:32,760 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) output:
Init tables ...
Init tables ...Ok

2025-11-14 21:12:32,760 - DEBUG - ya.test - _save_outputs: Command (pid 3717515) errors:

2025-11-14 21:12:32,761 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-14 21:12:32,761 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb -e grpc://localhost:18008 -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-11-14 21:12:32,761 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.12
2025-11-14 21:12:32,762 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.12
2025-11-14 21:12:32,762 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-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-11-14 21:12:32,811 - DEBUG - ya.test - execute: Command pid: 3718089
2025-11-14 21:13:03,133 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-14 21:13:03,142 - DEBUG - ya.test - _save_outputs: Command (pid 3718089) rc: 0
2025-11-14 21:13:03,142 - DEBUG - ya.test - _save_outputs: Command (pid 3718089) elapsed time (sec): 30.326087951660156
2025-11-14 21:13:03,142 - DEBUG - ya.test - _save_outputs: Command (pid 3718089) wtime: 30.378
2025-11-14 21:13:03,142 - DEBUG - ya.test - _save_outputs: Command (pid 3718089) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	    207	207	0	0	45	73	84	88
2	    202	202	0	0	44	89	107	113
3	    210	210	0	0	46	71	77	83
4	    315	315	0	0	31	44	51	64
5	    227	227	0	0	41	69	77	78
6	    281	281	0	0	31	63	82	105
7	    313	313	0	0	30	52	63	77
8	    312	312	0	0	27	61	80	83
9	    345	345	0	0	25	52	78	86
10	    379	379	0	0	24	40	47	53
11	    414	414	0	0	23	33	35	36
12	    397	397	0	0	23	37	45	49
13	    379	379	0	0	24	39	52	61
14	    426	426	0	0	21	34	41	49
15	    428	428	0	0	21	34	46	50
16	    385	385	0	0	24	37	59	83
17	    400	400	0	0	23	38	47	52
18	    423	423	0	0	22	36	41	44
19	    436	436	0	0	21	32	39	43
20	    339	339	0	0	27	48	55	59
21	    311	311	0	0	29	57	64	68
22	    346	346	0	0	26	43	73	78
23	    315	315	0	0	29	52	75	84
24	    304	304	0	0	30	53	64	73
25	    353	353	0	0	26	45	56	71
26	    418	418	0	0	22	33	39	45
27	    420	420	0	0	22	31	36	38
28	    426	426	0	0	22	33	38	44
29	    405	405	0	0	22	42	60	69
30	    396	396	0	0	23	36	46	57

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	  10512	350.4	0	0	25	51	71	113

2025-11-14 21:13:03,142 - DEBUG - ya.test - _save_outputs: Command (pid 3718089) errors:

2025-11-14 21:13:03,143 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:13:03,156 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 70e504bc-5ebe-4010-84f6-e0b6b38d3a82, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:13:03,156 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 70e504bc-5ebe-4010-84f6-e0b6b38d3a82, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=" 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-14 21:13:03,157 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 70e504bc-5ebe-4010-84f6-e0b6b38d3a82, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:13:08,470 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:13:08,471 - INFO - root - test_log_uniform: Count rows after insert 10505 before wait
2025-11-14 21:13:08,475 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:13:08,476 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e5f7530d-b8bc-4dcd-8e0b-455a95557ae5, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:13:08,477 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e5f7530d-b8bc-4dcd-8e0b-455a95557ae5, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=" 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-14 21:13:08,480 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e5f7530d-b8bc-4dcd-8e0b-455a95557ae5, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:13:13,043 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:13:13,044 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:13:13,045 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3986a843-7711-4918-80e7-36041bfc5fdc, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:13:13,046 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3986a843-7711-4918-80e7-36041bfc5fdc, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" 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-14 21:13:13,046 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3986a843-7711-4918-80e7-36041bfc5fdc, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:13:18,237 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:13:18,254 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-14 21:13:18,255 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb -e grpc://localhost:18008 -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-11-14 21:13:18,256 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.13
2025-11-14 21:13:18,257 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.13
2025-11-14 21:13:18,257 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-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-11-14 21:13:18,262 - DEBUG - ya.test - execute: Command pid: 3743984
2025-11-14 21:13:18,263 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-11-14 21:13:18,264 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb -e grpc://localhost:18008 -d /Root workload log --path log run insert --seconds 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-11-14 21:13:18,266 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.14
2025-11-14 21:13:18,266 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.14
2025-11-14 21:13:18,267 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'insert', '--seconds', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-11-14 21:13:18,268 - DEBUG - ya.test - execute: Command pid: 3743989
2025-11-14 21:13:18,279 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-11-14 21:13:18,291 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb -e grpc://localhost:18008 -d /Root workload log --path log run upsert --seconds 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-11-14 21:13:18,292 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.15
2025-11-14 21:13:18,279 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:13:18,294 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 22672b7b-ce24-4982-ba1b-3231ef7bf066, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:13:18,300 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.15
2025-11-14 21:13:18,300 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 22672b7b-ce24-4982-ba1b-3231ef7bf066, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:13:18,301 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'upsert', '--seconds', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-11-14 21:13:18,303 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:13:18,308 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 768f7a85-1583-4c4c-8b5c-af1f93fce4f9, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:13:18,309 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 768f7a85-1583-4c4c-8b5c-af1f93fce4f9, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:13:18,310 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 768f7a85-1583-4c4c-8b5c-af1f93fce4f9, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:13:18,303 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 22672b7b-ce24-4982-ba1b-3231ef7bf066, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:13:18,310 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:13:18,311 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 518770d9-528f-4bd2-922e-30684e4aad3b, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:13:18,312 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 518770d9-528f-4bd2-922e-30684e4aad3b, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:13:18,313 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 518770d9-528f-4bd2-922e-30684e4aad3b, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:13:18,308 - DEBUG - ya.test - execute: Command pid: 3744038
2025-11-14 21:13:18,314 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:13:18,315 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd535159-8ee1-4010-ab74-ce7f2456b09a, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:13:18,316 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd535159-8ee1-4010-ab74-ce7f2456b09a, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:13:18,319 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd535159-8ee1-4010-ab74-ce7f2456b09a, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:13:18,319 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:13:18,320 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ee0a999d-6ccf-41c6-b546-3673e364d8b9, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:13:18,321 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ee0a999d-6ccf-41c6-b546-3673e364d8b9, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:13:18,322 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ee0a999d-6ccf-41c6-b546-3673e364d8b9, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:13:18,321 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:13:18,324 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 51a60a31-d0a8-40d9-8896-3e5f81bf87c2, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:13:18,327 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 51a60a31-d0a8-40d9-8896-3e5f81bf87c2, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:13:18,328 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 51a60a31-d0a8-40d9-8896-3e5f81bf87c2, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:13:18,324 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:13:18,330 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 26770c2f-a0ef-4736-9a80-f0be07bf2a87, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:13:18,331 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 26770c2f-a0ef-4736-9a80-f0be07bf2a87, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:13:18,332 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 26770c2f-a0ef-4736-9a80-f0be07bf2a87, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:13:18,331 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:13:18,333 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6d2d0688-80e0-4a22-b28c-3acecd194a83, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:13:18,334 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6d2d0688-80e0-4a22-b28c-3acecd194a83, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:13:18,334 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:13:18,335 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6d2d0688-80e0-4a22-b28c-3acecd194a83, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:13:18,341 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 44db512a-9509-4d9e-bf7e-3abb56dd1bb3, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:13:18,344 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 44db512a-9509-4d9e-bf7e-3abb56dd1bb3, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:13:18,341 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:13:18,345 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 44db512a-9509-4d9e-bf7e-3abb56dd1bb3, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:13:18,346 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 46990e03-5ef1-48ac-9c30-6caa1958ddb7, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:13:18,354 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 46990e03-5ef1-48ac-9c30-6caa1958ddb7, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:13:18,360 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 46990e03-5ef1-48ac-9c30-6caa1958ddb7, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:13:48,530 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-14 21:13:48,531 - DEBUG - ya.test - _save_outputs: Command (pid 3743984) rc: 0
2025-11-14 21:13:48,531 - DEBUG - ya.test - _save_outputs: Command (pid 3743984) elapsed time (sec): 30.266782760620117
2025-11-14 21:13:48,532 - DEBUG - ya.test - _save_outputs: Command (pid 3743984) wtime: 30.273
2025-11-14 21:13:48,533 - DEBUG - ya.test - _save_outputs: Command (pid 3743984) 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	      4	4	0	0	3759	3919	3919	3919
5	      5	5	0	0	4639	4959	4959	4959
6	      1	1	0	0	5407	5407	5407	5407
7	      0	0	0	0	0	0	0	0
8	      0	0	0	0	0	0	0	0
9	      0	0	0	0	0	0	0	0
10	      0	0	0	0	0	0	0	0
11	      0	0	0	0	0	0	0	0
12	      0	0	0	0	0	0	0	0
13	      0	0	0	0	0	0	0	0
14	      0	0	0	0	0	0	0	0
15	      0	0	0	0	0	0	0	0
16	      0	0	0	0	0	0	0	0
17	      1	1	0	0	12543	12543	12543	12543
18	      0	0	0	0	0	0	0	0
19	      4	4	0	0	14847	14975	14975	14975
20	      5	5	0	0	15039	15423	15423	15423
21	      0	0	0	0	0	0	0	0
22	      0	0	0	0	0	0	0	0
23	      0	0	0	0	0	0	0	0
24	      0	0	0	0	0	0	0	0
25	      0	0	0	0	0	0	0	0
26	      0	0	0	0	0	0	0	0
27	      0	0	0	0	0	0	0	0
28	     10	10	0	0	8191	11199	11199	11199
29	     23	23	0	0	261	871	1039	1039
30	    110	110	0	0	96	247	252	259

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    163	5.43333	0	0	122	14335	15039	15423

2025-11-14 21:13:48,533 - DEBUG - ya.test - _save_outputs: Command (pid 3743984) errors:

2025-11-14 21:13:48,729 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-14 21:13:48,730 - DEBUG - ya.test - _save_outputs: Command (pid 3743989) rc: 0
2025-11-14 21:13:48,730 - DEBUG - ya.test - _save_outputs: Command (pid 3743989) elapsed time (sec): 30.46123766899109
2025-11-14 21:13:48,730 - DEBUG - ya.test - _save_outputs: Command (pid 3743989) wtime: 30.463
2025-11-14 21:13:48,730 - DEBUG - ya.test - _save_outputs: Command (pid 3743989) 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	0	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	2	0	0	0	0
7	      0	0	0	8	0	0	0	0
8	      0	0	0	10	0	0	0	0
9	      0	0	0	0	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	0	0	0	0	0
13	      0	0	0	10	0	0	0	0
14	      0	0	0	10	0	0	0	0
15	      0	0	0	10	0	0	0	0
16	      0	0	0	0	0	0	0	0
17	      0	0	0	10	0	0	0	0
18	      0	0	0	10	0	0	0	0
19	      0	0	0	10	0	0	0	0
20	      0	0	0	10	0	0	0	0
21	      0	0	0	4	0	0	0	0
22	      0	0	0	6	0	0	0	0
23	      0	0	0	5	0	0	0	0
24	      0	0	0	5	0	0	0	0
25	      0	0	0	10	0	0	0	0
26	      0	0	0	4	0	0	0	0
27	      0	0	0	5	0	0	0	0
28	      0	0	0	10	0	0	0	0
29	      9	9	0	9	523	875	875	875
30	     57	57	0	0	237	367	477	711

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	     66	2.2	0	198	248	579	711	875

2025-11-14 21:13:48,731 - DEBUG - ya.test - _save_outputs: Command (pid 3743989) errors:

2025-11-14 21:13:49,405 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-14 21:13:49,411 - DEBUG - ya.test - _save_outputs: Command (pid 3744038) rc: 0
2025-11-14 21:13:49,413 - DEBUG - ya.test - _save_outputs: Command (pid 3744038) elapsed time (sec): 31.0952365398407
2025-11-14 21:13:49,413 - DEBUG - ya.test - _save_outputs: Command (pid 3744038) wtime: 31.101
2025-11-14 21:13:49,413 - DEBUG - ya.test - _save_outputs: Command (pid 3744038) 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	0	0	0	0	0
6	      0	0	0	10	0	0	0	0
7	      0	0	0	10	0	0	0	0
8	      0	0	0	0	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	0	0	0	0	0
12	      0	0	0	10	0	0	0	0
13	      0	0	0	10	0	0	0	0
14	      0	0	0	0	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	10	0	0	0	0
19	      0	0	0	10	0	0	0	0
20	      0	0	0	9	0	0	0	0
21	      0	0	0	0	0	0	0	0
22	      0	0	0	1	0	0	0	0
23	      0	0	0	10	0	0	0	0
24	      0	0	0	9	0	0	0	0
25	      0	0	0	1	0	0	0	0
26	      0	0	0	9	0	0	0	0
27	      0	0	0	10	0	0	0	0
28	     10	10	0	10	443	463	463	463
29	     74	74	0	0	128	287	293	303
30	    212	212	0	0	42	121	151	156

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    296	9.86667	0	199	48	285	447	463

2025-11-14 21:13:49,413 - DEBUG - ya.test - _save_outputs: Command (pid 3744038) errors:

2025-11-14 21:14:07,251 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:14:07,252 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:14:07,252 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ccf8500d-4df2-41be-ad93-cd54e087f941, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:07,253 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ccf8500d-4df2-41be-ad93-cd54e087f941, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" 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-14 21:14:07,253 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ccf8500d-4df2-41be-ad93-cd54e087f941, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:07,258 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:14:07,259 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:14:07,260 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b940fd3b-73f7-4f6f-bb5d-1f6a2540b591, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:07,260 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b940fd3b-73f7-4f6f-bb5d-1f6a2540b591, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" 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-14 21:14:07,261 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b940fd3b-73f7-4f6f-bb5d-1f6a2540b591, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:07,351 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:14:07,351 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:14:07,352 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1b3ff043-dc19-4a20-8df2-8b8303cd4000, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:07,352 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1b3ff043-dc19-4a20-8df2-8b8303cd4000, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" 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-14 21:14:07,354 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1b3ff043-dc19-4a20-8df2-8b8303cd4000, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:07,702 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:14:07,703 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:14:07,703 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5a1cb698-b543-4945-80d4-6c24a3956496, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:07,704 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5a1cb698-b543-4945-80d4-6c24a3956496, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:14:07,704 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5a1cb698-b543-4945-80d4-6c24a3956496, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:07,716 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:14:07,716 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:14:07,717 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8564005e-24e2-4386-a8a8-95bbcf32a4ee, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:07,717 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8564005e-24e2-4386-a8a8-95bbcf32a4ee, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==" 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-11-14 21:14:07,718 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8564005e-24e2-4386-a8a8-95bbcf32a4ee, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:07,858 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:14:07,860 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:14:07,860 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d3ce1b0a-de98-4468-8d28-ac0f4bc1a705, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:07,864 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d3ce1b0a-de98-4468-8d28-ac0f4bc1a705, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:14:07,871 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d3ce1b0a-de98-4468-8d28-ac0f4bc1a705, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:07,883 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:14:07,883 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:14:07,884 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 75443a56-aa16-410d-9d92-1c47516ae22c, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:07,884 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 75443a56-aa16-410d-9d92-1c47516ae22c, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=" 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-14 21:14:07,885 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 75443a56-aa16-410d-9d92-1c47516ae22c, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:08,310 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:14:08,315 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:14:08,315 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 69c42f12-a31b-4add-89fe-d49fb0e7ccc1, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:08,316 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 69c42f12-a31b-4add-89fe-d49fb0e7ccc1, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:14:08,317 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 69c42f12-a31b-4add-89fe-d49fb0e7ccc1, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:08,316 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:14:08,359 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:14:08,392 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 60eb8d51-a4ad-4d6b-9558-002fbb2c0275, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:08,405 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 60eb8d51-a4ad-4d6b-9558-002fbb2c0275, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" 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-14 21:14:08,406 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 60eb8d51-a4ad-4d6b-9558-002fbb2c0275, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:08,510 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:14:08,511 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:14:08,511 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a2b3221f-fe0c-4d0b-90c9-05fda2221d70, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:08,512 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a2b3221f-fe0c-4d0b-90c9-05fda2221d70, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:14:08,530 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a2b3221f-fe0c-4d0b-90c9-05fda2221d70, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:10,676 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:14:10,690 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:14:10,690 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f05fe71a-9f45-47ea-8f05-bd0d3e0f176c, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:10,691 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f05fe71a-9f45-47ea-8f05-bd0d3e0f176c, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" 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-14 21:14:10,693 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f05fe71a-9f45-47ea-8f05-bd0d3e0f176c, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:10,698 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:14:10,711 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:14:10,733 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a8f7f57d-c02f-4738-97f5-9025a2c027cc, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:10,756 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a8f7f57d-c02f-4738-97f5-9025a2c027cc, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" 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-14 21:14:10,779 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:14:10,782 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a8f7f57d-c02f-4738-97f5-9025a2c027cc, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:10,828 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:14:10,830 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a2b553c7-8104-41fb-9fd6-afb976a067e7, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:10,832 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a2b553c7-8104-41fb-9fd6-afb976a067e7, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" 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-14 21:14:10,846 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a2b553c7-8104-41fb-9fd6-afb976a067e7, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:11,238 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:14:11,239 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:14:11,239 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 105ddabf-9107-43d0-b261-f1360203308d, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:11,240 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 105ddabf-9107-43d0-b261-f1360203308d, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" 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-14 21:14:11,248 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:14:11,257 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 105ddabf-9107-43d0-b261-f1360203308d, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:11,263 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:14:11,271 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:14:11,296 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c457a7e8-cd9c-469e-aed8-aae30979e133, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:11,296 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:14:11,298 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c457a7e8-cd9c-469e-aed8-aae30979e133, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" 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-14 21:14:11,303 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d36b39e5-ec45-4400-8abe-aa6f0a44d289, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:11,314 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c457a7e8-cd9c-469e-aed8-aae30979e133, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:11,316 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d36b39e5-ec45-4400-8abe-aa6f0a44d289, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" 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-14 21:14:11,339 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d36b39e5-ec45-4400-8abe-aa6f0a44d289, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:11,442 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:14:11,459 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:14:11,463 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a47d6d00-f098-4317-adf5-d0b29f172fb8, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:11,464 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:14:11,471 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:14:11,473 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:14:11,473 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:14:11,480 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:14:11,481 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:14:11,481 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:14:11,482 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:14:11,495 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a47d6d00-f098-4317-adf5-d0b29f172fb8, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:14:11,542 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:14:11,544 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:14:11,634 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a47d6d00-f098-4317-adf5-d0b29f172fb8, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:11,634 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3252f5f0-fb57-4558-883d-1e5952b838ee, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:11,635 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:14:11,637 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ecd43b2e-3ee2-4564-aba5-308ac4feec04, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:11,638 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:14:11,637 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3252f5f0-fb57-4558-883d-1e5952b838ee, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:14:11,639 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:14:11,639 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7c7cc683-5fc5-478e-9687-db1bd930b818, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:11,638 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ecd43b2e-3ee2-4564-aba5-308ac4feec04, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==" 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-11-14 21:14:11,640 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3252f5f0-fb57-4558-883d-1e5952b838ee, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:11,641 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c2ace9fa-85b4-4931-910d-e3053797a4bf, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:11,643 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7c7cc683-5fc5-478e-9687-db1bd930b818, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" 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-14 21:14:11,642 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:14:11,644 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ecd43b2e-3ee2-4564-aba5-308ac4feec04, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:11,647 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c2ace9fa-85b4-4931-910d-e3053797a4bf, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:14:11,666 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c2ace9fa-85b4-4931-910d-e3053797a4bf, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:11,663 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5aa53b89-4f12-4177-b9e7-b4f736345c87, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:11,663 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:14:11,648 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7c7cc683-5fc5-478e-9687-db1bd930b818, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:11,669 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5aa53b89-4f12-4177-b9e7-b4f736345c87, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==" 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-14 21:14:11,670 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b2be8004-7739-4531-9df1-d945c721046d, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:11,681 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5aa53b89-4f12-4177-b9e7-b4f736345c87, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:11,682 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b2be8004-7739-4531-9df1-d945c721046d, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:14:11,715 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b2be8004-7739-4531-9df1-d945c721046d, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:12,546 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:14:12,548 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:14:12,549 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cd407997-1cbf-4f0c-9ab1-2c03a69dc585, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:12,550 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cd407997-1cbf-4f0c-9ab1-2c03a69dc585, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(9))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:14:12,552 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:14:12,553 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:14:12,553 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cd407997-1cbf-4f0c-9ab1-2c03a69dc585, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:12,554 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 498edf5a-cfe4-4ae6-92b1-fabdbc0f1111, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:12,554 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:14:12,555 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:14:12,555 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:14:12,556 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 498edf5a-cfe4-4ae6-92b1-fabdbc0f1111, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=" 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-11-14 21:14:12,557 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:14:12,557 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:14:12,558 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:14:12,561 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 293b2f2d-8669-4c7d-93b5-92576a2b552e, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:12,561 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:14:12,562 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 498edf5a-cfe4-4ae6-92b1-fabdbc0f1111, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:12,564 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 293b2f2d-8669-4c7d-93b5-92576a2b552e, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(9))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:14:12,567 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:14:12,566 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5001eeea-ec3c-40a8-8c47-c73d4614489e, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:12,569 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a83da896-b41a-4932-919d-7b4445f9370e, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:12,569 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:14:12,569 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 293b2f2d-8669-4c7d-93b5-92576a2b552e, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:12,570 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5001eeea-ec3c-40a8-8c47-c73d4614489e, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(6))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:14:12,571 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a83da896-b41a-4932-919d-7b4445f9370e, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==" 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-14 21:14:12,571 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 92219121-885e-46a7-b932-83078fd8ce4d, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:12,574 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5001eeea-ec3c-40a8-8c47-c73d4614489e, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:12,572 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:14:12,577 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cf3f9e39-20fb-4c4d-891a-338360060f79, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:12,577 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cf3f9e39-20fb-4c4d-891a-338360060f79, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(6))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:14:12,578 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a83da896-b41a-4932-919d-7b4445f9370e, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:12,575 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 92219121-885e-46a7-b932-83078fd8ce4d, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==" 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-14 21:14:12,579 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 92219121-885e-46a7-b932-83078fd8ce4d, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:12,580 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cf3f9e39-20fb-4c4d-891a-338360060f79, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:12,900 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:14:12,901 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:14:12,903 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:14:12,911 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:14:12,913 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:14:12,914 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:14:12,915 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:14:12,915 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:14:12,916 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 55a6c0a0-1e94-40c1-9522-ff9db1aaf8b6, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:12,916 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 55a6c0a0-1e94-40c1-9522-ff9db1aaf8b6, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" 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-14 21:14:12,917 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 55a6c0a0-1e94-40c1-9522-ff9db1aaf8b6, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:14:13,219 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:14:13,219 - INFO - root - test_log_uniform: check insert: 5621 10505
2025-11-14 21:14:13,221 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.16
2025-11-14 21:14:13,222 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.16
2025-11-14 21:14:13,222 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-11-14 21:14:13,223 - DEBUG - ya.test - execute: Command pid: 3779378
2025-11-14 21:14:13,441 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) rc: 0
2025-11-14 21:14:13,442 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) elapsed time (sec): 0.21747756004333496
2025-11-14 21:14:13,442 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) idrss: 0
2025-11-14 21:14:13,442 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) inblock: 0
2025-11-14 21:14:13,442 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) isrss: 0
2025-11-14 21:14:13,443 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) ixrss: 0
2025-11-14 21:14:13,443 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) majflt: 0
2025-11-14 21:14:13,443 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) maxrss: 684424
2025-11-14 21:14:13,443 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) minflt: 3477
2025-11-14 21:14:13,443 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) msgrcv: 0
2025-11-14 21:14:13,443 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) msgsnd: 0
2025-11-14 21:14:13,444 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) nivcsw: 35
2025-11-14 21:14:13,444 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) nsignals: 0
2025-11-14 21:14:13,444 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) nswap: 0
2025-11-14 21:14:13,444 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) nvcsw: 167
2025-11-14 21:14:13,444 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) oublock: 8
2025-11-14 21:14:13,444 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) stime: 0.031044
2025-11-14 21:14:13,445 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) utime: 0.048784
2025-11-14 21:14:13,445 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) wtime: 0.219
2025-11-14 21:14:13,445 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) output:
Remove path log...
Remove path log...Ok

2025-11-14 21:14:13,445 - DEBUG - ya.test - _save_outputs: Command (pid 3779378) errors:

2025-11-14 21:14:13,447 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-14 21:14:13,448 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 8780e60d-5862-499a-993e-e2017c9e5c84, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:13,448 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 8780e60d-5862-499a-993e-e2017c9e5c84, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==" }
2025-11-14 21:14:13,473 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 8780e60d-5862-499a-993e-e2017c9e5c84, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS }
2025-11-14 21:14:13,474 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, ce28a742-cd2c-49f9-8247-d8bc1cb6949d, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:13,475 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, ce28a742-cd2c-49f9-8247-d8bc1cb6949d, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=" }
2025-11-14 21:14:13,478 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, ce28a742-cd2c-49f9-8247-d8bc1cb6949d, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS }
2025-11-14 21:14:13,478 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 2c3be45f-6b74-4e1c-95a7-2eefdc5c413a, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:13,479 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 2c3be45f-6b74-4e1c-95a7-2eefdc5c413a, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==" }
2025-11-14 21:14:13,481 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 2c3be45f-6b74-4e1c-95a7-2eefdc5c413a, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS }
2025-11-14 21:14:13,481 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 8c2d3b19-70c7-43a6-8994-be34c07728e8, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:13,482 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 8c2d3b19-70c7-43a6-8994-be34c07728e8, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" }
2025-11-14 21:14:13,483 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 8c2d3b19-70c7-43a6-8994-be34c07728e8, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS }
2025-11-14 21:14:13,487 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 2dea32be-2231-47d5-aa1c-2d5bb207fff3, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:13,487 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 2dea32be-2231-47d5-aa1c-2d5bb207fff3, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=" }
2025-11-14 21:14:13,491 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 2dea32be-2231-47d5-aa1c-2d5bb207fff3, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS }
2025-11-14 21:14:13,492 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 567125c9-152e-49ad-91e2-7144e70b804f, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:13,493 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 567125c9-152e-49ad-91e2-7144e70b804f, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=" }
2025-11-14 21:14:13,494 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 567125c9-152e-49ad-91e2-7144e70b804f, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS }
2025-11-14 21:14:13,494 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 731be076-e385-4e77-9cfa-dcdf4d3054c1, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:13,495 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 731be076-e385-4e77-9cfa-dcdf4d3054c1, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==" }
2025-11-14 21:14:13,497 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 731be076-e385-4e77-9cfa-dcdf4d3054c1, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS }
2025-11-14 21:14:13,497 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, a9c4134f-368d-438e-9127-8b7dcdcad534, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:13,497 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, a9c4134f-368d-438e-9127-8b7dcdcad534, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" }
2025-11-14 21:14:13,499 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, a9c4134f-368d-438e-9127-8b7dcdcad534, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS }
2025-11-14 21:14:13,500 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 92f14a73-5a0f-4c6e-85e5-8c2ff541db53, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:13,500 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 92f14a73-5a0f-4c6e-85e5-8c2ff541db53, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" }
2025-11-14 21:14:13,502 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 92f14a73-5a0f-4c6e-85e5-8c2ff541db53, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS }
2025-11-14 21:14:13,503 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, b132b3eb-749c-49e5-b80c-f5e0a4dbdad5, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:14:13,503 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, b132b3eb-749c-49e5-b80c-f5e0a4dbdad5, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" }
2025-11-14 21:14:13,505 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, b132b3eb-749c-49e5-b80c-f5e0a4dbdad5, ghrun-alaktn3s7q.auto.internal:18008): response = { status: SUCCESS }
2025-11-14 21:14:13,506 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-14 21:14:13,507 - DEBUG - ydb.topic - close: Close topic client
2025-11-14 21:14:13,507 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-14 21:14:13,507 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18008
2025-11-14 21:14:13,508 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-alaktn3s7q.auto.internal:18008
2025-11-14 21:14:13,509 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-14 21:14:25,515 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:18008/1
2025-11-14 21:14:25,517 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22655
2025-11-14 21:14:25,520 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19392
2025-11-14 21:14:25,520 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/26166
2025-11-14 21:14:25,521 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/18008
2025-11-14 21:14:25,521 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3815
