2025-11-25 15:05:26,172 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-25 15:05:26,177 - INFO - ya.test - pytest_runtest_setup: test_log_uniform
2025-11-25 15:05:26,177 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-25 15:05:26,182 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-25 15:05:26,197 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_uniform)
2025-11-25 15:05:26,198 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/fvd5/0002c2/ydb/apps/ydb/ydb
2025-11-25 15:05:26,198 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/fvd5/0002c2/ydb/apps/ydb/ydb -e grpc://localhost:4007 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-11-25 15:05:26,200 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.11
2025-11-25 15:05:26,200 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.11
2025-11-25 15:05:26,201 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/fvd5/0002c2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:4007', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-11-25 15:05:26,211 - DEBUG - ya.test - execute: Command pid: 3938468
2025-11-25 15:05:27,009 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) rc: 0
2025-11-25 15:05:27,009 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) elapsed time (sec): 0.7783093452453613
2025-11-25 15:05:27,018 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) idrss: 0
2025-11-25 15:05:27,026 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) inblock: 0
2025-11-25 15:05:27,030 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) isrss: 0
2025-11-25 15:05:27,038 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) ixrss: 0
2025-11-25 15:05:27,038 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) majflt: 0
2025-11-25 15:05:27,038 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) maxrss: 799556
2025-11-25 15:05:27,038 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) minflt: 3486
2025-11-25 15:05:27,038 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) msgrcv: 0
2025-11-25 15:05:27,052 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) msgsnd: 0
2025-11-25 15:05:27,053 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) nivcsw: 56
2025-11-25 15:05:27,053 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) nsignals: 0
2025-11-25 15:05:27,053 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) nswap: 0
2025-11-25 15:05:27,053 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) nvcsw: 130
2025-11-25 15:05:27,053 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) oublock: 8
2025-11-25 15:05:27,053 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) stime: 0.019476
2025-11-25 15:05:27,054 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) utime: 0.058428
2025-11-25 15:05:27,054 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) wtime: 0.805
2025-11-25 15:05:27,054 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) output:
Init tables ...
Init tables ...Ok

2025-11-25 15:05:27,054 - DEBUG - ya.test - _save_outputs: Command (pid 3938468) errors:

2025-11-25 15:05:27,055 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-25 15:05:27,055 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/fvd5/0002c2/ydb/apps/ydb/ydb -e grpc://localhost:4007 -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-25 15:05:27,057 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.12
2025-11-25 15:05:27,058 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.12
2025-11-25 15:05:27,058 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/fvd5/0002c2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:4007', '-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-25 15:05:27,062 - DEBUG - ya.test - execute: Command pid: 3939030
2025-11-25 15:05:57,413 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-25 15:05:57,414 - DEBUG - ya.test - _save_outputs: Command (pid 3939030) rc: 0
2025-11-25 15:05:57,414 - DEBUG - ya.test - _save_outputs: Command (pid 3939030) elapsed time (sec): 30.351521492004395
2025-11-25 15:05:57,414 - DEBUG - ya.test - _save_outputs: Command (pid 3939030) wtime: 30.355
2025-11-25 15:05:57,415 - DEBUG - ya.test - _save_outputs: Command (pid 3939030) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	    189	189	0	0	45	90	99	105
2	    236	236	0	0	41	66	81	85
3	    221	221	0	0	36	74	167	170
4	    286	286	0	0	31	63	79	95
5	    310	310	0	0	28	54	82	90
6	    293	293	0	0	30	62	83	88
7	    262	262	0	0	34	68	73	77
8	    303	303	0	0	28	64	73	76
9	    285	285	0	0	33	53	62	78
10	    267	267	0	0	33	65	70	71
11	    316	316	0	0	29	54	63	84
12	    289	289	0	0	30	60	101	108
13	    258	258	0	0	36	64	75	76
14	    283	283	0	0	34	60	83	93
15	    309	309	0	0	28	60	71	73
16	    375	375	0	0	25	40	49	53
17	    346	346	0	0	25	58	71	77
18	    346	346	0	0	26	45	61	70
19	    378	378	0	0	25	42	52	59
20	    350	350	0	0	27	44	56	62
21	    324	324	0	0	27	71	78	79
22	    351	351	0	0	25	51	55	66
23	    303	303	0	0	31	62	70	81
24	    284	284	0	0	32	62	74	79
25	    374	374	0	0	25	39	43	47
26	    364	364	0	0	25	47	56	58
27	    419	419	0	0	22	34	39	46
28	    344	344	0	0	27	47	59	65
29	    358	358	0	0	25	47	57	62
30	    387	387	0	0	24	43	51	56

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	   9410	313.667	0	0	28	57	75	170

2025-11-25 15:05:57,415 - DEBUG - ya.test - _save_outputs: Command (pid 3939030) errors:

2025-11-25 15:05:57,415 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODU2ODJhMDgtY2JlZjFlYWUtN2QzODE3Ni1lMGUyNzFjYQ==
2025-11-25 15:05:57,416 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bb7ee1d9-3666-416d-9444-1b78099458d3, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:05:57,434 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bb7ee1d9-3666-416d-9444-1b78099458d3, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ODU2ODJhMDgtY2JlZjFlYWUtN2QzODE3Ni1lMGUyNzFjYQ==" 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-25 15:05:57,437 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bb7ee1d9-3666-416d-9444-1b78099458d3, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:01,839 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODU2ODJhMDgtY2JlZjFlYWUtN2QzODE3Ni1lMGUyNzFjYQ==
2025-11-25 15:06:01,840 - INFO - root - test_log_uniform: Count rows after insert 9408 before wait
2025-11-25 15:06:01,840 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=
2025-11-25 15:06:01,842 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 40bf7ad8-c057-42ec-a3aa-4f21a36e1b1d, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:01,866 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 40bf7ad8-c057-42ec-a3aa-4f21a36e1b1d, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=" 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-25 15:06:01,867 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 40bf7ad8-c057-42ec-a3aa-4f21a36e1b1d, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:03,417 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-25 15:06:03,424 - 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-25 15:06:03,430 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-25 15:06:03,431 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 5425c0c5-b3bb-4eb1-8c0f-8d5fb4995c45, localhost:4007): creating call state
2025-11-25 15:06:03,432 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 5425c0c5-b3bb-4eb1-8c0f-8d5fb4995c45, localhost:4007): request = { database: "/Root" }
2025-11-25 15:06:03,460 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 5425c0c5-b3bb-4eb1-8c0f-8d5fb4995c45, localhost:4007): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-n7olz6xni4.auto.internal" port: 4007 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-25 15:06:03,470 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-n7olz6xni4.auto.internal:4007, location 1, ssl: False>]>
2025-11-25 15:06:03,504 - INFO - ydb.connection - close: Closing channel for endpoint localhost:4007
2025-11-25 15:06:04,898 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=
2025-11-25 15:06:04,926 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNiYjVjODAtYWFhYjk3NDctYTFjNGRjNC1iYWMyZGZkYw==
2025-11-25 15:06:04,928 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 61459db7-b0e6-4398-8a15-bbe3493abd65, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:04,930 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 61459db7-b0e6-4398-8a15-bbe3493abd65, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ZTNiYjVjODAtYWFhYjk3NDctYTFjNGRjNC1iYWMyZGZkYw==" 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-25 15:06:04,932 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 61459db7-b0e6-4398-8a15-bbe3493abd65, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:08,059 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNiYjVjODAtYWFhYjk3NDctYTFjNGRjNC1iYWMyZGZkYw==
2025-11-25 15:06:08,060 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-25 15:06:08,060 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/fvd5/0002c2/ydb/apps/ydb/ydb -e grpc://localhost:4007 -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-25 15:06:08,061 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.13
2025-11-25 15:06:08,062 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.13
2025-11-25 15:06:08,062 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/fvd5/0002c2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:4007', '-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-25 15:06:08,066 - DEBUG - ya.test - execute: Command pid: 3962663
2025-11-25 15:06:08,067 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-11-25 15:06:08,067 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/fvd5/0002c2/ydb/apps/ydb/ydb -e grpc://localhost:4007 -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-25 15:06:08,068 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.14
2025-11-25 15:06:08,069 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.14
2025-11-25 15:06:08,069 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/fvd5/0002c2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:4007', '-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-25 15:06:08,076 - DEBUG - ya.test - execute: Command pid: 3962714
2025-11-25 15:06:08,076 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-11-25 15:06:08,077 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDFkYmRmZTUtNDNiN2IyOS0xZjEwNmM1OS03MTE2MTMyZA==
2025-11-25 15:06:08,078 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/fvd5/0002c2/ydb/apps/ydb/ydb -e grpc://localhost:4007 -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-25 15:06:08,080 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9def2a24-7950-438c-bfa5-ef7c35a89546, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:08,080 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTdjMjk4NTYtMjI1NGIyYTktY2NlNDEyYTMtMmI1ZjVlYTU=
2025-11-25 15:06:08,082 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9def2a24-7950-438c-bfa5-ef7c35a89546, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ZDFkYmRmZTUtNDNiN2IyOS0xZjEwNmM1OS03MTE2MTMyZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 15:06:08,082 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.15
2025-11-25 15:06:08,083 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 12f2701c-2407-4e0e-aa23-0a1ab8d8304b, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:08,116 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 12f2701c-2407-4e0e-aa23-0a1ab8d8304b, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=YTdjMjk4NTYtMjI1NGIyYTktY2NlNDEyYTMtMmI1ZjVlYTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 15:06:08,117 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 12f2701c-2407-4e0e-aa23-0a1ab8d8304b, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:08,083 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWI4YzBkNTYtMzYxZDRjNGQtM2Q4ZTc5ZjMtZjIzODllMjM=
2025-11-25 15:06:08,084 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9def2a24-7950-438c-bfa5-ef7c35a89546, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:08,117 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3ded649a-bb28-4c63-88c6-20d4896eed5e, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:08,119 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmI4NDQ2NGMtMWZiN2JjMTktM2Q4Zjk5MjktMjQ4NGJhZWY=
2025-11-25 15:06:08,137 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bbbf1745-3ca6-4c7a-8fb0-2a1b9413a5f1, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:08,138 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bbbf1745-3ca6-4c7a-8fb0-2a1b9413a5f1, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=YmI4NDQ2NGMtMWZiN2JjMTktM2Q4Zjk5MjktMjQ4NGJhZWY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 15:06:08,138 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bbbf1745-3ca6-4c7a-8fb0-2a1b9413a5f1, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:08,122 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.15
2025-11-25 15:06:08,139 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/fvd5/0002c2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:4007', '-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-25 15:06:08,136 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3ded649a-bb28-4c63-88c6-20d4896eed5e, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=NWI4YzBkNTYtMzYxZDRjNGQtM2Q4ZTc5ZjMtZjIzODllMjM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 15:06:08,145 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3ded649a-bb28-4c63-88c6-20d4896eed5e, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:08,143 - DEBUG - ya.test - execute: Command pid: 3962829
2025-11-25 15:06:08,148 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZmZDcyNzEtOGVkMTQ1YTYtMjExNWJlMmEtN2ViN2JiNjA=
2025-11-25 15:06:08,149 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b8c8e1a3-7dbf-482c-948d-30e78776c17f, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:08,150 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b8c8e1a3-7dbf-482c-948d-30e78776c17f, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ODZmZDcyNzEtOGVkMTQ1YTYtMjExNWJlMmEtN2ViN2JiNjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 15:06:08,150 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b8c8e1a3-7dbf-482c-948d-30e78776c17f, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:08,151 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yjc1ZTk3MDEtNzMyMTQ4NjAtYmIyYWE5NWQtN2JlZDNhNmY=
2025-11-25 15:06:08,151 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 61057cae-410c-4fd3-b6ba-24b69c4f9ec9, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:08,152 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 61057cae-410c-4fd3-b6ba-24b69c4f9ec9, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=Yjc1ZTk3MDEtNzMyMTQ4NjAtYmIyYWE5NWQtN2JlZDNhNmY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 15:06:08,152 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 61057cae-410c-4fd3-b6ba-24b69c4f9ec9, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:08,154 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Njc5NTUyOWMtM2E5YjU5NTItYWMxYjYzYjAtYWZmNGZhYTk=
2025-11-25 15:06:08,154 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 429d2a94-a248-4fb5-b3c5-357998fda5fb, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:08,155 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 429d2a94-a248-4fb5-b3c5-357998fda5fb, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=Njc5NTUyOWMtM2E5YjU5NTItYWMxYjYzYjAtYWZmNGZhYTk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 15:06:08,156 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 429d2a94-a248-4fb5-b3c5-357998fda5fb, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:08,156 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODU2ODJhMDgtY2JlZjFlYWUtN2QzODE3Ni1lMGUyNzFjYQ==
2025-11-25 15:06:08,157 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 251a4d34-7e17-42b1-96a6-d9fe848524bc, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:08,158 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 251a4d34-7e17-42b1-96a6-d9fe848524bc, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ODU2ODJhMDgtY2JlZjFlYWUtN2QzODE3Ni1lMGUyNzFjYQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 15:06:08,158 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 251a4d34-7e17-42b1-96a6-d9fe848524bc, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:08,159 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=
2025-11-25 15:06:08,161 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b68ed90d-89f1-4051-b731-430ffe504d21, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:08,161 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNiYjVjODAtYWFhYjk3NDctYTFjNGRjNC1iYWMyZGZkYw==
2025-11-25 15:06:08,162 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b68ed90d-89f1-4051-b731-430ffe504d21, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 15:06:08,167 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bafa6774-c954-4e8a-81a1-a23cd81b980b, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:08,178 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bafa6774-c954-4e8a-81a1-a23cd81b980b, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ZTNiYjVjODAtYWFhYjk3NDctYTFjNGRjNC1iYWMyZGZkYw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 15:06:08,176 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b68ed90d-89f1-4051-b731-430ffe504d21, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:08,180 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bafa6774-c954-4e8a-81a1-a23cd81b980b, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:38,651 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-25 15:06:38,651 - DEBUG - ya.test - _save_outputs: Command (pid 3962663) rc: 0
2025-11-25 15:06:38,652 - DEBUG - ya.test - _save_outputs: Command (pid 3962663) elapsed time (sec): 30.585258960723877
2025-11-25 15:06:38,652 - DEBUG - ya.test - _save_outputs: Command (pid 3962663) wtime: 30.589
2025-11-25 15:06:38,652 - DEBUG - ya.test - _save_outputs: Command (pid 3962663) 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	      6	6	0	0	3695	3999	3999	3999
5	      4	4	0	0	4159	4287	4287	4287
6	      0	0	0	0	0	0	0	0
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	     10	10	0	0	11775	11839	11839	11839
17	     10	10	0	0	1079	1327	1327	1327
18	     36	36	0	0	285	493	507	507
19	     54	54	0	0	168	277	323	329
20	    114	114	0	0	92	121	140	146
21	    168	168	0	0	57	85	92	100
22	    150	150	0	0	61	94	101	106
23	    159	159	0	0	61	76	78	82
24	    143	143	0	0	69	83	88	89
25	    117	117	0	0	81	107	111	117
26	     70	70	0	0	136	213	220	221
27	     47	47	0	0	209	241	247	247
28	     36	36	0	0	273	297	301	301
29	     38	38	0	0	267	315	331	331
30	     37	37	0	0	371	405	443	443

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	   1199	39.9667	0	0	75	363	4159	11839

2025-11-25 15:06:38,652 - DEBUG - ya.test - _save_outputs: Command (pid 3962663) errors:

2025-11-25 15:06:39,273 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-25 15:06:39,273 - DEBUG - ya.test - _save_outputs: Command (pid 3962829) rc: 0
2025-11-25 15:06:39,273 - DEBUG - ya.test - _save_outputs: Command (pid 3962829) elapsed time (sec): 31.12465763092041
2025-11-25 15:06:39,274 - DEBUG - ya.test - _save_outputs: Command (pid 3962829) wtime: 31.133
2025-11-25 15:06:39,275 - DEBUG - ya.test - _save_outputs: Command (pid 3962829) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	      0	0	0	10	0	0	0	0
3	      0	0	0	10	0	0	0	0
4	      0	0	0	10	0	0	0	0
5	      0	0	0	10	0	0	0	0
6	      0	0	0	10	0	0	0	0
7	      0	0	0	0	0	0	0	0
8	      0	0	0	10	0	0	0	0
9	      0	0	0	5	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	10	0	0	0	0
14	      0	0	0	4	0	0	0	0
15	      0	0	0	10	0	0	0	0
16	      0	0	0	9	0	0	0	0
17	     22	22	0	1	341	751	831	831
18	     43	43	0	0	251	345	355	355
19	     76	76	0	0	131	186	210	234
20	    125	125	0	0	79	110	120	131
21	    120	120	0	0	80	106	115	119
22	    122	122	0	0	79	107	127	128
23	    115	115	0	0	88	102	109	109
24	     97	97	0	0	100	119	124	132
25	     64	64	0	0	140	192	197	200
26	     41	41	0	0	232	267	275	275
27	     31	31	0	0	319	343	357	357
28	     30	30	0	0	317	385	387	387
29	     25	25	0	0	375	481	489	489
30	     37	37	0	0	331	479	491	491

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    948	31.6	0	119	98	357	509	831

2025-11-25 15:06:39,275 - DEBUG - ya.test - _save_outputs: Command (pid 3962829) errors:

2025-11-25 15:06:39,400 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-25 15:06:39,402 - DEBUG - ya.test - _save_outputs: Command (pid 3962714) rc: 0
2025-11-25 15:06:39,403 - DEBUG - ya.test - _save_outputs: Command (pid 3962714) elapsed time (sec): 31.323047876358032
2025-11-25 15:06:39,403 - DEBUG - ya.test - _save_outputs: Command (pid 3962714) wtime: 31.332
2025-11-25 15:06:39,403 - DEBUG - ya.test - _save_outputs: Command (pid 3962714) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	      0	0	0	10	0	0	0	0
3	      0	0	0	10	0	0	0	0
4	      0	0	0	10	0	0	0	0
5	      0	0	0	10	0	0	0	0
6	      0	0	0	10	0	0	0	0
7	      0	0	0	0	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	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	10	0	0	0	0
15	      0	0	0	10	0	0	0	0
16	      0	0	0	9	0	0	0	0
17	     16	16	0	1	583	619	699	699
18	     29	29	0	0	401	623	691	691
19	     39	39	0	0	254	351	399	399
20	     46	46	0	0	210	279	359	359
21	     53	53	0	0	199	271	301	347
22	     49	49	0	0	201	259	279	279
23	     50	50	0	0	211	257	265	265
24	     43	43	0	0	239	289	301	301
25	     37	37	0	0	271	355	395	395
26	     27	27	0	0	337	451	497	497
27	     23	23	0	0	451	559	567	567
28	     20	20	0	0	461	539	567	567
29	     19	19	0	0	535	651	655	655
30	     29	29	0	0	493	671	699	699

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    480	16	0	120	253	583	655	699

2025-11-25 15:06:39,403 - DEBUG - ya.test - _save_outputs: Command (pid 3962714) errors:

2025-11-25 15:06:43,083 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODU2ODJhMDgtY2JlZjFlYWUtN2QzODE3Ni1lMGUyNzFjYQ==
2025-11-25 15:06:43,084 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODU2ODJhMDgtY2JlZjFlYWUtN2QzODE3Ni1lMGUyNzFjYQ==
2025-11-25 15:06:43,085 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e5e9c9cf-2e73-49d6-acd0-951621515f86, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:43,086 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e5e9c9cf-2e73-49d6-acd0-951621515f86, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ODU2ODJhMDgtY2JlZjFlYWUtN2QzODE3Ni1lMGUyNzFjYQ==" 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-25 15:06:43,088 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e5e9c9cf-2e73-49d6-acd0-951621515f86, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:43,108 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZmZDcyNzEtOGVkMTQ1YTYtMjExNWJlMmEtN2ViN2JiNjA=
2025-11-25 15:06:43,108 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZmZDcyNzEtOGVkMTQ1YTYtMjExNWJlMmEtN2ViN2JiNjA=
2025-11-25 15:06:43,108 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, de23f096-f5a0-4558-92ef-ee8fc3474a16, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:43,109 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, de23f096-f5a0-4558-92ef-ee8fc3474a16, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ODZmZDcyNzEtOGVkMTQ1YTYtMjExNWJlMmEtN2ViN2JiNjA=" 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-25 15:06:43,110 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, de23f096-f5a0-4558-92ef-ee8fc3474a16, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:43,303 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNiYjVjODAtYWFhYjk3NDctYTFjNGRjNC1iYWMyZGZkYw==
2025-11-25 15:06:43,303 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNiYjVjODAtYWFhYjk3NDctYTFjNGRjNC1iYWMyZGZkYw==
2025-11-25 15:06:43,304 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c9652d6e-1353-4e44-96bc-f83227bdc12b, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:43,304 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c9652d6e-1353-4e44-96bc-f83227bdc12b, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ZTNiYjVjODAtYWFhYjk3NDctYTFjNGRjNC1iYWMyZGZkYw==" 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-25 15:06:43,305 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c9652d6e-1353-4e44-96bc-f83227bdc12b, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:43,432 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmI4NDQ2NGMtMWZiN2JjMTktM2Q4Zjk5MjktMjQ4NGJhZWY=
2025-11-25 15:06:43,433 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmI4NDQ2NGMtMWZiN2JjMTktM2Q4Zjk5MjktMjQ4NGJhZWY=
2025-11-25 15:06:43,433 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 68dbac4f-8c42-4683-b6f6-b6ed1555e361, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:43,434 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 68dbac4f-8c42-4683-b6f6-b6ed1555e361, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=YmI4NDQ2NGMtMWZiN2JjMTktM2Q4Zjk5MjktMjQ4NGJhZWY=" 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-25 15:06:43,435 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 68dbac4f-8c42-4683-b6f6-b6ed1555e361, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:43,678 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWI4YzBkNTYtMzYxZDRjNGQtM2Q4ZTc5ZjMtZjIzODllMjM=
2025-11-25 15:06:43,678 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWI4YzBkNTYtMzYxZDRjNGQtM2Q4ZTc5ZjMtZjIzODllMjM=
2025-11-25 15:06:43,679 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 39a57ae5-401f-4878-ba4f-ab6af0c42e48, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:43,679 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 39a57ae5-401f-4878-ba4f-ab6af0c42e48, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=NWI4YzBkNTYtMzYxZDRjNGQtM2Q4ZTc5ZjMtZjIzODllMjM=" 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-25 15:06:43,680 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 39a57ae5-401f-4878-ba4f-ab6af0c42e48, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:43,936 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTdjMjk4NTYtMjI1NGIyYTktY2NlNDEyYTMtMmI1ZjVlYTU=
2025-11-25 15:06:43,937 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTdjMjk4NTYtMjI1NGIyYTktY2NlNDEyYTMtMmI1ZjVlYTU=
2025-11-25 15:06:43,937 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 35c71230-2aa2-49e2-ab5b-db863a7aa442, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:43,937 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 35c71230-2aa2-49e2-ab5b-db863a7aa442, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=YTdjMjk4NTYtMjI1NGIyYTktY2NlNDEyYTMtMmI1ZjVlYTU=" 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-25 15:06:43,938 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 35c71230-2aa2-49e2-ab5b-db863a7aa442, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:43,939 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Njc5NTUyOWMtM2E5YjU5NTItYWMxYjYzYjAtYWZmNGZhYTk=
2025-11-25 15:06:43,940 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Njc5NTUyOWMtM2E5YjU5NTItYWMxYjYzYjAtYWZmNGZhYTk=
2025-11-25 15:06:43,940 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7b506edb-5710-44a4-8421-98b194bf532b, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:43,940 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7b506edb-5710-44a4-8421-98b194bf532b, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=Njc5NTUyOWMtM2E5YjU5NTItYWMxYjYzYjAtYWZmNGZhYTk=" 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-25 15:06:43,941 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7b506edb-5710-44a4-8421-98b194bf532b, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:44,347 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDFkYmRmZTUtNDNiN2IyOS0xZjEwNmM1OS03MTE2MTMyZA==
2025-11-25 15:06:44,348 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDFkYmRmZTUtNDNiN2IyOS0xZjEwNmM1OS03MTE2MTMyZA==
2025-11-25 15:06:44,350 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 92bef921-7697-4fe0-bc52-ef025c8be18a, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:44,351 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 92bef921-7697-4fe0-bc52-ef025c8be18a, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ZDFkYmRmZTUtNDNiN2IyOS0xZjEwNmM1OS03MTE2MTMyZA==" 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-25 15:06:44,353 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 92bef921-7697-4fe0-bc52-ef025c8be18a, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:44,566 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yjc1ZTk3MDEtNzMyMTQ4NjAtYmIyYWE5NWQtN2JlZDNhNmY=
2025-11-25 15:06:44,567 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yjc1ZTk3MDEtNzMyMTQ4NjAtYmIyYWE5NWQtN2JlZDNhNmY=
2025-11-25 15:06:44,567 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 863c909c-34e0-402c-8c18-a38be930fbac, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:44,567 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 863c909c-34e0-402c-8c18-a38be930fbac, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=Yjc1ZTk3MDEtNzMyMTQ4NjAtYmIyYWE5NWQtN2JlZDNhNmY=" 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-25 15:06:44,568 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 863c909c-34e0-402c-8c18-a38be930fbac, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:44,824 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=
2025-11-25 15:06:44,824 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=
2025-11-25 15:06:44,825 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 61781503-678b-4c45-b415-64ba6af085ec, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:44,826 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 61781503-678b-4c45-b415-64ba6af085ec, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=" 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-25 15:06:44,826 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 61781503-678b-4c45-b415-64ba6af085ec, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:55,407 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODU2ODJhMDgtY2JlZjFlYWUtN2QzODE3Ni1lMGUyNzFjYQ==
2025-11-25 15:06:55,457 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODU2ODJhMDgtY2JlZjFlYWUtN2QzODE3Ni1lMGUyNzFjYQ==
2025-11-25 15:06:55,460 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c0beccdc-f138-43cb-afb9-745c2ece958d, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:55,461 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c0beccdc-f138-43cb-afb9-745c2ece958d, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ODU2ODJhMDgtY2JlZjFlYWUtN2QzODE3Ni1lMGUyNzFjYQ==" 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-25 15:06:55,458 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZmZDcyNzEtOGVkMTQ1YTYtMjExNWJlMmEtN2ViN2JiNjA=
2025-11-25 15:06:55,510 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZmZDcyNzEtOGVkMTQ1YTYtMjExNWJlMmEtN2ViN2JiNjA=
2025-11-25 15:06:55,511 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c0beccdc-f138-43cb-afb9-745c2ece958d, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:55,525 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5d06674b-e9d3-4fb8-b854-bc70821e46c1, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:55,533 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5d06674b-e9d3-4fb8-b854-bc70821e46c1, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ODZmZDcyNzEtOGVkMTQ1YTYtMjExNWJlMmEtN2ViN2JiNjA=" 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-25 15:06:55,547 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5d06674b-e9d3-4fb8-b854-bc70821e46c1, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:55,571 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmI4NDQ2NGMtMWZiN2JjMTktM2Q4Zjk5MjktMjQ4NGJhZWY=
2025-11-25 15:06:55,618 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmI4NDQ2NGMtMWZiN2JjMTktM2Q4Zjk5MjktMjQ4NGJhZWY=
2025-11-25 15:06:55,619 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d60b13c3-f6ff-4b02-b6ec-4e3db50c0471, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:55,620 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d60b13c3-f6ff-4b02-b6ec-4e3db50c0471, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=YmI4NDQ2NGMtMWZiN2JjMTktM2Q4Zjk5MjktMjQ4NGJhZWY=" 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-25 15:06:55,623 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d60b13c3-f6ff-4b02-b6ec-4e3db50c0471, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:55,641 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yjc1ZTk3MDEtNzMyMTQ4NjAtYmIyYWE5NWQtN2JlZDNhNmY=
2025-11-25 15:06:55,695 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yjc1ZTk3MDEtNzMyMTQ4NjAtYmIyYWE5NWQtN2JlZDNhNmY=
2025-11-25 15:06:55,696 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6456dbbe-1d15-421e-a304-ded30c1eb1d8, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:55,697 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6456dbbe-1d15-421e-a304-ded30c1eb1d8, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=Yjc1ZTk3MDEtNzMyMTQ4NjAtYmIyYWE5NWQtN2JlZDNhNmY=" 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-25 15:06:55,699 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6456dbbe-1d15-421e-a304-ded30c1eb1d8, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:55,707 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Njc5NTUyOWMtM2E5YjU5NTItYWMxYjYzYjAtYWZmNGZhYTk=
2025-11-25 15:06:55,751 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Njc5NTUyOWMtM2E5YjU5NTItYWMxYjYzYjAtYWZmNGZhYTk=
2025-11-25 15:06:55,752 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e28ebbc8-09a9-41d3-b550-bb33413d9307, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:55,754 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e28ebbc8-09a9-41d3-b550-bb33413d9307, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=Njc5NTUyOWMtM2E5YjU5NTItYWMxYjYzYjAtYWZmNGZhYTk=" 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-25 15:06:55,755 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e28ebbc8-09a9-41d3-b550-bb33413d9307, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:55,772 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDFkYmRmZTUtNDNiN2IyOS0xZjEwNmM1OS03MTE2MTMyZA==
2025-11-25 15:06:55,822 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDFkYmRmZTUtNDNiN2IyOS0xZjEwNmM1OS03MTE2MTMyZA==
2025-11-25 15:06:55,828 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c065e3e6-19d9-4079-afda-b2f7453fa40e, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:55,831 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c065e3e6-19d9-4079-afda-b2f7453fa40e, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ZDFkYmRmZTUtNDNiN2IyOS0xZjEwNmM1OS03MTE2MTMyZA==" 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-25 15:06:55,835 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c065e3e6-19d9-4079-afda-b2f7453fa40e, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:55,849 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTdjMjk4NTYtMjI1NGIyYTktY2NlNDEyYTMtMmI1ZjVlYTU=
2025-11-25 15:06:55,897 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTdjMjk4NTYtMjI1NGIyYTktY2NlNDEyYTMtMmI1ZjVlYTU=
2025-11-25 15:06:55,899 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f51358ff-ce42-4669-8189-0ded54fc0c86, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:55,900 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f51358ff-ce42-4669-8189-0ded54fc0c86, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=YTdjMjk4NTYtMjI1NGIyYTktY2NlNDEyYTMtMmI1ZjVlYTU=" 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-25 15:06:55,903 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f51358ff-ce42-4669-8189-0ded54fc0c86, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:55,909 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNiYjVjODAtYWFhYjk3NDctYTFjNGRjNC1iYWMyZGZkYw==
2025-11-25 15:06:55,962 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNiYjVjODAtYWFhYjk3NDctYTFjNGRjNC1iYWMyZGZkYw==
2025-11-25 15:06:55,963 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9fa621df-b41f-4669-ab17-c5b2884909d0, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:55,964 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9fa621df-b41f-4669-ab17-c5b2884909d0, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ZTNiYjVjODAtYWFhYjk3NDctYTFjNGRjNC1iYWMyZGZkYw==" 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-25 15:06:55,965 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9fa621df-b41f-4669-ab17-c5b2884909d0, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:55,973 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=
2025-11-25 15:06:56,024 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=
2025-11-25 15:06:56,025 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7769a724-bdaf-4080-b29f-e376e2f1b245, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:56,028 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7769a724-bdaf-4080-b29f-e376e2f1b245, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=" 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-25 15:06:56,030 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7769a724-bdaf-4080-b29f-e376e2f1b245, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:06:56,041 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWI4YzBkNTYtMzYxZDRjNGQtM2Q4ZTc5ZjMtZjIzODllMjM=
2025-11-25 15:06:56,119 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWI4YzBkNTYtMzYxZDRjNGQtM2Q4ZTc5ZjMtZjIzODllMjM=
2025-11-25 15:06:56,120 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8fcc93dd-b78e-4345-8721-e292ba0c0e07, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:06:56,121 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8fcc93dd-b78e-4345-8721-e292ba0c0e07, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=NWI4YzBkNTYtMzYxZDRjNGQtM2Q4ZTc5ZjMtZjIzODllMjM=" 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-25 15:06:56,122 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8fcc93dd-b78e-4345-8721-e292ba0c0e07, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:07:00,686 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yjc1ZTk3MDEtNzMyMTQ4NjAtYmIyYWE5NWQtN2JlZDNhNmY=
2025-11-25 15:07:00,687 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yjc1ZTk3MDEtNzMyMTQ4NjAtYmIyYWE5NWQtN2JlZDNhNmY=
2025-11-25 15:07:00,687 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b6aa6322-6012-44f7-be74-5b4da9909de9, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:00,688 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b6aa6322-6012-44f7-be74-5b4da9909de9, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=Yjc1ZTk3MDEtNzMyMTQ4NjAtYmIyYWE5NWQtN2JlZDNhNmY=" 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-25 15:07:00,688 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b6aa6322-6012-44f7-be74-5b4da9909de9, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:07:00,714 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=
2025-11-25 15:07:00,715 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=
2025-11-25 15:07:00,715 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNiYjVjODAtYWFhYjk3NDctYTFjNGRjNC1iYWMyZGZkYw==
2025-11-25 15:07:00,717 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e16d44ee-4d1c-4d92-96ec-503856007f75, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:00,719 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e16d44ee-4d1c-4d92-96ec-503856007f75, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=" 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-25 15:07:00,718 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTdjMjk4NTYtMjI1NGIyYTktY2NlNDEyYTMtMmI1ZjVlYTU=
2025-11-25 15:07:00,717 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTNiYjVjODAtYWFhYjk3NDctYTFjNGRjNC1iYWMyZGZkYw==
2025-11-25 15:07:00,721 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e87b01fe-4a3c-405f-b23f-d27eaf2f3160, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:00,721 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e87b01fe-4a3c-405f-b23f-d27eaf2f3160, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ZTNiYjVjODAtYWFhYjk3NDctYTFjNGRjNC1iYWMyZGZkYw==" 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-25 15:07:00,722 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTdjMjk4NTYtMjI1NGIyYTktY2NlNDEyYTMtMmI1ZjVlYTU=
2025-11-25 15:07:00,723 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e16d44ee-4d1c-4d92-96ec-503856007f75, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:07:00,723 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0c2d478c-d4c2-494f-b38b-d63c2fb024c6, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:00,724 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDFkYmRmZTUtNDNiN2IyOS0xZjEwNmM1OS03MTE2MTMyZA==
2025-11-25 15:07:00,724 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e87b01fe-4a3c-405f-b23f-d27eaf2f3160, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:07:00,727 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0c2d478c-d4c2-494f-b38b-d63c2fb024c6, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=YTdjMjk4NTYtMjI1NGIyYTktY2NlNDEyYTMtMmI1ZjVlYTU=" 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-25 15:07:00,727 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDFkYmRmZTUtNDNiN2IyOS0xZjEwNmM1OS03MTE2MTMyZA==
2025-11-25 15:07:00,730 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d6b18349-b595-4488-b389-0df45aa24520, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:00,732 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d6b18349-b595-4488-b389-0df45aa24520, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ZDFkYmRmZTUtNDNiN2IyOS0xZjEwNmM1OS03MTE2MTMyZA==" 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-25 15:07:00,732 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0c2d478c-d4c2-494f-b38b-d63c2fb024c6, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:07:00,733 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Njc5NTUyOWMtM2E5YjU5NTItYWMxYjYzYjAtYWZmNGZhYTk=
2025-11-25 15:07:00,734 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Njc5NTUyOWMtM2E5YjU5NTItYWMxYjYzYjAtYWZmNGZhYTk=
2025-11-25 15:07:00,734 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d6b18349-b595-4488-b389-0df45aa24520, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:07:00,735 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 79886cae-d874-488d-b871-df4c2c0c84b9, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:00,737 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 79886cae-d874-488d-b871-df4c2c0c84b9, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=Njc5NTUyOWMtM2E5YjU5NTItYWMxYjYzYjAtYWZmNGZhYTk=" 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-25 15:07:00,738 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZmZDcyNzEtOGVkMTQ1YTYtMjExNWJlMmEtN2ViN2JiNjA=
2025-11-25 15:07:00,738 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 79886cae-d874-488d-b871-df4c2c0c84b9, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:07:00,742 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODZmZDcyNzEtOGVkMTQ1YTYtMjExNWJlMmEtN2ViN2JiNjA=
2025-11-25 15:07:00,743 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dee78208-1982-47cd-98a9-affdc7d7209e, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:00,744 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dee78208-1982-47cd-98a9-affdc7d7209e, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ODZmZDcyNzEtOGVkMTQ1YTYtMjExNWJlMmEtN2ViN2JiNjA=" 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-25 15:07:00,745 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dee78208-1982-47cd-98a9-affdc7d7209e, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:07:00,823 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODU2ODJhMDgtY2JlZjFlYWUtN2QzODE3Ni1lMGUyNzFjYQ==
2025-11-25 15:07:00,824 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODU2ODJhMDgtY2JlZjFlYWUtN2QzODE3Ni1lMGUyNzFjYQ==
2025-11-25 15:07:00,825 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9ac62447-6268-4dcf-ab1c-0341197d4657, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:00,826 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9ac62447-6268-4dcf-ab1c-0341197d4657, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ODU2ODJhMDgtY2JlZjFlYWUtN2QzODE3Ni1lMGUyNzFjYQ==" 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-25 15:07:00,826 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9ac62447-6268-4dcf-ab1c-0341197d4657, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:07:00,846 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWI4YzBkNTYtMzYxZDRjNGQtM2Q4ZTc5ZjMtZjIzODllMjM=
2025-11-25 15:07:00,847 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NWI4YzBkNTYtMzYxZDRjNGQtM2Q4ZTc5ZjMtZjIzODllMjM=
2025-11-25 15:07:00,847 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be8ae771-32dd-4a84-96e1-26a0f16f664c, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:00,848 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be8ae771-32dd-4a84-96e1-26a0f16f664c, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=NWI4YzBkNTYtMzYxZDRjNGQtM2Q4ZTc5ZjMtZjIzODllMjM=" 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-25 15:07:00,849 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be8ae771-32dd-4a84-96e1-26a0f16f664c, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:07:01,006 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmI4NDQ2NGMtMWZiN2JjMTktM2Q4Zjk5MjktMjQ4NGJhZWY=
2025-11-25 15:07:01,006 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YmI4NDQ2NGMtMWZiN2JjMTktM2Q4Zjk5MjktMjQ4NGJhZWY=
2025-11-25 15:07:01,007 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a1299894-a4de-4418-89ac-76dfec37de33, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:01,007 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a1299894-a4de-4418-89ac-76dfec37de33, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=YmI4NDQ2NGMtMWZiN2JjMTktM2Q4Zjk5MjktMjQ4NGJhZWY=" 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-25 15:07:01,008 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a1299894-a4de-4418-89ac-76dfec37de33, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:07:01,193 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=
2025-11-25 15:07:01,194 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODU2ODJhMDgtY2JlZjFlYWUtN2QzODE3Ni1lMGUyNzFjYQ==
2025-11-25 15:07:01,195 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODZmZDcyNzEtOGVkMTQ1YTYtMjExNWJlMmEtN2ViN2JiNjA=
2025-11-25 15:07:01,200 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yjc1ZTk3MDEtNzMyMTQ4NjAtYmIyYWE5NWQtN2JlZDNhNmY=
2025-11-25 15:07:01,208 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTNiYjVjODAtYWFhYjk3NDctYTFjNGRjNC1iYWMyZGZkYw==
2025-11-25 15:07:01,210 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDFkYmRmZTUtNDNiN2IyOS0xZjEwNmM1OS03MTE2MTMyZA==
2025-11-25 15:07:01,211 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Njc5NTUyOWMtM2E5YjU5NTItYWMxYjYzYjAtYWZmNGZhYTk=
2025-11-25 15:07:01,212 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTdjMjk4NTYtMjI1NGIyYTktY2NlNDEyYTMtMmI1ZjVlYTU=
2025-11-25 15:07:01,240 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NWI4YzBkNTYtMzYxZDRjNGQtM2Q4ZTc5ZjMtZjIzODllMjM=
2025-11-25 15:07:01,269 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YmI4NDQ2NGMtMWZiN2JjMTktM2Q4Zjk5MjktMjQ4NGJhZWY=
2025-11-25 15:07:01,270 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=
2025-11-25 15:07:01,271 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1bda9251-4599-4437-82ed-1d0b1488b756, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:01,272 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1bda9251-4599-4437-82ed-1d0b1488b756, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=" 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-25 15:07:01,272 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1bda9251-4599-4437-82ed-1d0b1488b756, ghrun-n7olz6xni4.auto.internal:4007): response = { <_MultiThreadedRendezvous object> }
2025-11-25 15:07:02,804 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=
2025-11-25 15:07:02,805 - INFO - root - test_log_uniform: check insert: 9999 9408
2025-11-25 15:07:02,806 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.16
2025-11-25 15:07:02,807 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.16
2025-11-25 15:07:02,807 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/fvd5/0002c2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:4007', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-11-25 15:07:02,809 - DEBUG - ya.test - execute: Command pid: 3988347
2025-11-25 15:07:02,986 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) rc: 0
2025-11-25 15:07:02,986 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) elapsed time (sec): 0.17524051666259766
2025-11-25 15:07:02,986 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) idrss: 0
2025-11-25 15:07:02,986 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) inblock: 0
2025-11-25 15:07:02,986 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) isrss: 0
2025-11-25 15:07:02,987 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) ixrss: 0
2025-11-25 15:07:02,987 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) majflt: 0
2025-11-25 15:07:02,987 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) maxrss: 799556
2025-11-25 15:07:02,987 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) minflt: 3483
2025-11-25 15:07:02,987 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) msgrcv: 0
2025-11-25 15:07:02,987 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) msgsnd: 0
2025-11-25 15:07:02,987 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) nivcsw: 22
2025-11-25 15:07:02,988 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) nsignals: 0
2025-11-25 15:07:02,988 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) nswap: 0
2025-11-25 15:07:02,988 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) nvcsw: 137
2025-11-25 15:07:02,988 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) oublock: 8
2025-11-25 15:07:02,988 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) stime: 0.034967
2025-11-25 15:07:02,988 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) utime: 0.034936
2025-11-25 15:07:02,988 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) wtime: 0.178
2025-11-25 15:07:02,989 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) output:
Remove path log...
Remove path log...Ok

2025-11-25 15:07:02,989 - DEBUG - ya.test - _save_outputs: Command (pid 3988347) errors:

2025-11-25 15:07:02,996 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-25 15:07:02,997 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, ae453174-4c8b-4a38-90b8-d561f043dd94, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:02,998 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, ae453174-4c8b-4a38-90b8-d561f043dd94, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ODU2ODJhMDgtY2JlZjFlYWUtN2QzODE3Ni1lMGUyNzFjYQ==" }
2025-11-25 15:07:03,070 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, ae453174-4c8b-4a38-90b8-d561f043dd94, ghrun-n7olz6xni4.auto.internal:4007): response = { status: SUCCESS }
2025-11-25 15:07:03,072 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, d92e5fbc-82bf-4181-b988-9529dffc5536, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:03,072 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, d92e5fbc-82bf-4181-b988-9529dffc5536, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ODZmZDcyNzEtOGVkMTQ1YTYtMjExNWJlMmEtN2ViN2JiNjA=" }
2025-11-25 15:07:03,074 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, d92e5fbc-82bf-4181-b988-9529dffc5536, ghrun-n7olz6xni4.auto.internal:4007): response = { status: SUCCESS }
2025-11-25 15:07:03,075 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, dc5f563e-7403-40b0-bc90-e1acb58482d9, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:03,076 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, dc5f563e-7403-40b0-bc90-e1acb58482d9, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=Yjc1ZTk3MDEtNzMyMTQ4NjAtYmIyYWE5NWQtN2JlZDNhNmY=" }
2025-11-25 15:07:03,078 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, dc5f563e-7403-40b0-bc90-e1acb58482d9, ghrun-n7olz6xni4.auto.internal:4007): response = { status: SUCCESS }
2025-11-25 15:07:03,080 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, d726ab2c-e3ac-4dc2-b468-8c58edfbc662, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:03,081 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, d726ab2c-e3ac-4dc2-b468-8c58edfbc662, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ZTNiYjVjODAtYWFhYjk3NDctYTFjNGRjNC1iYWMyZGZkYw==" }
2025-11-25 15:07:03,084 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, d726ab2c-e3ac-4dc2-b468-8c58edfbc662, ghrun-n7olz6xni4.auto.internal:4007): response = { status: SUCCESS }
2025-11-25 15:07:03,084 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 04ae905b-beb9-42e5-8ce4-78e93774ccc1, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:03,085 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 04ae905b-beb9-42e5-8ce4-78e93774ccc1, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=ZDFkYmRmZTUtNDNiN2IyOS0xZjEwNmM1OS03MTE2MTMyZA==" }
2025-11-25 15:07:03,091 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 04ae905b-beb9-42e5-8ce4-78e93774ccc1, ghrun-n7olz6xni4.auto.internal:4007): response = { status: SUCCESS }
2025-11-25 15:07:03,094 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 1b1ecf9f-a0e3-4aa4-b5f9-22e38ad3a907, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:03,094 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 1b1ecf9f-a0e3-4aa4-b5f9-22e38ad3a907, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=Njc5NTUyOWMtM2E5YjU5NTItYWMxYjYzYjAtYWZmNGZhYTk=" }
2025-11-25 15:07:03,096 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 1b1ecf9f-a0e3-4aa4-b5f9-22e38ad3a907, ghrun-n7olz6xni4.auto.internal:4007): response = { status: SUCCESS }
2025-11-25 15:07:03,097 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, d3f02cbe-f4a6-4f7e-95d7-1f281178ec86, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:03,098 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, d3f02cbe-f4a6-4f7e-95d7-1f281178ec86, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=YTdjMjk4NTYtMjI1NGIyYTktY2NlNDEyYTMtMmI1ZjVlYTU=" }
2025-11-25 15:07:03,102 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, d3f02cbe-f4a6-4f7e-95d7-1f281178ec86, ghrun-n7olz6xni4.auto.internal:4007): response = { status: SUCCESS }
2025-11-25 15:07:03,103 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, acdf99c8-edd7-4090-8b7a-d7c682143f2a, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:03,104 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, acdf99c8-edd7-4090-8b7a-d7c682143f2a, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=NWI4YzBkNTYtMzYxZDRjNGQtM2Q4ZTc5ZjMtZjIzODllMjM=" }
2025-11-25 15:07:03,113 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, acdf99c8-edd7-4090-8b7a-d7c682143f2a, ghrun-n7olz6xni4.auto.internal:4007): response = { status: SUCCESS }
2025-11-25 15:07:03,116 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, c33252fe-5b67-4287-a9f4-c0c9ce8f6062, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:03,120 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, c33252fe-5b67-4287-a9f4-c0c9ce8f6062, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=YmI4NDQ2NGMtMWZiN2JjMTktM2Q4Zjk5MjktMjQ4NGJhZWY=" }
2025-11-25 15:07:03,123 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, c33252fe-5b67-4287-a9f4-c0c9ce8f6062, ghrun-n7olz6xni4.auto.internal:4007): response = { status: SUCCESS }
2025-11-25 15:07:03,125 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 79afd082-2fa2-4ff1-994b-6c9fac011eda, ghrun-n7olz6xni4.auto.internal:4007): creating call state
2025-11-25 15:07:03,128 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 79afd082-2fa2-4ff1-994b-6c9fac011eda, ghrun-n7olz6xni4.auto.internal:4007): request = { session_id: "ydb://session/3?node_id=1&id=MTQxYTEwMDUtZTFkMGFhN2MtMTEwNzE4MzAtZjMwZmMzNjc=" }
2025-11-25 15:07:03,132 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 79afd082-2fa2-4ff1-994b-6c9fac011eda, ghrun-n7olz6xni4.auto.internal:4007): response = { status: SUCCESS }
2025-11-25 15:07:03,132 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-25 15:07:03,133 - DEBUG - ydb.topic - close: Close topic client
2025-11-25 15:07:03,133 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-25 15:07:03,139 - INFO - ydb.connection - close: Closing channel for endpoint localhost:4007
2025-11-25 15:07:03,140 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-n7olz6xni4.auto.internal:4007
2025-11-25 15:07:03,141 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-25 15:07:10,144 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:4007/1
2025-11-25 15:07:10,145 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/63390
2025-11-25 15:07:10,145 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/16041
2025-11-25 15:07:10,145 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/17361
2025-11-25 15:07:10,146 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/4007
2025-11-25 15:07:10,146 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/22875
