2025-11-11 16:05:15,948 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 16:05:15,949 - INFO - ya.test - pytest_runtest_setup: test_log_uniform
2025-11-11 16:05:15,950 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-11 16:05:15,950 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-11 16:05:15,957 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_uniform)
2025-11-11 16:05:15,958 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb
2025-11-11 16:05:15,958 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb -e grpc://localhost:30334 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-11-11 16:05:15,966 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.11
2025-11-11 16:05:15,969 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.11
2025-11-11 16:05:15,971 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:30334', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-11-11 16:05:15,977 - DEBUG - ya.test - execute: Command pid: 3299475
2025-11-11 16:05:16,565 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) rc: 0
2025-11-11 16:05:16,570 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) elapsed time (sec): 0.584970235824585
2025-11-11 16:05:16,576 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) idrss: 0
2025-11-11 16:05:16,579 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) inblock: 0
2025-11-11 16:05:16,579 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) isrss: 0
2025-11-11 16:05:16,580 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) ixrss: 0
2025-11-11 16:05:16,581 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) majflt: 0
2025-11-11 16:05:16,582 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) maxrss: 744152
2025-11-11 16:05:16,582 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) minflt: 3482
2025-11-11 16:05:16,583 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) msgrcv: 0
2025-11-11 16:05:16,583 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) msgsnd: 0
2025-11-11 16:05:16,584 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) nivcsw: 36
2025-11-11 16:05:16,587 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) nsignals: 0
2025-11-11 16:05:16,590 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) nswap: 0
2025-11-11 16:05:16,595 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) nvcsw: 172
2025-11-11 16:05:16,595 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) oublock: 8
2025-11-11 16:05:16,595 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) stime: 0.024464999999999997
2025-11-11 16:05:16,595 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) utime: 0.053008
2025-11-11 16:05:16,596 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) wtime: 0.588
2025-11-11 16:05:16,596 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) output:
Init tables ...
Init tables ...Ok

2025-11-11 16:05:16,596 - DEBUG - ya.test - _save_outputs: Command (pid 3299475) errors:

2025-11-11 16:05:16,596 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-11 16:05:16,596 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb -e grpc://localhost:30334 -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-11 16:05:16,597 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.12
2025-11-11 16:05:16,598 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.12
2025-11-11 16:05:16,598 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:30334', '-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-11 16:05:16,607 - DEBUG - ya.test - execute: Command pid: 3299941
2025-11-11 16:05:31,838 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 16:05:31,839 - 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-11 16:05:31,854 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-11 16:05:31,855 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 1d280797-191c-498f-b6a3-4d558deff7b0, localhost:30334): creating call state
2025-11-11 16:05:31,855 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 1d280797-191c-498f-b6a3-4d558deff7b0, localhost:30334): request = { database: "/Root" }
2025-11-11 16:05:31,865 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 1d280797-191c-498f-b6a3-4d558deff7b0, localhost:30334): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-l3u76ogmey.auto.internal" port: 30334 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-11 16:05:31,866 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-l3u76ogmey.auto.internal:30334, location 1, ssl: False>]>
2025-11-11 16:05:31,876 - INFO - ydb.connection - close: Closing channel for endpoint localhost:30334
2025-11-11 16:05:46,864 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 16:05:46,864 - DEBUG - ya.test - _save_outputs: Command (pid 3299941) rc: 0
2025-11-11 16:05:46,865 - DEBUG - ya.test - _save_outputs: Command (pid 3299941) elapsed time (sec): 30.256959676742554
2025-11-11 16:05:46,866 - DEBUG - ya.test - _save_outputs: Command (pid 3299941) wtime: 30.266
2025-11-11 16:05:46,869 - DEBUG - ya.test - _save_outputs: Command (pid 3299941) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	    473	473	0	0	18	34	60	68
2	    519	519	0	0	17	31	37	46
3	    475	475	0	0	18	39	51	54
4	    481	481	0	0	19	34	38	43
5	    467	467	0	0	20	33	38	43
6	    348	348	0	0	26	53	79	96
7	    449	449	0	0	19	41	68	73
8	    448	448	0	0	19	40	49	60
9	    351	351	0	0	25	50	60	74
10	    425	425	0	0	22	38	47	51
11	    357	357	0	0	23	54	113	119
12	    471	471	0	0	17	45	64	70
13	    396	396	0	0	22	44	49	55
14	    617	617	0	0	13	31	38	41
15	    670	670	0	0	13	25	47	54
16	    614	614	0	0	15	25	31	36
17	    515	515	0	0	17	34	61	69
18	    448	448	0	0	20	41	49	56
19	    568	568	0	0	15	37	48	53
20	    530	530	0	0	16	34	45	60
21	    627	627	0	0	14	26	35	46
22	    794	794	0	0	11	19	35	40
23	    659	659	0	0	13	25	38	41
24	    494	494	0	0	18	36	41	43
25	    629	629	0	0	13	30	34	44
26	    549	549	0	0	16	31	49	59
27	    426	426	0	0	22	35	42	47
28	    409	409	0	0	22	45	54	60
29	    600	600	0	0	15	26	50	60
30	    689	689	0	0	13	24	34	44

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	  15498	516.6	0	0	16	36	51	119

2025-11-11 16:05:46,870 - DEBUG - ya.test - _save_outputs: Command (pid 3299941) errors:

2025-11-11 16:05:46,871 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:05:46,871 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e2f63ac1-4d8a-4f4a-9aa0-6816596786a7, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:05:46,897 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e2f63ac1-4d8a-4f4a-9aa0-6816596786a7, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=" 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-11 16:05:46,898 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e2f63ac1-4d8a-4f4a-9aa0-6816596786a7, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:05:55,700 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:05:55,701 - INFO - root - test_log_uniform: Count rows after insert 15484 before wait
2025-11-11 16:05:55,701 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=
2025-11-11 16:05:55,701 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 36b841f4-2376-4808-8b0e-e4628a35d54c, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:05:55,702 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 36b841f4-2376-4808-8b0e-e4628a35d54c, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=" 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-11 16:05:55,706 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 36b841f4-2376-4808-8b0e-e4628a35d54c, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:06:03,948 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=
2025-11-11 16:06:03,949 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi
2025-11-11 16:06:03,950 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 27f763b1-5234-468f-b414-3ce52b607712, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:06:03,950 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 27f763b1-5234-468f-b414-3ce52b607712, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi" 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-11 16:06:03,951 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 27f763b1-5234-468f-b414-3ce52b607712, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:06:11,229 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi
2025-11-11 16:06:11,230 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-11 16:06:11,230 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-11-11 16:06:11,231 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-11-11 16:06:11,231 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:06:11,232 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb -e grpc://localhost:30334 -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-11 16:06:11,233 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb -e grpc://localhost:30334 -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-11 16:06:11,234 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb -e grpc://localhost:30334 -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-11 16:06:11,236 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d7214c64-a132-4c86-88f2-cdcc4ae99db2, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:06:11,236 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==
2025-11-11 16:06:11,241 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d7214c64-a132-4c86-88f2-cdcc4ae99db2, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:06:11,244 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3d5b78e4-aaaa-445c-923f-a9cfae1a4d11, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:06:11,245 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:06:11,246 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.13
2025-11-11 16:06:11,250 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.14
2025-11-11 16:06:11,248 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d7214c64-a132-4c86-88f2-cdcc4ae99db2, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:06:11,250 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3fa0b0e3-52d7-4ae7-8fa3-bf1fa9716d16, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:06:11,247 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3d5b78e4-aaaa-445c-923f-a9cfae1a4d11, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:06:11,253 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.15
2025-11-11 16:06:11,254 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.13
2025-11-11 16:06:11,255 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=
2025-11-11 16:06:11,259 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3fa0b0e3-52d7-4ae7-8fa3-bf1fa9716d16, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:06:11,260 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3d5b78e4-aaaa-445c-923f-a9cfae1a4d11, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:06:11,261 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 148421a7-968f-4904-8631-77641cafdec9, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:06:11,262 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:06:11,263 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3fa0b0e3-52d7-4ae7-8fa3-bf1fa9716d16, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:06:11,264 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 148421a7-968f-4904-8631-77641cafdec9, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:06:11,264 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 92fb07d2-686a-477a-98e8-3d0f4634a499, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:06:11,265 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:06:11,266 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 148421a7-968f-4904-8631-77641cafdec9, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:06:11,266 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 92fb07d2-686a-477a-98e8-3d0f4634a499, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:06:11,267 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1b76ce68-2309-46f2-8cfe-5f9d80224f5a, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:06:11,268 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:06:11,268 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 92fb07d2-686a-477a-98e8-3d0f4634a499, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:06:11,269 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1b76ce68-2309-46f2-8cfe-5f9d80224f5a, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:06:11,270 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9085f9fd-e158-4635-8214-10f14b992c71, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:06:11,270 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.14
2025-11-11 16:06:11,270 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:06:11,271 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:30334', '-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-11 16:06:11,272 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1b76ce68-2309-46f2-8cfe-5f9d80224f5a, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:06:11,273 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9085f9fd-e158-4635-8214-10f14b992c71, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:06:11,274 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 67995786-fbd8-4d0a-9a7e-8100029dafd0, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:06:11,274 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.15
2025-11-11 16:06:11,274 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=
2025-11-11 16:06:11,276 - DEBUG - ya.test - execute: Command pid: 3346477
2025-11-11 16:06:11,276 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:30334', '-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-11 16:06:11,277 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9085f9fd-e158-4635-8214-10f14b992c71, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:06:11,278 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 67995786-fbd8-4d0a-9a7e-8100029dafd0, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:06:11,279 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:30334', '-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-11 16:06:11,279 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b6f4ec87-927e-4014-ab20-3a36dd7ded01, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:06:11,279 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi
2025-11-11 16:06:11,282 - DEBUG - ya.test - execute: Command pid: 3346484
2025-11-11 16:06:11,286 - DEBUG - ya.test - execute: Command pid: 3346485
2025-11-11 16:06:11,286 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 67995786-fbd8-4d0a-9a7e-8100029dafd0, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:06:11,287 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b6f4ec87-927e-4014-ab20-3a36dd7ded01, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:06:11,288 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6b18d7b5-03e6-45e2-bee2-657187d45547, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:06:11,291 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6b18d7b5-03e6-45e2-bee2-657187d45547, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-11 16:06:11,293 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6b18d7b5-03e6-45e2-bee2-657187d45547, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:06:11,292 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b6f4ec87-927e-4014-ab20-3a36dd7ded01, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:06:39,807 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-11 16:06:39,808 - 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-11 16:06:39,812 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-11 16:06:39,816 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 627c3037-3800-464c-b954-afd7bd80b9ce, localhost:30334): creating call state
2025-11-11 16:06:39,816 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 627c3037-3800-464c-b954-afd7bd80b9ce, localhost:30334): request = { database: "/Root" }
2025-11-11 16:06:40,149 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 627c3037-3800-464c-b954-afd7bd80b9ce, localhost:30334): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-l3u76ogmey.auto.internal" port: 30334 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-11 16:06:40,150 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-l3u76ogmey.auto.internal:30334, location 1, ssl: False>]>
2025-11-11 16:06:40,150 - INFO - ydb.connection - close: Closing channel for endpoint localhost:30334
2025-11-11 16:06:44,624 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 16:06:44,624 - DEBUG - ya.test - _save_outputs: Command (pid 3346477) rc: 0
2025-11-11 16:06:44,625 - DEBUG - ya.test - _save_outputs: Command (pid 3346477) elapsed time (sec): 33.343830585479736
2025-11-11 16:06:44,625 - DEBUG - ya.test - _save_outputs: Command (pid 3346477) wtime: 33.349
2025-11-11 16:06:44,625 - DEBUG - ya.test - _save_outputs: Command (pid 3346477) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	      0	0	0	10	0	0	0	0
3	      0	0	0	0	0	0	0	0
4	      0	0	0	10	0	0	0	0
5	      0	0	0	0	0	0	0	0
6	      0	0	0	10	0	0	0	0
7	      0	0	0	1	0	0	0	0
8	      0	0	0	9	0	0	0	0
9	      0	0	0	10	0	0	0	0
10	      0	0	0	10	0	0	0	0
11	      0	0	0	0	0	0	0	0
12	      0	0	0	6	0	0	0	0
13	      0	0	0	5	0	0	0	0
14	      0	0	0	5	0	0	0	0
15	      0	0	0	2	0	0	0	0
16	      0	0	0	8	0	0	0	0
17	      0	0	0	10	0	0	0	0
18	      0	0	0	0	0	0	0	0
19	      0	0	0	10	0	0	0	0
20	      0	0	0	0	0	0	0	0
21	      0	0	0	10	0	0	0	0
22	      0	0	0	0	0	0	0	0
23	      0	0	0	6	0	0	0	0
24	      0	0	0	4	0	0	0	0
25	      0	0	0	10	0	0	0	0
26	      0	0	0	10	0	0	0	0
27	      0	0	0	0	0	0	0	0
28	      0	0	0	10	0	0	0	0
29	      0	0	0	10	0	0	0	0
30	      0	0	0	10	0	0	0	0

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	      0	0	0	176	0	0	0	0

2025-11-11 16:06:44,625 - DEBUG - ya.test - _save_outputs: Command (pid 3346477) errors:

2025-11-11 16:06:45,019 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 16:06:45,019 - DEBUG - ya.test - _save_outputs: Command (pid 3346484) rc: 0
2025-11-11 16:06:45,020 - DEBUG - ya.test - _save_outputs: Command (pid 3346484) elapsed time (sec): 33.72994518280029
2025-11-11 16:06:45,020 - DEBUG - ya.test - _save_outputs: Command (pid 3346484) wtime: 33.738
2025-11-11 16:06:45,020 - DEBUG - ya.test - _save_outputs: Command (pid 3346484) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      0	0	0	0	0	0	0	0
2	      0	0	0	10	0	0	0	0
3	      0	0	0	0	0	0	0	0
4	      0	0	0	8	0	0	0	0
5	      0	0	0	2	0	0	0	0
6	      0	0	0	10	0	0	0	0
7	      0	0	0	2	0	0	0	0
8	      0	0	0	8	0	0	0	0
9	      0	0	0	10	0	0	0	0
10	      0	0	0	10	0	0	0	0
11	      0	0	0	0	0	0	0	0
12	      0	0	0	5	0	0	0	0
13	      0	0	0	5	0	0	0	0
14	      0	0	0	3	0	0	0	0
15	      0	0	0	6	0	0	0	0
16	      0	0	0	4	0	0	0	0
17	      0	0	0	10	0	0	0	0
18	      0	0	0	0	0	0	0	0
19	      0	0	0	10	0	0	0	0
20	      0	0	0	0	0	0	0	0
21	      0	0	0	10	0	0	0	0
22	      0	0	0	0	0	0	0	0
23	      0	0	0	5	0	0	0	0
24	      0	0	0	5	0	0	0	0
25	      0	0	0	9	0	0	0	0
26	      0	0	0	9	0	0	0	0
27	      0	0	0	3	0	0	0	0
28	      0	0	0	8	0	0	0	0
29	      0	0	0	9	0	0	0	0
30	      0	0	0	13	0	0	0	0

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	      0	0	0	174	0	0	0	0

2025-11-11 16:06:45,020 - DEBUG - ya.test - _save_outputs: Command (pid 3346484) errors:

2025-11-11 16:06:58,608 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-11 16:06:58,609 - DEBUG - ya.test - _save_outputs: Command (pid 3346485) rc: 0
2025-11-11 16:06:58,609 - DEBUG - ya.test - _save_outputs: Command (pid 3346485) elapsed time (sec): 47.319053411483765
2025-11-11 16:06:58,609 - DEBUG - ya.test - _save_outputs: Command (pid 3346485) wtime: 47.324
2025-11-11 16:06:58,610 - DEBUG - ya.test - _save_outputs: Command (pid 3346485) 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	      0	0	0	0	0	0	0	0
6	      0	0	0	0	0	0	0	0
7	      7	7	0	0	6271	6975	6975	6975
8	      3	3	0	0	7359	7391	7391	7391
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	      0	0	0	0	0	0	0	0
20	      0	0	0	0	0	0	0	0
21	      1	1	0	0	14527	14527	14527	14527
22	      0	0	0	0	0	0	0	0
23	      2	2	0	0	16319	16767	16767	16767
24	      3	3	0	0	16639	17023	17023	17023
25	      1	1	0	0	17023	17023	17023	17023
26	      2	2	0	0	18303	18687	18687	18687
27	      1	1	0	0	19071	19071	19071	19071
28	      0	0	0	0	0	0	0	0
29	      0	0	0	0	0	0	0	0
30	      3	3	0	7	20607	20607	20607	20607

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	     23	0.766667	0	7	16319	20607	20607	20607

2025-11-11 16:06:58,610 - DEBUG - ya.test - _save_outputs: Command (pid 3346485) errors:

2025-11-11 16:07:40,454 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:07:40,461 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:07:40,462 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cb2c7bbc-7d5a-4461-bfb7-efc231192d37, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:40,463 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cb2c7bbc-7d5a-4461-bfb7-efc231192d37, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=" 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-11 16:07:40,466 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cb2c7bbc-7d5a-4461-bfb7-efc231192d37, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:41,340 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:07:41,349 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:07:41,350 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 712f0fd3-103c-4555-b04d-de7d5c21ca21, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:41,350 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 712f0fd3-103c-4555-b04d-de7d5c21ca21, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:07:41,353 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 712f0fd3-103c-4555-b04d-de7d5c21ca21, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:41,459 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:07:41,461 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:07:41,462 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7abb7229-5146-485f-aa7b-299dc4d2315b, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:41,463 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7abb7229-5146-485f-aa7b-299dc4d2315b, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==" 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-11 16:07:41,469 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7abb7229-5146-485f-aa7b-299dc4d2315b, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:41,702 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:07:41,721 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:07:41,729 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:07:41,748 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 547ff7c8-c703-4f73-a7cf-205df5dc7160, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:41,781 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:07:41,807 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 547ff7c8-c703-4f73-a7cf-205df5dc7160, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=" 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-11 16:07:41,809 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 913ce4c7-64e0-4320-8648-810fa4a866ca, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:41,821 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 913ce4c7-64e0-4320-8648-810fa4a866ca, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=" 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-11 16:07:41,829 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 547ff7c8-c703-4f73-a7cf-205df5dc7160, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:41,845 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 913ce4c7-64e0-4320-8648-810fa4a866ca, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:41,906 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:07:41,933 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:07:41,935 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bd9d3388-cd3f-46c4-9c4a-fcd55dc17611, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:41,959 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bd9d3388-cd3f-46c4-9c4a-fcd55dc17611, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=" 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-11 16:07:41,971 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bd9d3388-cd3f-46c4-9c4a-fcd55dc17611, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:42,102 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:07:42,109 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==
2025-11-11 16:07:42,135 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:07:42,136 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 223e3686-2d2f-4c31-9cde-8211d3b91ad5, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:42,141 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==
2025-11-11 16:07:42,159 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 223e3686-2d2f-4c31-9cde-8211d3b91ad5, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=" 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-11 16:07:42,161 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, af08673c-b0f0-49c2-a336-832f36cc819c, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:42,185 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 223e3686-2d2f-4c31-9cde-8211d3b91ad5, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:42,185 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, af08673c-b0f0-49c2-a336-832f36cc819c, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:07:42,196 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:07:42,219 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, af08673c-b0f0-49c2-a336-832f36cc819c, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:42,220 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:07:42,234 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a945efdb-c7d2-408f-883f-c43201f99740, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:42,244 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a945efdb-c7d2-408f-883f-c43201f99740, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=" 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-11 16:07:42,249 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=
2025-11-11 16:07:42,249 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=
2025-11-11 16:07:42,252 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 52d3d5bf-86a5-4637-bca6-dc037b61bc30, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:42,252 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 52d3d5bf-86a5-4637-bca6-dc037b61bc30, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=" 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-11 16:07:42,260 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a945efdb-c7d2-408f-883f-c43201f99740, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:42,281 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi
2025-11-11 16:07:42,301 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=
2025-11-11 16:07:42,302 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 52d3d5bf-86a5-4637-bca6-dc037b61bc30, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:42,312 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi
2025-11-11 16:07:42,358 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:07:42,359 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b6f1e1e0-9c1a-4dea-b3ec-f6abb021aa56, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:42,363 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=
2025-11-11 16:07:42,472 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b6f1e1e0-9c1a-4dea-b3ec-f6abb021aa56, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:07:42,490 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b8b0a1f1-6fd1-423a-b1b2-64cc83da890a, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:42,490 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:07:42,505 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b6f1e1e0-9c1a-4dea-b3ec-f6abb021aa56, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:42,514 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b8b0a1f1-6fd1-423a-b1b2-64cc83da890a, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=" 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-11 16:07:42,521 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6d3d4b7d-cab9-4e88-b6ab-ec4e71aebbf9, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:42,540 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:07:42,548 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==
2025-11-11 16:07:42,576 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6d3d4b7d-cab9-4e88-b6ab-ec4e71aebbf9, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==" 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-11 16:07:42,577 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b8b0a1f1-6fd1-423a-b1b2-64cc83da890a, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:42,606 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:07:42,607 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:07:42,659 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6d3d4b7d-cab9-4e88-b6ab-ec4e71aebbf9, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:42,667 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, efdc4436-32b7-4f12-a414-8f006b2212c7, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:42,670 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==
2025-11-11 16:07:42,726 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, efdc4436-32b7-4f12-a414-8f006b2212c7, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(7))" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:07:42,737 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b97a1728-b7c2-42fe-8f40-f3e329f5c20b, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:42,743 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b97a1728-b7c2-42fe-8f40-f3e329f5c20b, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:07:42,750 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, efdc4436-32b7-4f12-a414-8f006b2212c7, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:42,782 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b97a1728-b7c2-42fe-8f40-f3e329f5c20b, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:42,994 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:07:43,015 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:07:43,047 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:07:43,053 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:07:43,080 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 525ba888-fa1c-419b-90a0-69079d7544f5, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:43,081 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:07:43,123 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 525ba888-fa1c-419b-90a0-69079d7544f5, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=" 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-11 16:07:43,149 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 45d522ad-b0a1-49ff-a975-0691e783b65d, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:43,208 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 45d522ad-b0a1-49ff-a975-0691e783b65d, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=" 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-11 16:07:43,217 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 525ba888-fa1c-419b-90a0-69079d7544f5, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:43,226 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 45d522ad-b0a1-49ff-a975-0691e783b65d, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:43,390 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:07:43,394 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:07:43,440 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:07:43,449 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:07:43,485 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 15b6f45e-7672-4be2-8ba8-37b33c8fc984, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:43,485 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:07:43,496 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 15b6f45e-7672-4be2-8ba8-37b33c8fc984, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==" 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-11 16:07:43,497 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2c99605b-7a82-4aa1-b96a-8206bbeb45be, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:43,512 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2c99605b-7a82-4aa1-b96a-8206bbeb45be, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=" 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-11 16:07:43,513 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 15b6f45e-7672-4be2-8ba8-37b33c8fc984, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:43,525 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2c99605b-7a82-4aa1-b96a-8206bbeb45be, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:43,630 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:07:43,825 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:07:43,851 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:07:43,852 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, caa13875-2a34-45c9-a059-6f052ada0dda, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:43,853 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, caa13875-2a34-45c9-a059-6f052ada0dda, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=" 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-11 16:07:43,861 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, caa13875-2a34-45c9-a059-6f052ada0dda, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:43,926 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=
2025-11-11 16:07:43,958 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:07:43,965 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:07:43,966 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:07:44,005 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 14bd70a2-3463-4603-b931-eec8db744a66, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,005 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 14bd70a2-3463-4603-b931-eec8db744a66, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=" 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-11 16:07:44,006 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:07:44,006 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 335479d0-bc87-4106-af5e-1e1e977b339f, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,010 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi
2025-11-11 16:07:44,010 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 14bd70a2-3463-4603-b931-eec8db744a66, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:44,010 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:07:44,018 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 335479d0-bc87-4106-af5e-1e1e977b339f, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=" 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-11 16:07:44,082 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d4cb90b9-2275-4b3b-96ff-db69e3970b7d, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,083 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:07:44,084 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 335479d0-bc87-4106-af5e-1e1e977b339f, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:44,086 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d4cb90b9-2275-4b3b-96ff-db69e3970b7d, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=" 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-11 16:07:44,095 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:07:44,121 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=
2025-11-11 16:07:44,111 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=
2025-11-11 16:07:44,096 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1e5772e4-083c-465b-af1b-2bde5c672a55, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,122 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d4cb90b9-2275-4b3b-96ff-db69e3970b7d, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:44,123 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 228a6790-eef0-440c-890b-a52d754a1a39, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,146 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:07:44,146 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1e5772e4-083c-465b-af1b-2bde5c672a55, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:07:44,148 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 228a6790-eef0-440c-890b-a52d754a1a39, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=" 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-11 16:07:44,157 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==
2025-11-11 16:07:44,155 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d7a42f58-88fd-4b83-a3ac-f6be9ff54d9b, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,158 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=
2025-11-11 16:07:44,192 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d7a42f58-88fd-4b83-a3ac-f6be9ff54d9b, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=" 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-11 16:07:44,166 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 228a6790-eef0-440c-890b-a52d754a1a39, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:44,189 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:07:44,165 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1e5772e4-083c-465b-af1b-2bde5c672a55, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:44,194 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d7a42f58-88fd-4b83-a3ac-f6be9ff54d9b, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:44,195 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f0edb7b8-6dfb-419b-8285-ccc1bfe2c227, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,198 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f0edb7b8-6dfb-419b-8285-ccc1bfe2c227, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:07:44,199 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f0edb7b8-6dfb-419b-8285-ccc1bfe2c227, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:44,290 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=
2025-11-11 16:07:44,302 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=
2025-11-11 16:07:44,304 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=
2025-11-11 16:07:44,304 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi
2025-11-11 16:07:44,305 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 10f236eb-d038-4398-abc3-6c60d5838bd6, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,308 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==
2025-11-11 16:07:44,309 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 10f236eb-d038-4398-abc3-6c60d5838bd6, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(7))" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:07:44,310 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=
2025-11-11 16:07:44,310 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:07:44,311 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 10f236eb-d038-4398-abc3-6c60d5838bd6, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:44,312 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9d4e1328-625b-4a4d-b301-fd7cb129830b, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,313 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=
2025-11-11 16:07:44,315 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9d4e1328-625b-4a4d-b301-fd7cb129830b, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(7))" } stats_mode: STATS_MODE_NONE }
2025-11-11 16:07:44,316 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aeca532b-6fb0-45c0-8749-3046f3a52c5d, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,317 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9d4e1328-625b-4a4d-b301-fd7cb129830b, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:44,318 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aeca532b-6fb0-45c0-8749-3046f3a52c5d, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=" 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-11 16:07:44,333 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aeca532b-6fb0-45c0-8749-3046f3a52c5d, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:44,376 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi
2025-11-11 16:07:44,386 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=
2025-11-11 16:07:44,387 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=
2025-11-11 16:07:44,389 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==
2025-11-11 16:07:44,391 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0400e03c-20b4-4b2c-874b-4b495be9b9c3, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,392 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0400e03c-20b4-4b2c-874b-4b495be9b9c3, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==" 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-11 16:07:44,393 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0400e03c-20b4-4b2c-874b-4b495be9b9c3, ghrun-l3u76ogmey.auto.internal:30334): response = { <_MultiThreadedRendezvous object> }
2025-11-11 16:07:44,436 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==
2025-11-11 16:07:44,436 - INFO - root - test_log_uniform: check insert: 7813 15484
2025-11-11 16:07:44,438 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.16
2025-11-11 16:07:44,439 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.16
2025-11-11 16:07:44,440 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/72pg/000725/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:30334', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-11-11 16:07:44,441 - DEBUG - ya.test - execute: Command pid: 3438681
2025-11-11 16:07:44,608 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) rc: 0
2025-11-11 16:07:44,609 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) elapsed time (sec): 0.16100263595581055
2025-11-11 16:07:44,615 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) idrss: 0
2025-11-11 16:07:44,628 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) inblock: 0
2025-11-11 16:07:44,631 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) isrss: 0
2025-11-11 16:07:44,632 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) ixrss: 0
2025-11-11 16:07:44,632 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) majflt: 0
2025-11-11 16:07:44,632 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) maxrss: 744152
2025-11-11 16:07:44,632 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) minflt: 3482
2025-11-11 16:07:44,632 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) msgrcv: 0
2025-11-11 16:07:44,632 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) msgsnd: 0
2025-11-11 16:07:44,633 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) nivcsw: 16
2025-11-11 16:07:44,633 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) nsignals: 0
2025-11-11 16:07:44,633 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) nswap: 0
2025-11-11 16:07:44,633 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) nvcsw: 192
2025-11-11 16:07:44,633 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) oublock: 8
2025-11-11 16:07:44,633 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) stime: 0.030676
2025-11-11 16:07:44,634 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) utime: 0.045956
2025-11-11 16:07:44,634 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) wtime: 0.166
2025-11-11 16:07:44,634 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) output:
Remove path log...
Remove path log...Ok

2025-11-11 16:07:44,635 - DEBUG - ya.test - _save_outputs: Command (pid 3438681) errors:

2025-11-11 16:07:44,640 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-11 16:07:44,641 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 08d7591d-83ef-4991-bdb3-7dd4f68f694d, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,642 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 08d7591d-83ef-4991-bdb3-7dd4f68f694d, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OTMzZWNhMzItOTA2NWY2OTYtYmYxNDZhM2MtNTg1NzI4ZTY=" }
2025-11-11 16:07:44,686 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 08d7591d-83ef-4991-bdb3-7dd4f68f694d, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS }
2025-11-11 16:07:44,690 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 46ed852a-8b89-4a03-b293-a7df04b0fc33, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,692 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 46ed852a-8b89-4a03-b293-a7df04b0fc33, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YzY4ZDI4ZTUtN2VjZjVmZjktZWU1ZTc1MGQtYzUwYjQxMTU=" }
2025-11-11 16:07:44,702 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 46ed852a-8b89-4a03-b293-a7df04b0fc33, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS }
2025-11-11 16:07:44,708 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, b003f16e-a9d2-4c4d-ae46-d34e75b12177, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,710 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, b003f16e-a9d2-4c4d-ae46-d34e75b12177, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=OGQzNzg3ODUtYTBjZDJjM2EtYWJjOGNmYTMtOThmMWZlZmE=" }
2025-11-11 16:07:44,718 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, b003f16e-a9d2-4c4d-ae46-d34e75b12177, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS }
2025-11-11 16:07:44,720 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, a3cfae5c-8946-43e9-94d7-9d47d4cd8dfe, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,721 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, a3cfae5c-8946-43e9-94d7-9d47d4cd8dfe, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ODQ0YzdkNTUtNzlhMjk1YjMtYjZkMGJkZGUtMzYzMjk0M2Y=" }
2025-11-11 16:07:44,730 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, a3cfae5c-8946-43e9-94d7-9d47d4cd8dfe, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS }
2025-11-11 16:07:44,731 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 08a2040d-c570-4e9d-9a15-7f6e4efe82e1, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,732 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 08a2040d-c570-4e9d-9a15-7f6e4efe82e1, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=MWU2ZmE1YzEtNjEyMzdhZi1hMTY0OTY5Yy1mM2MyMmI0ZA==" }
2025-11-11 16:07:44,734 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 08a2040d-c570-4e9d-9a15-7f6e4efe82e1, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS }
2025-11-11 16:07:44,735 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, ef12cc63-ed7c-4a45-bf25-2e713aca8296, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,736 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, ef12cc63-ed7c-4a45-bf25-2e713aca8296, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=NjNkZjFlZmItM2ZlZDJjNjgtNzliNjIxZTctYjJmZWYxZGQ=" }
2025-11-11 16:07:44,738 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, ef12cc63-ed7c-4a45-bf25-2e713aca8296, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS }
2025-11-11 16:07:44,741 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 7d47ab42-563f-4d9e-9500-6768debda132, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,743 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 7d47ab42-563f-4d9e-9500-6768debda132, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZDIxNjVhNDgtYzllMjRkLTk1ZDBhOWM2LTYxY2E5MWZi" }
2025-11-11 16:07:44,749 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 7d47ab42-563f-4d9e-9500-6768debda132, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS }
2025-11-11 16:07:44,750 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 9dd5dc60-48d3-463c-adea-1d5218dc745c, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,756 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 9dd5dc60-48d3-463c-adea-1d5218dc745c, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=YTEyZDJjYmItOGU1OGJmZTYtZDk1M2YyNGItNjhiYzA0YTQ=" }
2025-11-11 16:07:44,759 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 9dd5dc60-48d3-463c-adea-1d5218dc745c, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS }
2025-11-11 16:07:44,762 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 905686d0-045b-4042-af05-40b669bb91bd, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,762 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 905686d0-045b-4042-af05-40b669bb91bd, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZjFlOGZiNmMtODU0N2I1NWYtNGJjNzRhODgtM2Q1MDg2ZmY=" }
2025-11-11 16:07:44,769 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 905686d0-045b-4042-af05-40b669bb91bd, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS }
2025-11-11 16:07:44,769 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, c792fe2d-65a7-4bd8-a30b-42d0625c711c, ghrun-l3u76ogmey.auto.internal:30334): creating call state
2025-11-11 16:07:44,770 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, c792fe2d-65a7-4bd8-a30b-42d0625c711c, ghrun-l3u76ogmey.auto.internal:30334): request = { session_id: "ydb://session/3?node_id=1&id=ZWVmZjI5OC0zMzc4NjZjYy0yNzc5OGQ4Ny1kYTIzZjQ4Nw==" }
2025-11-11 16:07:44,772 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, c792fe2d-65a7-4bd8-a30b-42d0625c711c, ghrun-l3u76ogmey.auto.internal:30334): response = { status: SUCCESS }
2025-11-11 16:07:44,791 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-11 16:07:44,793 - DEBUG - ydb.topic - close: Close topic client
2025-11-11 16:07:44,795 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-11 16:07:44,795 - INFO - ydb.connection - close: Closing channel for endpoint localhost:30334
2025-11-11 16:07:44,796 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-l3u76ogmey.auto.internal:30334
2025-11-11 16:07:44,798 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-11 16:07:56,802 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:30334/1
2025-11-11 16:07:56,803 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/1212
2025-11-11 16:07:56,803 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/24431
2025-11-11 16:07:56,803 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6304
2025-11-11 16:07:56,804 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/30334
2025-11-11 16:07:56,804 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/27425
