2025-11-12 08:30:00,561 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-12 08:30:00,590 - INFO - ya.test - pytest_runtest_setup: test_log_uniform
2025-11-12 08:30:00,599 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-12 08:30:00,600 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-12 08:30:00,612 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_uniform)
2025-11-12 08:30:00,612 - 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:30:00,613 - 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:30:00,616 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.11
2025-11-12 08:30:00,621 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.11
2025-11-12 08:30:00,623 - 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:30:00,624 - DEBUG - ya.test - execute: Command pid: 3667131
2025-11-12 08:30:01,596 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) rc: 0
2025-11-12 08:30:01,596 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) elapsed time (sec): 0.9667541980743408
2025-11-12 08:30:01,596 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) idrss: 0
2025-11-12 08:30:01,596 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) inblock: 0
2025-11-12 08:30:01,597 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) isrss: 0
2025-11-12 08:30:01,597 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) ixrss: 0
2025-11-12 08:30:01,597 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) majflt: 0
2025-11-12 08:30:01,597 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) maxrss: 788384
2025-11-12 08:30:01,597 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) minflt: 3480
2025-11-12 08:30:01,597 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) msgrcv: 0
2025-11-12 08:30:01,598 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) msgsnd: 0
2025-11-12 08:30:01,598 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) nivcsw: 46
2025-11-12 08:30:01,598 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) nsignals: 0
2025-11-12 08:30:01,598 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) nswap: 0
2025-11-12 08:30:01,598 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) nvcsw: 132
2025-11-12 08:30:01,598 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) oublock: 8
2025-11-12 08:30:01,599 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) stime: 0.028513
2025-11-12 08:30:01,599 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) utime: 0.061091
2025-11-12 08:30:01,599 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) wtime: 0.968
2025-11-12 08:30:01,599 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) output:
Init tables ...
Init tables ...Ok

2025-11-12 08:30:01,599 - DEBUG - ya.test - _save_outputs: Command (pid 3667131) errors:

2025-11-12 08:30:01,630 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-12 08:30:01,636 - 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 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-11-12 08:30:01,662 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.12
2025-11-12 08:30:01,663 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.12
2025-11-12 08:30:01,663 - 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', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-11-12 08:30:01,676 - DEBUG - ya.test - execute: Command pid: 3667798
2025-11-12 08:30:31,924 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-12 08:30:31,957 - DEBUG - ya.test - _save_outputs: Command (pid 3667798) rc: 0
2025-11-12 08:30:31,957 - DEBUG - ya.test - _save_outputs: Command (pid 3667798) elapsed time (sec): 30.273826360702515
2025-11-12 08:30:31,957 - DEBUG - ya.test - _save_outputs: Command (pid 3667798) wtime: 30.293
2025-11-12 08:30:31,957 - DEBUG - ya.test - _save_outputs: Command (pid 3667798) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	    281	281	0	0	33	58	76	85
2	    237	237	0	0	40	65	72	73
3	    260	260	0	0	30	108	153	159
4	    316	316	0	0	30	47	52	56
5	    329	329	0	0	29	48	63	66
6	    322	322	0	0	29	48	55	69
7	    301	301	0	0	30	54	66	73
8	    332	332	0	0	27	52	63	68
9	    320	320	0	0	28	55	69	82
10	    229	229	0	0	38	88	105	107
11	    319	319	0	0	28	52	68	78
12	    291	291	0	0	29	67	108	116
13	    313	313	0	0	29	54	79	93
14	    242	242	0	0	40	69	86	91
15	    224	224	0	0	41	72	87	99
16	    358	358	0	0	25	50	64	73
17	    415	415	0	0	22	40	46	63
18	    280	280	0	0	34	49	57	63
19	    449	449	0	0	20	41	54	60
20	    348	348	0	0	27	41	52	59
21	    290	290	0	0	31	61	75	89
22	    332	332	0	0	27	47	90	99
23	    322	322	0	0	28	58	71	92
24	    258	258	0	0	24	82	241	247
25	    427	427	0	0	20	45	55	58
26	    319	319	0	0	29	54	61	64
27	    376	376	0	0	23	45	53	58
28	    355	355	0	0	24	55	69	75
29	    288	288	0	0	33	57	68	73
30	    355	355	0	0	28	42	50	58

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	   9488	316.267	0	0	28	56	77	247

2025-11-12 08:30:31,958 - DEBUG - ya.test - _save_outputs: Command (pid 3667798) errors:
Terminating due to uncaught exception 0x729e7f4fe210    what() -> "(Error 35: Resource deadlock avoided) util/system/thread.cpp:199: can not join thread"

2025-11-12 08:30:31,994 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==
2025-11-12 08:30:31,994 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c86d85a6-35d1-43a6-913d-96e179c9be81, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:30:31,995 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c86d85a6-35d1-43a6-913d-96e179c9be81, 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(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:30:32,001 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c86d85a6-35d1-43a6-913d-96e179c9be81, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:30:36,323 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==
2025-11-12 08:30:36,324 - INFO - root - test_log_uniform: Count rows after insert 9486 before wait
2025-11-12 08:30:36,324 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:30:36,325 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5cf568b7-e3ca-4b83-821f-22d370e6365d, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:30:36,369 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5cf568b7-e3ca-4b83-821f-22d370e6365d, 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(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:30:36,370 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5cf568b7-e3ca-4b83-821f-22d370e6365d, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:30:40,205 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:30:40,206 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=
2025-11-12 08:30:40,206 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5fc77e44-a4bc-4384-9598-aef8f27ce095, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:30:40,207 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5fc77e44-a4bc-4384-9598-aef8f27ce095, 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(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:30:40,208 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5fc77e44-a4bc-4384-9598-aef8f27ce095, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:30:45,129 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=
2025-11-12 08:30:45,168 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-12 08:30:45,168 - 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 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-11-12 08:30:45,170 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.13
2025-11-12 08:30:45,170 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.13
2025-11-12 08:30:45,170 - 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', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-11-12 08:30:45,200 - DEBUG - ya.test - execute: Command pid: 3686148
2025-11-12 08:30:45,212 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-11-12 08:30:45,212 - 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 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-11-12 08:30:45,214 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.14
2025-11-12 08:30:45,222 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-11-12 08:30:45,223 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:30:45,224 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.14
2025-11-12 08:30:45,234 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7faabdcc-bd2b-4752-b3dd-a6dea9763027, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:30:45,235 - 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', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-11-12 08:30:45,232 - 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 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-11-12 08:30:45,236 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7faabdcc-bd2b-4752-b3dd-a6dea9763027, 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:30:45,254 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7faabdcc-bd2b-4752-b3dd-a6dea9763027, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:30:45,251 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==
2025-11-12 08:30:45,256 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4e0b8657-dfdc-45be-aaf1-3040e5d470e4, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:30:45,257 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4e0b8657-dfdc-45be-aaf1-3040e5d470e4, 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:30:45,258 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4e0b8657-dfdc-45be-aaf1-3040e5d470e4, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:30:45,258 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:30:45,259 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 20b122bd-0ee7-428d-86e7-d8130f07e9b7, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:30:45,260 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 20b122bd-0ee7-428d-86e7-d8130f07e9b7, 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:30:45,259 - DEBUG - ya.test - execute: Command pid: 3686168
2025-11-12 08:30:45,259 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:30:45,253 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.15
2025-11-12 08:30:45,269 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.15
2025-11-12 08:30:45,269 - 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', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-11-12 08:30:45,268 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8e13ca39-0861-4d63-b67f-d7994ca1d9d4, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:30:45,271 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=
2025-11-12 08:30:45,262 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 20b122bd-0ee7-428d-86e7-d8130f07e9b7, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:30:45,294 - DEBUG - ya.test - execute: Command pid: 3686181
2025-11-12 08:30:45,305 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8e13ca39-0861-4d63-b67f-d7994ca1d9d4, 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:30:45,325 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8e13ca39-0861-4d63-b67f-d7994ca1d9d4, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:30:45,315 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=
2025-11-12 08:30:45,341 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 76e790d5-aa8f-485c-9d48-9d0700c777c6, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:30:45,313 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4871eb7c-e905-4a68-b42a-88b48beaef35, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:30:45,371 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4871eb7c-e905-4a68-b42a-88b48beaef35, 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:30:45,372 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4871eb7c-e905-4a68-b42a-88b48beaef35, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:30:45,347 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:30:45,373 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3529206f-1779-4369-af38-80fca53eeb63, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:30:45,374 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3529206f-1779-4369-af38-80fca53eeb63, 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:30:45,374 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3529206f-1779-4369-af38-80fca53eeb63, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:30:45,364 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 76e790d5-aa8f-485c-9d48-9d0700c777c6, 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:30:45,375 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 76e790d5-aa8f-485c-9d48-9d0700c777c6, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:30:45,376 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==
2025-11-12 08:30:45,376 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f9029e31-c872-4854-ad6b-a1c9f0a529d6, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:30:45,377 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:30:45,405 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0a73cf3a-957e-48eb-84c8-db23871cdeaf, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:30:45,406 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0a73cf3a-957e-48eb-84c8-db23871cdeaf, 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:30:45,407 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0a73cf3a-957e-48eb-84c8-db23871cdeaf, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:30:45,407 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=
2025-11-12 08:30:45,408 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 84718adc-6215-425b-ba02-5da3c6c68189, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:30:45,409 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 84718adc-6215-425b-ba02-5da3c6c68189, 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:30:45,409 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 84718adc-6215-425b-ba02-5da3c6c68189, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:30:45,404 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f9029e31-c872-4854-ad6b-a1c9f0a529d6, 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:30:45,422 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f9029e31-c872-4854-ad6b-a1c9f0a529d6, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:15,831 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-12 08:31:15,833 - DEBUG - ya.test - _save_outputs: Command (pid 3686148) rc: 0
2025-11-12 08:31:15,837 - DEBUG - ya.test - _save_outputs: Command (pid 3686148) elapsed time (sec): 30.631479024887085
2025-11-12 08:31:15,845 - DEBUG - ya.test - _save_outputs: Command (pid 3686148) wtime: 30.661
2025-11-12 08:31:15,846 - DEBUG - ya.test - _save_outputs: Command (pid 3686148) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	      0	0	0	0	0	0	0	0
3	      0	0	0	0	0	0	0	0
4	      0	0	0	0	0	0	0	0
5	      5	5	0	0	4767	5023	5023	5023
6	      5	5	0	0	5055	5087	5087	5087
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	      0	0	0	0	0	0	0	0
18	      0	0	0	0	0	0	0	0
19	      1	1	0	0	14399	14399	14399	14399
20	      9	9	0	0	14463	14719	14719	14719
21	      0	0	0	0	0	0	0	0
22	      0	0	0	0	0	0	0	0
23	     10	10	0	0	3519	3935	3935	3935
24	     43	43	0	0	179	353	353	353
25	     37	37	0	0	269	339	365	365
26	     53	53	0	0	186	297	301	315
27	     51	51	0	0	148	307	317	331
28	     17	17	0	0	493	547	575	575
29	     20	20	0	0	579	647	647	647
30	     51	51	0	0	289	369	377	467

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    302	10.0667	0	0	281	5023	14527	14719

2025-11-12 08:31:15,846 - DEBUG - ya.test - _save_outputs: Command (pid 3686148) errors:

2025-11-12 08:31:16,568 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-12 08:31:16,576 - DEBUG - ya.test - _save_outputs: Command (pid 3686181) rc: 0
2025-11-12 08:31:16,577 - DEBUG - ya.test - _save_outputs: Command (pid 3686181) elapsed time (sec): 31.256013870239258
2025-11-12 08:31:16,583 - DEBUG - ya.test - _save_outputs: Command (pid 3686181) wtime: 31.292
2025-11-12 08:31:16,586 - DEBUG - ya.test - _save_outputs: Command (pid 3686181) 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	8	0	0	0	0
6	      0	0	0	2	0	0	0	0
7	      0	0	0	10	0	0	0	0
8	      0	0	0	7	0	0	0	0
9	      0	0	0	3	0	0	0	0
10	      0	0	0	6	0	0	0	0
11	      0	0	0	4	0	0	0	0
12	      0	0	0	10	0	0	0	0
13	      0	0	0	0	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	0	0	0	0	0
20	      0	0	0	10	0	0	0	0
21	      0	0	0	10	0	0	0	0
22	      0	0	0	10	0	0	0	0
23	     35	35	0	0	367	755	791	791
24	     29	29	0	0	297	389	403	403
25	     42	42	0	0	279	383	411	411
26	     45	45	0	0	174	323	343	343
27	     17	17	0	0	539	599	615	615
28	     12	12	0	0	695	743	747	747
29	     34	34	0	0	347	675	731	731
30	     56	56	0	0	173	359	361	371

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    270	9	0	150	277	715	755	791

2025-11-12 08:31:16,586 - DEBUG - ya.test - _save_outputs: Command (pid 3686181) errors:

2025-11-12 08:31:16,752 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-12 08:31:16,752 - DEBUG - ya.test - _save_outputs: Command (pid 3686168) rc: 0
2025-11-12 08:31:16,753 - DEBUG - ya.test - _save_outputs: Command (pid 3686168) elapsed time (sec): 31.48657202720642
2025-11-12 08:31:16,753 - DEBUG - ya.test - _save_outputs: Command (pid 3686168) wtime: 31.515
2025-11-12 08:31:16,753 - DEBUG - ya.test - _save_outputs: Command (pid 3686168) 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	8	0	0	0	0
6	      0	0	0	2	0	0	0	0
7	      0	0	0	10	0	0	0	0
8	      0	0	0	6	0	0	0	0
9	      0	0	0	4	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	0	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	0	0	0	0	0
20	      0	0	0	10	0	0	0	0
21	      0	0	0	10	0	0	0	0
22	      0	0	0	10	0	0	0	0
23	     27	27	0	0	427	835	835	835
24	     17	17	0	1	403	667	699	699
25	     29	29	0	0	403	599	683	683
26	     28	28	0	0	325	487	539	539
27	     12	12	0	0	647	863	915	915
28	     11	11	0	1	851	939	983	983
29	     24	24	0	0	443	859	939	939
30	     37	37	0	1	303	575	803	803

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    185	6.16667	0	153	403	851	939	983

2025-11-12 08:31:16,773 - DEBUG - ya.test - _save_outputs: Command (pid 3686168) errors:

2025-11-12 08:31:29,472 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-12 08:31:29,472 - 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:31:29,477 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-12 08:31:29,478 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 7aa54fb8-ecf3-456e-ab44-6329eeafb1c7, localhost:5575): creating call state
2025-11-12 08:31:29,478 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 7aa54fb8-ecf3-456e-ab44-6329eeafb1c7, localhost:5575): request = { database: "/Root" }
2025-11-12 08:31:29,513 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 7aa54fb8-ecf3-456e-ab44-6329eeafb1c7, 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:31:29,513 - 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:31:29,515 - INFO - ydb.connection - close: Closing channel for endpoint localhost:5575
2025-11-12 08:31:33,420 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=
2025-11-12 08:31:33,420 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=
2025-11-12 08:31:33,421 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8928d94d-b386-4bff-940f-87a3fa0ea98f, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:33,422 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8928d94d-b386-4bff-940f-87a3fa0ea98f, 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(6)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:33,425 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8928d94d-b386-4bff-940f-87a3fa0ea98f, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:33,501 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:31:33,501 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:31:33,507 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 41fde499-f653-4582-9491-3b8c17439020, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:33,510 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 41fde499-f653-4582-9491-3b8c17439020, 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(9)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:33,516 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 41fde499-f653-4582-9491-3b8c17439020, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:33,784 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:31:33,784 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:31:33,785 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 92445301-afd3-4e04-93d8-69b497b9ae62, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:33,785 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 92445301-afd3-4e04-93d8-69b497b9ae62, 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:31:33,796 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 92445301-afd3-4e04-93d8-69b497b9ae62, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:34,171 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:31:34,172 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:31:34,173 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 48690381-fa01-4df6-a6a1-0d6538808f97, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:34,173 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 48690381-fa01-4df6-a6a1-0d6538808f97, 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(6)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:34,174 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 48690381-fa01-4df6-a6a1-0d6538808f97, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:34,710 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:31:34,764 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:31:34,786 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6c919467-e09c-4870-a6df-117e5de07caf, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:34,789 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6c919467-e09c-4870-a6df-117e5de07caf, 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(6)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:34,790 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6c919467-e09c-4870-a6df-117e5de07caf, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:34,800 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==
2025-11-12 08:31:34,814 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==
2025-11-12 08:31:34,818 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 648a8ca2-2d44-48dc-982a-81683f49b0a7, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:34,819 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 648a8ca2-2d44-48dc-982a-81683f49b0a7, 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(10)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:34,821 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 648a8ca2-2d44-48dc-982a-81683f49b0a7, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:34,904 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=
2025-11-12 08:31:34,905 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=
2025-11-12 08:31:34,905 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 561c27e3-8a68-4b37-a632-369229254d11, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:34,908 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 561c27e3-8a68-4b37-a632-369229254d11, 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(5)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:34,910 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 561c27e3-8a68-4b37-a632-369229254d11, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:34,929 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=
2025-11-12 08:31:34,930 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=
2025-11-12 08:31:34,931 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6baa1a89-e4e6-405e-94f0-e1cfeafd75a3, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:34,936 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6baa1a89-e4e6-405e-94f0-e1cfeafd75a3, 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(1)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:34,958 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6baa1a89-e4e6-405e-94f0-e1cfeafd75a3, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:34,965 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==
2025-11-12 08:31:34,971 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==
2025-11-12 08:31:34,971 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 60d920a9-4ab3-444e-90b8-474026d8e3c4, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:34,991 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 60d920a9-4ab3-444e-90b8-474026d8e3c4, 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(1)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:34,995 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 60d920a9-4ab3-444e-90b8-474026d8e3c4, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:35,462 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:31:35,462 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:31:35,478 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7622baa5-d674-49f3-b455-a71d09a3b636, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:35,481 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7622baa5-d674-49f3-b455-a71d09a3b636, 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(1)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:35,483 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7622baa5-d674-49f3-b455-a71d09a3b636, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:39,068 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:31:39,103 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:31:39,104 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 72701e5f-136f-490c-a055-241be0eeb9ea, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:39,110 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 72701e5f-136f-490c-a055-241be0eeb9ea, 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(2)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:39,118 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 72701e5f-136f-490c-a055-241be0eeb9ea, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:39,150 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:31:39,179 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:31:39,180 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 27864164-a913-4995-a3fb-cceddac3f4ed, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:39,181 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 27864164-a913-4995-a3fb-cceddac3f4ed, 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)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:39,183 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 27864164-a913-4995-a3fb-cceddac3f4ed, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:39,274 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:31:39,296 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:31:39,297 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, efd41faf-8e9c-439e-a3e0-7bc2381479c8, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:39,298 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, efd41faf-8e9c-439e-a3e0-7bc2381479c8, 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(6)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:39,305 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, efd41faf-8e9c-439e-a3e0-7bc2381479c8, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:39,460 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=
2025-11-12 08:31:39,498 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=
2025-11-12 08:31:39,499 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 79a76855-4e83-442a-bfef-3107eaab5340, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:39,507 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 79a76855-4e83-442a-bfef-3107eaab5340, 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(6)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:39,541 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 79a76855-4e83-442a-bfef-3107eaab5340, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:40,116 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:31:40,135 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:31:40,135 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a52c4f00-fd5c-4c1f-9135-60a70678d035, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:40,136 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a52c4f00-fd5c-4c1f-9135-60a70678d035, 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:31:40,141 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a52c4f00-fd5c-4c1f-9135-60a70678d035, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:40,154 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:31:40,157 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:31:40,159 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=
2025-11-12 08:31:40,179 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=
2025-11-12 08:31:40,179 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fa982c56-0e77-483d-881f-177628532b87, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:40,180 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fa982c56-0e77-483d-881f-177628532b87, 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(7)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(6))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:40,170 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:31:40,181 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:31:40,181 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 50fc6df0-ffbf-4e32-89b5-000740e718f3, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:40,182 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 50fc6df0-ffbf-4e32-89b5-000740e718f3, 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(7)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(6))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:40,179 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:31:40,178 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2935d6d8-558c-43e2-b6cc-6143111d2d42, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:40,218 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:31:40,221 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fa982c56-0e77-483d-881f-177628532b87, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:40,223 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 50fc6df0-ffbf-4e32-89b5-000740e718f3, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:40,251 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2935d6d8-558c-43e2-b6cc-6143111d2d42, 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(10)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(9))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:40,256 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8077fc59-cf05-4846-8a76-6986880773b1, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:40,264 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8077fc59-cf05-4846-8a76-6986880773b1, 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(6)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:40,269 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2935d6d8-558c-43e2-b6cc-6143111d2d42, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:40,270 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8077fc59-cf05-4846-8a76-6986880773b1, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:40,273 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=
2025-11-12 08:31:40,298 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=
2025-11-12 08:31:40,299 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3077fc0b-aa60-471e-a46d-e7de9102d9ff, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:40,311 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3077fc0b-aa60-471e-a46d-e7de9102d9ff, 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(5)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:40,313 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3077fc0b-aa60-471e-a46d-e7de9102d9ff, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:40,324 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==
2025-11-12 08:31:40,338 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:31:40,396 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=
2025-11-12 08:31:40,397 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==
2025-11-12 08:31:40,424 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0d2b3212-1366-462f-a478-3035114e7a5e, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:40,424 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:31:40,426 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0d2b3212-1366-462f-a478-3035114e7a5e, 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(1)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:40,427 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==
2025-11-12 08:31:40,433 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6ba77cf9-5044-480a-a9a0-2c18d5ad94df, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:40,434 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=
2025-11-12 08:31:40,435 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0d2b3212-1366-462f-a478-3035114e7a5e, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:40,469 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6ba77cf9-5044-480a-a9a0-2c18d5ad94df, 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(1)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:40,477 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ed353ead-5465-49ba-8786-f87b6f392daf, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:40,477 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==
2025-11-12 08:31:40,483 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6ba77cf9-5044-480a-a9a0-2c18d5ad94df, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:40,487 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ed353ead-5465-49ba-8786-f87b6f392daf, 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(1)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:40,489 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 73a4f5b2-db2d-4924-b821-fa70e7a7a088, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:40,496 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ed353ead-5465-49ba-8786-f87b6f392daf, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:40,500 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 73a4f5b2-db2d-4924-b821-fa70e7a7a088, 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(10)" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:40,503 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 73a4f5b2-db2d-4924-b821-fa70e7a7a088, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:40,551 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:31:40,569 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:31:40,618 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=
2025-11-12 08:31:40,623 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:31:41,513 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:31:41,513 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:31:41,514 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d818b3d2-d546-4154-9690-bb886e3a673a, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:41,514 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d818b3d2-d546-4154-9690-bb886e3a673a, 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(7)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(6))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:41,515 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d818b3d2-d546-4154-9690-bb886e3a673a, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:41,524 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=
2025-11-12 08:31:41,525 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:31:41,525 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6357f34f-cd4b-4214-be0a-72fdf44cad8d, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:41,526 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6357f34f-cd4b-4214-be0a-72fdf44cad8d, 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(6)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(5))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:41,526 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6357f34f-cd4b-4214-be0a-72fdf44cad8d, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:41,576 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:31:41,596 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==
2025-11-12 08:31:41,597 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=
2025-11-12 08:31:41,598 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 969c8df6-4c8c-4fc2-8e8b-1a5322ab1278, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:41,599 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==
2025-11-12 08:31:41,600 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 969c8df6-4c8c-4fc2-8e8b-1a5322ab1278, 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(2)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(1))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:41,601 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:31:41,601 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=
2025-11-12 08:31:41,603 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 32d79998-0bd2-43df-ae1f-7af8b025016f, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:41,604 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 969c8df6-4c8c-4fc2-8e8b-1a5322ab1278, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:41,604 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:31:41,606 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 32d79998-0bd2-43df-ae1f-7af8b025016f, 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(11)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:41,607 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6a5e3755-fe7f-4809-884b-c74c7b7f72ae, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:41,610 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6a5e3755-fe7f-4809-884b-c74c7b7f72ae, 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)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(1))" } stats_mode: STATS_MODE_NONE }
2025-11-12 08:31:41,610 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=
2025-11-12 08:31:41,611 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 32d79998-0bd2-43df-ae1f-7af8b025016f, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:41,612 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=
2025-11-12 08:31:41,613 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e76bec6b-4364-43c0-8e2a-da83c02d899a, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:41,614 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e76bec6b-4364-43c0-8e2a-da83c02d899a, 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:31:41,613 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6a5e3755-fe7f-4809-884b-c74c7b7f72ae, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:41,615 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e76bec6b-4364-43c0-8e2a-da83c02d899a, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:41,820 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=
2025-11-12 08:31:41,841 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=
2025-11-12 08:31:41,845 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=
2025-11-12 08:31:41,845 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=
2025-11-12 08:31:41,846 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2
2025-11-12 08:31:41,847 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:31:41,848 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 19ac46aa-2e76-4c70-86ec-51bcdf7e8ce6, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:41,848 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 19ac46aa-2e76-4c70-86ec-51bcdf7e8ce6, 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:31:41,849 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 19ac46aa-2e76-4c70-86ec-51bcdf7e8ce6, ghrun-tqbhkoylkm.auto.internal:5575): response = { <_MultiThreadedRendezvous object> }
2025-11-12 08:31:42,199 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==
2025-11-12 08:31:42,199 - INFO - root - test_log_uniform: check insert: 6419 9486
2025-11-12 08:31:42,202 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.16
2025-11-12 08:31:42,203 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.16
2025-11-12 08:31:42,203 - 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:31:42,205 - DEBUG - ya.test - execute: Command pid: 3714593
2025-11-12 08:31:42,386 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) rc: 0
2025-11-12 08:31:42,386 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) elapsed time (sec): 0.17875266075134277
2025-11-12 08:31:42,387 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) idrss: 0
2025-11-12 08:31:42,387 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) inblock: 0
2025-11-12 08:31:42,387 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) isrss: 0
2025-11-12 08:31:42,387 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) ixrss: 0
2025-11-12 08:31:42,387 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) majflt: 0
2025-11-12 08:31:42,387 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) maxrss: 788384
2025-11-12 08:31:42,388 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) minflt: 3472
2025-11-12 08:31:42,388 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) msgrcv: 0
2025-11-12 08:31:42,388 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) msgsnd: 0
2025-11-12 08:31:42,388 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) nivcsw: 23
2025-11-12 08:31:42,388 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) nsignals: 0
2025-11-12 08:31:42,388 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) nswap: 0
2025-11-12 08:31:42,389 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) nvcsw: 178
2025-11-12 08:31:42,389 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) oublock: 8
2025-11-12 08:31:42,389 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) stime: 0.036458
2025-11-12 08:31:42,389 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) utime: 0.040508999999999996
2025-11-12 08:31:42,389 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) wtime: 0.182
2025-11-12 08:31:42,390 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) output:
Remove path log...
Remove path log...Ok

2025-11-12 08:31:42,390 - DEBUG - ya.test - _save_outputs: Command (pid 3714593) errors:

2025-11-12 08:31:42,393 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-12 08:31:42,394 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 270caf85-d985-4001-9a34-947bb6be6358, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:42,394 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 270caf85-d985-4001-9a34-947bb6be6358, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NmU3YmNkNC03ZTY3MjgxOS1jOWYyZTkwZC03NzQzYWY4NA==" }
2025-11-12 08:31:42,440 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 270caf85-d985-4001-9a34-947bb6be6358, ghrun-tqbhkoylkm.auto.internal:5575): response = { status: SUCCESS }
2025-11-12 08:31:42,441 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 89eb94ea-3225-4edd-8e1c-8536d0f2a055, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:42,442 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 89eb94ea-3225-4edd-8e1c-8536d0f2a055, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NTNiNDQ5NDAtOTExNGEwMTAtNzhmY2ViZS03MzFhMWEzOA==" }
2025-11-12 08:31:42,444 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 89eb94ea-3225-4edd-8e1c-8536d0f2a055, ghrun-tqbhkoylkm.auto.internal:5575): response = { status: SUCCESS }
2025-11-12 08:31:42,445 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 57bfdb56-f9d7-45d6-89f8-2b091578f1b5, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:42,446 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 57bfdb56-f9d7-45d6-89f8-2b091578f1b5, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=MmY3YzBiN2UtYWQwZGYyYzctYjI0ODRiZjItZGIyNjRkNWY=" }
2025-11-12 08:31:42,447 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 57bfdb56-f9d7-45d6-89f8-2b091578f1b5, ghrun-tqbhkoylkm.auto.internal:5575): response = { status: SUCCESS }
2025-11-12 08:31:42,448 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, f36f95be-4d46-4db5-8473-703e9d6c9834, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:42,449 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, f36f95be-4d46-4db5-8473-703e9d6c9834, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=ODljMDM1OWYtYWM2MTI2MGUtOGRhZWQ3NjQtNTY2YTU1MTQ=" }
2025-11-12 08:31:42,450 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, f36f95be-4d46-4db5-8473-703e9d6c9834, ghrun-tqbhkoylkm.auto.internal:5575): response = { status: SUCCESS }
2025-11-12 08:31:42,451 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 398e26a8-558e-4f5b-932c-7253825c28c9, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:42,451 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 398e26a8-558e-4f5b-932c-7253825c28c9, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NThkM2YzMjUtZGE0ZDlhMjctYjk1NmQ4N2YtZGNmOGNjM2U=" }
2025-11-12 08:31:42,453 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 398e26a8-558e-4f5b-932c-7253825c28c9, ghrun-tqbhkoylkm.auto.internal:5575): response = { status: SUCCESS }
2025-11-12 08:31:42,454 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, fef296d9-9360-426f-b87e-2cdb90e4d69a, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:42,455 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, fef296d9-9360-426f-b87e-2cdb90e4d69a, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NTM5NTVlMmEtNGNkMDQ1NzktNGYxYjNiYjgtODZlNWQ1ZTI=" }
2025-11-12 08:31:42,457 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, fef296d9-9360-426f-b87e-2cdb90e4d69a, ghrun-tqbhkoylkm.auto.internal:5575): response = { status: SUCCESS }
2025-11-12 08:31:42,457 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 1a4b7bfb-0a31-4cd0-9328-1643b1dae31d, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:42,458 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 1a4b7bfb-0a31-4cd0-9328-1643b1dae31d, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=OGFmNzg0ODMtODg2MjkzMWMtNTU2NDI0N2QtMzBlNmJhZWY=" }
2025-11-12 08:31:42,459 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 1a4b7bfb-0a31-4cd0-9328-1643b1dae31d, ghrun-tqbhkoylkm.auto.internal:5575): response = { status: SUCCESS }
2025-11-12 08:31:42,460 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, d02c194c-8d9b-46ac-bf50-ef9ee3482f15, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:42,461 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, d02c194c-8d9b-46ac-bf50-ef9ee3482f15, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=YTA2Yjc3MzctYThlM2QyOGEtNmVlYmRiYTEtN2ZiNzA3ZmU=" }
2025-11-12 08:31:42,463 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, d02c194c-8d9b-46ac-bf50-ef9ee3482f15, ghrun-tqbhkoylkm.auto.internal:5575): response = { status: SUCCESS }
2025-11-12 08:31:42,464 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, b3318bdf-3d6e-45f7-ad08-bf613f44c4c1, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:42,464 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, b3318bdf-3d6e-45f7-ad08-bf613f44c4c1, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=YjY3YWQwMjUtM2Y0YTQwMzYtMjM1NTQ2LTM0MmZhNTI2" }
2025-11-12 08:31:42,467 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, b3318bdf-3d6e-45f7-ad08-bf613f44c4c1, ghrun-tqbhkoylkm.auto.internal:5575): response = { status: SUCCESS }
2025-11-12 08:31:42,467 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 43404460-a6df-4bac-b67a-4899dbe0e440, ghrun-tqbhkoylkm.auto.internal:5575): creating call state
2025-11-12 08:31:42,468 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 43404460-a6df-4bac-b67a-4899dbe0e440, ghrun-tqbhkoylkm.auto.internal:5575): request = { session_id: "ydb://session/3?node_id=1&id=NjlmMTM3ZTMtMmQzMDVhZS1iYTk5M2EzNS0xYTIwMzVlZg==" }
2025-11-12 08:31:42,469 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 43404460-a6df-4bac-b67a-4899dbe0e440, ghrun-tqbhkoylkm.auto.internal:5575): response = { status: SUCCESS }
2025-11-12 08:31:42,470 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-12 08:31:42,471 - DEBUG - ydb.topic - close: Close topic client
2025-11-12 08:31:42,471 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-12 08:31:42,472 - INFO - ydb.connection - close: Closing channel for endpoint localhost:5575
2025-11-12 08:31:42,472 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-tqbhkoylkm.auto.internal:5575
2025-11-12 08:31:42,474 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-12 08:31:54,486 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:5575/1
2025-11-12 08:31:54,488 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3113
2025-11-12 08:31:54,488 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20620
2025-11-12 08:31:54,488 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28570
2025-11-12 08:31:54,489 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/5575
2025-11-12 08:31:54,489 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/7528
