2025-11-25 14:49:18,096 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-25 14:49:18,101 - INFO - ya.test - pytest_runtest_setup: test_log_uniform
2025-11-25 14:49:18,103 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-25 14:49:18,103 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-25 14:49:18,114 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_uniform)
2025-11-25 14:49:18,118 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/g0y6/0007d0/ydb/apps/ydb/ydb
2025-11-25 14:49:18,118 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/g0y6/0007d0/ydb/apps/ydb/ydb -e grpc://localhost:3598 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-11-25 14:49:18,123 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.11
2025-11-25 14:49:18,127 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.11
2025-11-25 14:49:18,127 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g0y6/0007d0/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:3598', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-11-25 14:49:18,139 - DEBUG - ya.test - execute: Command pid: 3716957
2025-11-25 14:49:18,875 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) rc: 0
2025-11-25 14:49:18,881 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) elapsed time (sec): 0.7303738594055176
2025-11-25 14:49:18,885 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) idrss: 0
2025-11-25 14:49:18,886 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) inblock: 0
2025-11-25 14:49:18,886 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) isrss: 0
2025-11-25 14:49:18,894 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) ixrss: 0
2025-11-25 14:49:18,897 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) majflt: 0
2025-11-25 14:49:18,899 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) maxrss: 531328
2025-11-25 14:49:18,902 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) minflt: 3485
2025-11-25 14:49:18,903 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) msgrcv: 0
2025-11-25 14:49:18,903 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) msgsnd: 0
2025-11-25 14:49:18,905 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) nivcsw: 42
2025-11-25 14:49:18,906 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) nsignals: 0
2025-11-25 14:49:18,906 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) nswap: 0
2025-11-25 14:49:18,907 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) nvcsw: 135
2025-11-25 14:49:18,910 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) oublock: 8
2025-11-25 14:49:18,911 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) stime: 0.028752999999999997
2025-11-25 14:49:18,919 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) utime: 0.050318999999999996
2025-11-25 14:49:18,919 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) wtime: 0.734
2025-11-25 14:49:18,920 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) output:
Init tables ...
Init tables ...Ok

2025-11-25 14:49:18,920 - DEBUG - ya.test - _save_outputs: Command (pid 3716957) errors:

2025-11-25 14:49:18,921 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-25 14:49:18,921 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/g0y6/0007d0/ydb/apps/ydb/ydb -e grpc://localhost:3598 -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 14:49:18,924 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.12
2025-11-25 14:49:18,925 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.12
2025-11-25 14:49:18,927 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g0y6/0007d0/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:3598', '-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 14:49:18,928 - DEBUG - ya.test - execute: Command pid: 3717245
2025-11-25 14:49:24,183 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-25 14:49:24,184 - 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 14:49:24,204 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-25 14:49:24,204 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 18707bc1-ca4b-4d7a-9c77-5d003080bee5, localhost:3598): creating call state
2025-11-25 14:49:24,205 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 18707bc1-ca4b-4d7a-9c77-5d003080bee5, localhost:3598): request = { database: "/Root" }
2025-11-25 14:49:24,217 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 18707bc1-ca4b-4d7a-9c77-5d003080bee5, localhost:3598): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-n7olz6xni4.auto.internal" port: 3598 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 14:49:24,225 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-n7olz6xni4.auto.internal:3598, location 1, ssl: False>]>
2025-11-25 14:49:24,225 - INFO - ydb.connection - close: Closing channel for endpoint localhost:3598
2025-11-25 14:49:49,192 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-25 14:49:49,193 - DEBUG - ya.test - _save_outputs: Command (pid 3717245) rc: 0
2025-11-25 14:49:49,193 - DEBUG - ya.test - _save_outputs: Command (pid 3717245) elapsed time (sec): 30.257638692855835
2025-11-25 14:49:49,193 - DEBUG - ya.test - _save_outputs: Command (pid 3717245) wtime: 30.265
2025-11-25 14:49:49,194 - DEBUG - ya.test - _save_outputs: Command (pid 3717245) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	    340	340	0	0	24	54	112	120
2	    312	312	0	0	31	46	56	57
3	    349	349	0	0	27	42	57	75
4	    361	361	0	0	26	41	46	57
5	    275	275	0	0	32	66	71	78
6	    221	221	0	0	47	61	74	83
7	    247	247	0	0	39	53	66	67
8	    261	261	0	0	33	55	145	147
9	    270	270	0	0	33	67	77	85
10	    304	304	0	0	31	50	58	68
11	    305	305	0	0	31	54	61	65
12	    276	276	0	0	34	55	66	68
13	    318	318	0	0	29	50	56	58
14	    275	275	0	0	30	65	91	98
15	    342	342	0	0	27	46	71	72
16	    355	355	0	0	27	39	45	47
17	    372	372	0	0	25	40	50	55
18	    320	320	0	0	28	57	93	102
19	    261	261	0	0	31	84	92	95
20	    262	262	0	0	33	65	84	96
21	    333	333	0	0	27	50	61	75
22	    312	312	0	0	29	50	58	59
23	    405	405	0	0	22	37	45	50
24	    401	401	0	0	23	36	41	46
25	    354	354	0	0	27	42	52	63
26	    356	356	0	0	26	42	52	57
27	    380	380	0	0	23	41	46	48
28	    404	404	0	0	22	45	65	71
29	    482	482	0	0	20	23	26	30
30	    473	473	0	0	20	26	30	33

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	   9926	330.867	0	0	27	52	71	147

2025-11-25 14:49:49,194 - DEBUG - ya.test - _save_outputs: Command (pid 3717245) errors:

2025-11-25 14:49:49,194 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzFhYThlNmQtNTJkZmNjNGQtNjJlZTEyY2EtZDkzMjgyYzU=
2025-11-25 14:49:49,195 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ae68c9df-2636-47ee-90be-737aca0a1199, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:49:49,196 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ae68c9df-2636-47ee-90be-737aca0a1199, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NzFhYThlNmQtNTJkZmNjNGQtNjJlZTEyY2EtZDkzMjgyYzU=" 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 14:49:49,197 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ae68c9df-2636-47ee-90be-737aca0a1199, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:49:52,029 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzFhYThlNmQtNTJkZmNjNGQtNjJlZTEyY2EtZDkzMjgyYzU=
2025-11-25 14:49:52,030 - INFO - root - test_log_uniform: Count rows after insert 9916 before wait
2025-11-25 14:49:52,030 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTVhZTVmMGItMTBjNmU2MDItNDljOGJlMmQtNTY4NmZkNjA=
2025-11-25 14:49:52,030 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f3f83d9d-4625-4564-b851-d26a2e64a316, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:49:52,030 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f3f83d9d-4625-4564-b851-d26a2e64a316, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MTVhZTVmMGItMTBjNmU2MDItNDljOGJlMmQtNTY4NmZkNjA=" 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 14:49:52,031 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f3f83d9d-4625-4564-b851-d26a2e64a316, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:49:55,458 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTVhZTVmMGItMTBjNmU2MDItNDljOGJlMmQtNTY4NmZkNjA=
2025-11-25 14:49:55,458 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDBjODU1NmMtMmZmYzE2NDAtNDhmMTk3YS1lNDJhMzJiYg==
2025-11-25 14:49:55,459 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b5f39792-8da9-41e1-8e02-333442f5df92, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:49:55,465 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b5f39792-8da9-41e1-8e02-333442f5df92, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=ZDBjODU1NmMtMmZmYzE2NDAtNDhmMTk3YS1lNDJhMzJiYg==" 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 14:49:55,470 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b5f39792-8da9-41e1-8e02-333442f5df92, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:49:58,099 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDBjODU1NmMtMmZmYzE2NDAtNDhmMTk3YS1lNDJhMzJiYg==
2025-11-25 14:49:58,100 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-25 14:49:58,101 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/g0y6/0007d0/ydb/apps/ydb/ydb -e grpc://localhost:3598 -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 14:49:58,102 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.13
2025-11-25 14:49:58,103 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.13
2025-11-25 14:49:58,103 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g0y6/0007d0/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:3598', '-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 14:49:58,117 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-11-25 14:49:58,125 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/g0y6/0007d0/ydb/apps/ydb/ydb -e grpc://localhost:3598 -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 14:49:58,127 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.14
2025-11-25 14:49:58,128 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.14
2025-11-25 14:49:58,128 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g0y6/0007d0/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:3598', '-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 14:49:58,122 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzFlMGJkMDEtZWNhMzk3ZGQtNTgxNzliZTctODc1NmZiNDI=
2025-11-25 14:49:58,118 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-11-25 14:49:58,118 - DEBUG - ya.test - execute: Command pid: 3741420
2025-11-25 14:49:58,136 - DEBUG - ya.test - execute: Command pid: 3741428
2025-11-25 14:49:58,145 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/g0y6/0007d0/ydb/apps/ydb/ydb -e grpc://localhost:3598 -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 14:49:58,145 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjJhOGMyZmItZjM4ODEzY2YtMjU4ZTMzOTktM2I0OTU4ZmM=
2025-11-25 14:49:58,151 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 392489e7-b4a0-424a-b762-ed86fc03e835, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:49:58,152 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 392489e7-b4a0-424a-b762-ed86fc03e835, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MjJhOGMyZmItZjM4ODEzY2YtMjU4ZTMzOTktM2I0OTU4ZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 14:49:58,154 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 392489e7-b4a0-424a-b762-ed86fc03e835, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:49:58,146 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bd591817-c707-4f1e-95c0-47a41e025e11, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:49:58,155 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bd591817-c707-4f1e-95c0-47a41e025e11, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NzFlMGJkMDEtZWNhMzk3ZGQtNTgxNzliZTctODc1NmZiNDI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 14:49:58,156 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bd591817-c707-4f1e-95c0-47a41e025e11, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:49:58,149 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.15
2025-11-25 14:49:58,157 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTVkYThmZDktNzk3NThmYi1mMTMyYjE1LWE4Mzg2NWMx
2025-11-25 14:49:58,158 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzFiZWI2NDktOWYxZjRmYzYtYWU3NjNjLTFhZDZmMTk1
2025-11-25 14:49:58,160 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f273b445-8ec6-4cde-a9ac-c667037aaf65, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:49:58,161 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f273b445-8ec6-4cde-a9ac-c667037aaf65, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=YzFiZWI2NDktOWYxZjRmYzYtYWU3NjNjLTFhZDZmMTk1" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 14:49:58,161 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzljNGQ3YTUtOTljZjJhNzYtNWIxODZjYjAtZTRjMjllOTA=
2025-11-25 14:49:58,159 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 19ba43f4-74df-4ef3-97ca-4e47efa8c54e, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:49:58,159 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.15
2025-11-25 14:49:58,165 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, afc7620d-c866-4de2-aab4-4bad34ec825d, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:49:58,166 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 19ba43f4-74df-4ef3-97ca-4e47efa8c54e, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NTVkYThmZDktNzk3NThmYi1mMTMyYjE1LWE4Mzg2NWMx" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 14:49:58,166 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f273b445-8ec6-4cde-a9ac-c667037aaf65, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:49:58,167 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, afc7620d-c866-4de2-aab4-4bad34ec825d, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MzljNGQ3YTUtOTljZjJhNzYtNWIxODZjYjAtZTRjMjllOTA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 14:49:58,167 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g0y6/0007d0/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:3598', '-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 14:49:58,169 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 19ba43f4-74df-4ef3-97ca-4e47efa8c54e, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:49:58,169 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEwNDA3NzctZjk0MjU1MWMtMTQxYjhlYTgtMTEwZDk4NA==
2025-11-25 14:49:58,173 - DEBUG - ya.test - execute: Command pid: 3741477
2025-11-25 14:49:58,177 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, afc7620d-c866-4de2-aab4-4bad34ec825d, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:49:58,182 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b696dc67-98c1-4f65-af9a-397de4386d04, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:49:58,184 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b696dc67-98c1-4f65-af9a-397de4386d04, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NjEwNDA3NzctZjk0MjU1MWMtMTQxYjhlYTgtMTEwZDk4NA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 14:49:58,185 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b696dc67-98c1-4f65-af9a-397de4386d04, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:49:58,182 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQxZjJlODgtOTU4ODNhZTAtNzIyY2QwYS04NzZiYTU2MA==
2025-11-25 14:49:58,186 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 42f8f100-f280-4d28-b6be-42b004e9f1ac, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:49:58,196 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 42f8f100-f280-4d28-b6be-42b004e9f1ac, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=OGQxZjJlODgtOTU4ODNhZTAtNzIyY2QwYS04NzZiYTU2MA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 14:49:58,190 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzFhYThlNmQtNTJkZmNjNGQtNjJlZTEyY2EtZDkzMjgyYzU=
2025-11-25 14:49:58,197 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 42f8f100-f280-4d28-b6be-42b004e9f1ac, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:49:58,198 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a31bd7f3-fee1-4bde-8c76-f3040ec3beb5, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:49:58,198 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTVhZTVmMGItMTBjNmU2MDItNDljOGJlMmQtNTY4NmZkNjA=
2025-11-25 14:49:58,199 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a31bd7f3-fee1-4bde-8c76-f3040ec3beb5, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NzFhYThlNmQtNTJkZmNjNGQtNjJlZTEyY2EtZDkzMjgyYzU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 14:49:58,202 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 34684f76-6858-4ef0-92e8-8bc2a7644ba7, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:49:58,203 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDBjODU1NmMtMmZmYzE2NDAtNDhmMTk3YS1lNDJhMzJiYg==
2025-11-25 14:49:58,204 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a31bd7f3-fee1-4bde-8c76-f3040ec3beb5, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:49:58,205 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 34684f76-6858-4ef0-92e8-8bc2a7644ba7, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MTVhZTVmMGItMTBjNmU2MDItNDljOGJlMmQtNTY4NmZkNjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 14:49:58,206 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1684ebb2-33f7-4c2a-9043-84c56e2d6be3, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:49:58,208 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1684ebb2-33f7-4c2a-9043-84c56e2d6be3, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=ZDBjODU1NmMtMmZmYzE2NDAtNDhmMTk3YS1lNDJhMzJiYg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-25 14:49:58,210 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 34684f76-6858-4ef0-92e8-8bc2a7644ba7, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:49:58,211 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1684ebb2-33f7-4c2a-9043-84c56e2d6be3, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:28,621 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-25 14:50:28,630 - DEBUG - ya.test - _save_outputs: Command (pid 3741420) rc: 0
2025-11-25 14:50:28,630 - DEBUG - ya.test - _save_outputs: Command (pid 3741420) elapsed time (sec): 30.482818365097046
2025-11-25 14:50:28,630 - DEBUG - ya.test - _save_outputs: Command (pid 3741420) wtime: 30.526
2025-11-25 14:50:28,631 - DEBUG - ya.test - _save_outputs: Command (pid 3741420) 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	3871	3951	3951	3951
5	      4	4	0	0	4127	4511	4511	4511
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	      1	1	0	0	5151	5151	5151	5151
10	      2	2	0	0	5311	5983	5983	5983
11	      7	7	0	0	6239	6559	6559	6559
12	      0	0	0	0	0	0	0	0
13	      0	0	0	0	0	0	0	0
14	     10	10	0	0	3391	4799	4799	4799
15	     45	45	0	0	248	331	369	369
16	     90	90	0	0	109	190	199	202
17	     84	84	0	0	116	176	178	183
18	    122	122	0	0	78	108	119	119
19	    108	108	0	0	86	136	145	149
20	     88	88	0	0	104	164	199	205
21	     72	72	0	0	127	183	190	204
22	     77	77	0	0	130	180	203	207
23	     70	70	0	0	140	238	241	247
24	     66	66	0	0	143	199	202	203
25	     48	48	0	0	200	249	251	251
26	     46	46	0	0	211	247	263	263
27	     32	32	0	0	303	351	363	363
28	     32	32	0	0	297	353	357	357
29	     29	29	0	0	341	403	417	417
30	     35	35	0	0	391	423	425	425

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	   1074	35.8	0	0	131	385	4735	6559

2025-11-25 14:50:28,631 - DEBUG - ya.test - _save_outputs: Command (pid 3741420) errors:

2025-11-25 14:50:29,357 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-25 14:50:29,358 - DEBUG - ya.test - _save_outputs: Command (pid 3741428) rc: 0
2025-11-25 14:50:29,358 - DEBUG - ya.test - _save_outputs: Command (pid 3741428) elapsed time (sec): 31.210590362548828
2025-11-25 14:50:29,358 - DEBUG - ya.test - _save_outputs: Command (pid 3741428) wtime: 31.23
2025-11-25 14:50:29,359 - DEBUG - ya.test - _save_outputs: Command (pid 3741428) 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	0	0	0	0	0
7	      0	0	0	10	0	0	0	0
8	      0	0	0	10	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	10	0	0	0	0
12	      0	0	0	0	0	0	0	0
13	      0	0	0	10	0	0	0	0
14	     13	13	0	10	403	639	735	735
15	     28	28	0	0	345	519	531	531
16	     37	37	0	0	250	409	719	719
17	     42	42	0	0	241	377	679	679
18	     49	49	0	0	220	291	311	311
19	     36	36	0	0	221	337	341	341
20	     33	33	0	2	277	361	377	377
21	     37	37	0	0	267	333	497	497
22	     31	31	0	0	311	443	471	471
23	     35	35	0	0	279	347	353	353
24	     30	30	0	0	333	431	443	443
25	     26	26	0	0	349	435	457	457
26	     22	22	0	0	413	547	615	615
27	     20	20	0	0	497	539	543	543
28	     18	18	0	0	547	607	611	611
29	     19	19	0	0	555	647	695	695
30	     29	29	0	0	551	675	683	683

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    505	16.8333	0	112	307	575	675	735

2025-11-25 14:50:29,359 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-25 14:50:29,360 - DEBUG - ya.test - _save_outputs: Command (pid 3741477) rc: 0
2025-11-25 14:50:29,360 - DEBUG - ya.test - _save_outputs: Command (pid 3741477) elapsed time (sec): 31.17705011367798
2025-11-25 14:50:29,360 - DEBUG - ya.test - _save_outputs: Command (pid 3741477) wtime: 31.19
2025-11-25 14:50:29,361 - DEBUG - ya.test - _save_outputs: Command (pid 3741477) 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	0	0	0	0	0
7	      0	0	0	10	0	0	0	0
8	      0	0	0	10	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	10	0	0	0	0
12	      0	0	0	10	0	0	0	0
13	      0	0	0	1	0	0	0	0
14	     21	21	0	9	275	377	679	679
15	     60	60	0	0	174	361	363	379
16	     64	64	0	0	148	215	224	229
17	     89	89	0	0	116	169	177	188
18	     92	92	0	0	106	157	164	169
19	     66	66	0	0	141	230	235	235
20	     64	64	0	0	145	212	217	228
21	     57	57	0	0	165	219	236	240
22	     53	53	0	0	171	267	273	289
23	     59	59	0	0	187	219	226	230
24	     40	40	0	0	230	283	293	293
25	     38	38	0	0	261	291	301	301
26	     30	30	0	0	317	373	393	393
27	     27	27	0	0	365	431	447	447
28	     23	23	0	0	421	487	487	487
29	     23	23	0	0	439	467	467	467
30	     38	38	0	0	359	499	505	505

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	    844	28.1333	0	110	171	427	487	679

2025-11-25 14:50:29,361 - DEBUG - ya.test - _save_outputs: Command (pid 3741477) errors:

2025-11-25 14:50:29,359 - DEBUG - ya.test - _save_outputs: Command (pid 3741428) errors:

2025-11-25 14:50:30,922 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjJhOGMyZmItZjM4ODEzY2YtMjU4ZTMzOTktM2I0OTU4ZmM=
2025-11-25 14:50:30,923 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjJhOGMyZmItZjM4ODEzY2YtMjU4ZTMzOTktM2I0OTU4ZmM=
2025-11-25 14:50:30,923 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dbc5e80b-3a6c-43bf-adef-1b4bab536df2, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:30,924 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dbc5e80b-3a6c-43bf-adef-1b4bab536df2, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MjJhOGMyZmItZjM4ODEzY2YtMjU4ZTMzOTktM2I0OTU4ZmM=" 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 14:50:30,924 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dbc5e80b-3a6c-43bf-adef-1b4bab536df2, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:30,990 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzFiZWI2NDktOWYxZjRmYzYtYWU3NjNjLTFhZDZmMTk1
2025-11-25 14:50:30,990 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzFiZWI2NDktOWYxZjRmYzYtYWU3NjNjLTFhZDZmMTk1
2025-11-25 14:50:30,991 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e5a22da-aabe-44cc-a4fa-d29f93c3bc03, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:30,991 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e5a22da-aabe-44cc-a4fa-d29f93c3bc03, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=YzFiZWI2NDktOWYxZjRmYzYtYWU3NjNjLTFhZDZmMTk1" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)" } stats_mode: STATS_MODE_NONE }
2025-11-25 14:50:30,992 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e5a22da-aabe-44cc-a4fa-d29f93c3bc03, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:31,011 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDBjODU1NmMtMmZmYzE2NDAtNDhmMTk3YS1lNDJhMzJiYg==
2025-11-25 14:50:31,012 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDBjODU1NmMtMmZmYzE2NDAtNDhmMTk3YS1lNDJhMzJiYg==
2025-11-25 14:50:31,012 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, df876906-2fc4-4456-9ca1-73ea07e5d42c, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:31,013 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, df876906-2fc4-4456-9ca1-73ea07e5d42c, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=ZDBjODU1NmMtMmZmYzE2NDAtNDhmMTk3YS1lNDJhMzJiYg==" 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 14:50:31,013 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, df876906-2fc4-4456-9ca1-73ea07e5d42c, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:31,094 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzljNGQ3YTUtOTljZjJhNzYtNWIxODZjYjAtZTRjMjllOTA=
2025-11-25 14:50:31,095 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzljNGQ3YTUtOTljZjJhNzYtNWIxODZjYjAtZTRjMjllOTA=
2025-11-25 14:50:31,095 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1b898540-9f86-4984-97e7-7c9ca0ac50fe, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:31,096 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1b898540-9f86-4984-97e7-7c9ca0ac50fe, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MzljNGQ3YTUtOTljZjJhNzYtNWIxODZjYjAtZTRjMjllOTA=" 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 14:50:31,097 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1b898540-9f86-4984-97e7-7c9ca0ac50fe, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:31,106 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzFhYThlNmQtNTJkZmNjNGQtNjJlZTEyY2EtZDkzMjgyYzU=
2025-11-25 14:50:31,109 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzFhYThlNmQtNTJkZmNjNGQtNjJlZTEyY2EtZDkzMjgyYzU=
2025-11-25 14:50:31,109 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2ca6a61b-d7a2-4f47-9586-442c0d3a4c3d, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:31,110 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2ca6a61b-d7a2-4f47-9586-442c0d3a4c3d, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NzFhYThlNmQtNTJkZmNjNGQtNjJlZTEyY2EtZDkzMjgyYzU=" 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 14:50:31,113 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2ca6a61b-d7a2-4f47-9586-442c0d3a4c3d, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:31,334 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTVhZTVmMGItMTBjNmU2MDItNDljOGJlMmQtNTY4NmZkNjA=
2025-11-25 14:50:31,335 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTVhZTVmMGItMTBjNmU2MDItNDljOGJlMmQtNTY4NmZkNjA=
2025-11-25 14:50:31,335 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 92f4a26c-35b8-46e9-ace8-4b43f7fc19c0, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:31,336 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 92f4a26c-35b8-46e9-ace8-4b43f7fc19c0, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MTVhZTVmMGItMTBjNmU2MDItNDljOGJlMmQtNTY4NmZkNjA=" 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 14:50:31,336 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 92f4a26c-35b8-46e9-ace8-4b43f7fc19c0, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:31,366 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEwNDA3NzctZjk0MjU1MWMtMTQxYjhlYTgtMTEwZDk4NA==
2025-11-25 14:50:31,367 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEwNDA3NzctZjk0MjU1MWMtMTQxYjhlYTgtMTEwZDk4NA==
2025-11-25 14:50:31,367 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fa48e90f-5b7e-4faa-b697-1be02fc63d4e, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:31,369 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fa48e90f-5b7e-4faa-b697-1be02fc63d4e, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NjEwNDA3NzctZjk0MjU1MWMtMTQxYjhlYTgtMTEwZDk4NA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-25 14:50:31,369 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fa48e90f-5b7e-4faa-b697-1be02fc63d4e, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:31,698 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQxZjJlODgtOTU4ODNhZTAtNzIyY2QwYS04NzZiYTU2MA==
2025-11-25 14:50:31,699 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQxZjJlODgtOTU4ODNhZTAtNzIyY2QwYS04NzZiYTU2MA==
2025-11-25 14:50:31,699 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4710ac15-8ea3-4aa6-8230-92736e48b2ba, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:31,700 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4710ac15-8ea3-4aa6-8230-92736e48b2ba, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=OGQxZjJlODgtOTU4ODNhZTAtNzIyY2QwYS04NzZiYTU2MA==" 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 14:50:31,702 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4710ac15-8ea3-4aa6-8230-92736e48b2ba, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:31,812 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTVkYThmZDktNzk3NThmYi1mMTMyYjE1LWE4Mzg2NWMx
2025-11-25 14:50:31,813 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTVkYThmZDktNzk3NThmYi1mMTMyYjE1LWE4Mzg2NWMx
2025-11-25 14:50:31,813 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bc6300eb-2366-477c-9b78-f3b57dfe1095, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:31,814 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bc6300eb-2366-477c-9b78-f3b57dfe1095, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NTVkYThmZDktNzk3NThmYi1mMTMyYjE1LWE4Mzg2NWMx" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)" } stats_mode: STATS_MODE_NONE }
2025-11-25 14:50:31,816 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bc6300eb-2366-477c-9b78-f3b57dfe1095, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:31,864 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzFlMGJkMDEtZWNhMzk3ZGQtNTgxNzliZTctODc1NmZiNDI=
2025-11-25 14:50:31,865 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzFlMGJkMDEtZWNhMzk3ZGQtNTgxNzliZTctODc1NmZiNDI=
2025-11-25 14:50:31,865 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b612d006-0c02-426e-b929-0160302597ca, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:31,867 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b612d006-0c02-426e-b929-0160302597ca, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NzFlMGJkMDEtZWNhMzk3ZGQtNTgxNzliZTctODc1NmZiNDI=" 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 14:50:31,868 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b612d006-0c02-426e-b929-0160302597ca, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:39,765 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzFiZWI2NDktOWYxZjRmYzYtYWU3NjNjLTFhZDZmMTk1
2025-11-25 14:50:39,811 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzFiZWI2NDktOWYxZjRmYzYtYWU3NjNjLTFhZDZmMTk1
2025-11-25 14:50:39,822 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f99ab5fa-949b-421a-a122-840db36edbe3, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:39,825 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f99ab5fa-949b-421a-a122-840db36edbe3, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=YzFiZWI2NDktOWYxZjRmYzYtYWU3NjNjLTFhZDZmMTk1" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)" } stats_mode: STATS_MODE_NONE }
2025-11-25 14:50:39,826 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f99ab5fa-949b-421a-a122-840db36edbe3, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:39,944 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjJhOGMyZmItZjM4ODEzY2YtMjU4ZTMzOTktM2I0OTU4ZmM=
2025-11-25 14:50:39,987 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjJhOGMyZmItZjM4ODEzY2YtMjU4ZTMzOTktM2I0OTU4ZmM=
2025-11-25 14:50:39,989 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a319ea81-3b21-42e3-9e79-316706555d4f, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:39,990 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a319ea81-3b21-42e3-9e79-316706555d4f, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MjJhOGMyZmItZjM4ODEzY2YtMjU4ZTMzOTktM2I0OTU4ZmM=" 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 14:50:39,992 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a319ea81-3b21-42e3-9e79-316706555d4f, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:40,055 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzljNGQ3YTUtOTljZjJhNzYtNWIxODZjYjAtZTRjMjllOTA=
2025-11-25 14:50:40,098 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzljNGQ3YTUtOTljZjJhNzYtNWIxODZjYjAtZTRjMjllOTA=
2025-11-25 14:50:40,099 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6f8f43a3-6dd3-4680-a1ff-3481dd9febb3, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:40,100 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6f8f43a3-6dd3-4680-a1ff-3481dd9febb3, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MzljNGQ3YTUtOTljZjJhNzYtNWIxODZjYjAtZTRjMjllOTA=" 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 14:50:40,102 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6f8f43a3-6dd3-4680-a1ff-3481dd9febb3, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:40,160 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTVhZTVmMGItMTBjNmU2MDItNDljOGJlMmQtNTY4NmZkNjA=
2025-11-25 14:50:40,212 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTVhZTVmMGItMTBjNmU2MDItNDljOGJlMmQtNTY4NmZkNjA=
2025-11-25 14:50:40,212 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 93f7622b-7223-48fb-9821-cefe8f39809c, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:40,214 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 93f7622b-7223-48fb-9821-cefe8f39809c, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MTVhZTVmMGItMTBjNmU2MDItNDljOGJlMmQtNTY4NmZkNjA=" 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 14:50:40,216 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 93f7622b-7223-48fb-9821-cefe8f39809c, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:40,268 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzFlMGJkMDEtZWNhMzk3ZGQtNTgxNzliZTctODc1NmZiNDI=
2025-11-25 14:50:40,316 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzFlMGJkMDEtZWNhMzk3ZGQtNTgxNzliZTctODc1NmZiNDI=
2025-11-25 14:50:40,321 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ed802ee4-3d72-4125-bcf0-122e6abbbedc, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:40,322 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ed802ee4-3d72-4125-bcf0-122e6abbbedc, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NzFlMGJkMDEtZWNhMzk3ZGQtNTgxNzliZTctODc1NmZiNDI=" 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 14:50:40,329 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ed802ee4-3d72-4125-bcf0-122e6abbbedc, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:40,334 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzFhYThlNmQtNTJkZmNjNGQtNjJlZTEyY2EtZDkzMjgyYzU=
2025-11-25 14:50:40,373 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzFhYThlNmQtNTJkZmNjNGQtNjJlZTEyY2EtZDkzMjgyYzU=
2025-11-25 14:50:40,374 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 863157ea-8c97-4ddb-9e59-3e75c06b4a64, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:40,375 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 863157ea-8c97-4ddb-9e59-3e75c06b4a64, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NzFhYThlNmQtNTJkZmNjNGQtNjJlZTEyY2EtZDkzMjgyYzU=" 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 14:50:40,377 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 863157ea-8c97-4ddb-9e59-3e75c06b4a64, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:40,389 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDBjODU1NmMtMmZmYzE2NDAtNDhmMTk3YS1lNDJhMzJiYg==
2025-11-25 14:50:40,429 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDBjODU1NmMtMmZmYzE2NDAtNDhmMTk3YS1lNDJhMzJiYg==
2025-11-25 14:50:40,431 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 10d368d0-1ceb-4743-b865-99832cbaf7d7, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:40,434 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 10d368d0-1ceb-4743-b865-99832cbaf7d7, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=ZDBjODU1NmMtMmZmYzE2NDAtNDhmMTk3YS1lNDJhMzJiYg==" 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 14:50:40,435 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEwNDA3NzctZjk0MjU1MWMtMTQxYjhlYTgtMTEwZDk4NA==
2025-11-25 14:50:40,435 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 10d368d0-1ceb-4743-b865-99832cbaf7d7, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:40,481 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEwNDA3NzctZjk0MjU1MWMtMTQxYjhlYTgtMTEwZDk4NA==
2025-11-25 14:50:40,484 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7e293089-3df1-49a1-99d7-7952c616fcbd, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:40,486 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTVkYThmZDktNzk3NThmYi1mMTMyYjE1LWE4Mzg2NWMx
2025-11-25 14:50:40,491 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7e293089-3df1-49a1-99d7-7952c616fcbd, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NjEwNDA3NzctZjk0MjU1MWMtMTQxYjhlYTgtMTEwZDk4NA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-25 14:50:40,533 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTVkYThmZDktNzk3NThmYi1mMTMyYjE1LWE4Mzg2NWMx
2025-11-25 14:50:40,535 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7e293089-3df1-49a1-99d7-7952c616fcbd, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:40,537 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d710410f-95ae-4187-9611-931f92983eb4, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:40,540 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d710410f-95ae-4187-9611-931f92983eb4, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NTVkYThmZDktNzk3NThmYi1mMTMyYjE1LWE4Mzg2NWMx" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)" } stats_mode: STATS_MODE_NONE }
2025-11-25 14:50:40,542 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d710410f-95ae-4187-9611-931f92983eb4, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:40,543 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQxZjJlODgtOTU4ODNhZTAtNzIyY2QwYS04NzZiYTU2MA==
2025-11-25 14:50:40,608 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQxZjJlODgtOTU4ODNhZTAtNzIyY2QwYS04NzZiYTU2MA==
2025-11-25 14:50:40,608 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 69d6361d-5e7b-4951-803d-38c8c6d71398, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:40,609 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 69d6361d-5e7b-4951-803d-38c8c6d71398, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=OGQxZjJlODgtOTU4ODNhZTAtNzIyY2QwYS04NzZiYTU2MA==" 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 14:50:40,610 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 69d6361d-5e7b-4951-803d-38c8c6d71398, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:42,634 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjJhOGMyZmItZjM4ODEzY2YtMjU4ZTMzOTktM2I0OTU4ZmM=
2025-11-25 14:50:42,634 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjJhOGMyZmItZjM4ODEzY2YtMjU4ZTMzOTktM2I0OTU4ZmM=
2025-11-25 14:50:42,635 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ab6c8884-ff69-4417-a6c0-e0e271cb570b, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:42,636 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ab6c8884-ff69-4417-a6c0-e0e271cb570b, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MjJhOGMyZmItZjM4ODEzY2YtMjU4ZTMzOTktM2I0OTU4ZmM=" 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 14:50:42,637 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ab6c8884-ff69-4417-a6c0-e0e271cb570b, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:42,961 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzljNGQ3YTUtOTljZjJhNzYtNWIxODZjYjAtZTRjMjllOTA=
2025-11-25 14:50:42,962 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzljNGQ3YTUtOTljZjJhNzYtNWIxODZjYjAtZTRjMjllOTA=
2025-11-25 14:50:42,971 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c2f5a31a-91ce-4cbd-b36a-cb70214f4943, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:42,972 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzFiZWI2NDktOWYxZjRmYzYtYWU3NjNjLTFhZDZmMTk1
2025-11-25 14:50:42,973 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjJhOGMyZmItZjM4ODEzY2YtMjU4ZTMzOTktM2I0OTU4ZmM=
2025-11-25 14:50:42,974 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c2f5a31a-91ce-4cbd-b36a-cb70214f4943, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MzljNGQ3YTUtOTljZjJhNzYtNWIxODZjYjAtZTRjMjllOTA=" 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 14:50:42,975 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzFiZWI2NDktOWYxZjRmYzYtYWU3NjNjLTFhZDZmMTk1
2025-11-25 14:50:42,989 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d3b5d6e3-da62-4252-a3e1-6b955e29c114, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:42,990 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d3b5d6e3-da62-4252-a3e1-6b955e29c114, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=YzFiZWI2NDktOWYxZjRmYzYtYWU3NjNjLTFhZDZmMTk1" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(5)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(4))" } stats_mode: STATS_MODE_NONE }
2025-11-25 14:50:42,990 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c2f5a31a-91ce-4cbd-b36a-cb70214f4943, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:42,991 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d3b5d6e3-da62-4252-a3e1-6b955e29c114, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:43,155 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEwNDA3NzctZjk0MjU1MWMtMTQxYjhlYTgtMTEwZDk4NA==
2025-11-25 14:50:43,156 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MjJhOGMyZmItZjM4ODEzY2YtMjU4ZTMzOTktM2I0OTU4ZmM=
2025-11-25 14:50:43,156 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bdf2e110-065b-4e2f-b86a-17b3587f19cb, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:43,157 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bdf2e110-065b-4e2f-b86a-17b3587f19cb, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MjJhOGMyZmItZjM4ODEzY2YtMjU4ZTMzOTktM2I0OTU4ZmM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(1))" } stats_mode: STATS_MODE_NONE }
2025-11-25 14:50:43,161 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bdf2e110-065b-4e2f-b86a-17b3587f19cb, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:43,252 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTVkYThmZDktNzk3NThmYi1mMTMyYjE1LWE4Mzg2NWMx
2025-11-25 14:50:43,254 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjEwNDA3NzctZjk0MjU1MWMtMTQxYjhlYTgtMTEwZDk4NA==
2025-11-25 14:50:43,255 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd442633-d862-46e8-aba0-329d6de5e2b8, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:43,257 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd442633-d862-46e8-aba0-329d6de5e2b8, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NjEwNDA3NzctZjk0MjU1MWMtMTQxYjhlYTgtMTEwZDk4NA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3))" } stats_mode: STATS_MODE_NONE }
2025-11-25 14:50:43,258 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd442633-d862-46e8-aba0-329d6de5e2b8, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:43,266 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzljNGQ3YTUtOTljZjJhNzYtNWIxODZjYjAtZTRjMjllOTA=
2025-11-25 14:50:43,268 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzFiZWI2NDktOWYxZjRmYzYtYWU3NjNjLTFhZDZmMTk1
2025-11-25 14:50:43,283 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQxZjJlODgtOTU4ODNhZTAtNzIyY2QwYS04NzZiYTU2MA==
2025-11-25 14:50:43,284 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NTVkYThmZDktNzk3NThmYi1mMTMyYjE1LWE4Mzg2NWMx
2025-11-25 14:50:43,284 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e6a0b5c8-674c-4a15-87f5-931369fb3ce0, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:43,287 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e6a0b5c8-674c-4a15-87f5-931369fb3ce0, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NTVkYThmZDktNzk3NThmYi1mMTMyYjE1LWE4Mzg2NWMx" 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 14:50:43,289 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e6a0b5c8-674c-4a15-87f5-931369fb3ce0, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:43,349 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTVhZTVmMGItMTBjNmU2MDItNDljOGJlMmQtNTY4NmZkNjA=
2025-11-25 14:50:43,350 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MzljNGQ3YTUtOTljZjJhNzYtNWIxODZjYjAtZTRjMjllOTA=
2025-11-25 14:50:43,350 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b2fbc474-bd19-40a6-a028-67c98bf5523c, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:43,352 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b2fbc474-bd19-40a6-a028-67c98bf5523c, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MzljNGQ3YTUtOTljZjJhNzYtNWIxODZjYjAtZTRjMjllOTA=" 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 14:50:43,353 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b2fbc474-bd19-40a6-a028-67c98bf5523c, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:43,420 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzFhYThlNmQtNTJkZmNjNGQtNjJlZTEyY2EtZDkzMjgyYzU=
2025-11-25 14:50:43,421 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzFiZWI2NDktOWYxZjRmYzYtYWU3NjNjLTFhZDZmMTk1
2025-11-25 14:50:43,421 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2baf0f0c-2fec-4627-bb1d-e98b0e0be6fa, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:43,422 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2baf0f0c-2fec-4627-bb1d-e98b0e0be6fa, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=YzFiZWI2NDktOWYxZjRmYzYtYWU3NjNjLTFhZDZmMTk1" 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 14:50:43,422 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2baf0f0c-2fec-4627-bb1d-e98b0e0be6fa, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:43,432 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzFlMGJkMDEtZWNhMzk3ZGQtNTgxNzliZTctODc1NmZiNDI=
2025-11-25 14:50:43,432 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQxZjJlODgtOTU4ODNhZTAtNzIyY2QwYS04NzZiYTU2MA==
2025-11-25 14:50:43,433 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c74cd129-c1c5-4f82-8d37-e8cb4a8dbb63, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:43,433 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c74cd129-c1c5-4f82-8d37-e8cb4a8dbb63, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=OGQxZjJlODgtOTU4ODNhZTAtNzIyY2QwYS04NzZiYTU2MA==" 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 14:50:43,434 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c74cd129-c1c5-4f82-8d37-e8cb4a8dbb63, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:43,442 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDBjODU1NmMtMmZmYzE2NDAtNDhmMTk3YS1lNDJhMzJiYg==
2025-11-25 14:50:43,445 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTVhZTVmMGItMTBjNmU2MDItNDljOGJlMmQtNTY4NmZkNjA=
2025-11-25 14:50:43,445 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ddbcf607-9485-4f04-8e45-5a9b12372b09, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:43,446 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ddbcf607-9485-4f04-8e45-5a9b12372b09, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MTVhZTVmMGItMTBjNmU2MDItNDljOGJlMmQtNTY4NmZkNjA=" 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 14:50:43,447 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ddbcf607-9485-4f04-8e45-5a9b12372b09, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:43,527 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MjJhOGMyZmItZjM4ODEzY2YtMjU4ZTMzOTktM2I0OTU4ZmM=
2025-11-25 14:50:43,575 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjEwNDA3NzctZjk0MjU1MWMtMTQxYjhlYTgtMTEwZDk4NA==
2025-11-25 14:50:43,577 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NTVkYThmZDktNzk3NThmYi1mMTMyYjE1LWE4Mzg2NWMx
2025-11-25 14:50:43,578 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MzljNGQ3YTUtOTljZjJhNzYtNWIxODZjYjAtZTRjMjllOTA=
2025-11-25 14:50:43,626 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzFiZWI2NDktOWYxZjRmYzYtYWU3NjNjLTFhZDZmMTk1
2025-11-25 14:50:43,705 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTVhZTVmMGItMTBjNmU2MDItNDljOGJlMmQtNTY4NmZkNjA=
2025-11-25 14:50:43,709 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQxZjJlODgtOTU4ODNhZTAtNzIyY2QwYS04NzZiYTU2MA==
2025-11-25 14:50:43,717 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzFhYThlNmQtNTJkZmNjNGQtNjJlZTEyY2EtZDkzMjgyYzU=
2025-11-25 14:50:43,718 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 39fec805-0cc1-4075-82f4-8c30684e81da, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:43,719 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 39fec805-0cc1-4075-82f4-8c30684e81da, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NzFhYThlNmQtNTJkZmNjNGQtNjJlZTEyY2EtZDkzMjgyYzU=" 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 14:50:43,719 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 39fec805-0cc1-4075-82f4-8c30684e81da, ghrun-n7olz6xni4.auto.internal:3598): response = { <_MultiThreadedRendezvous object> }
2025-11-25 14:50:44,773 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzFhYThlNmQtNTJkZmNjNGQtNjJlZTEyY2EtZDkzMjgyYzU=
2025-11-25 14:50:44,774 - INFO - root - test_log_uniform: check insert: 9638 9916
2025-11-25 14:50:44,776 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.16
2025-11-25 14:50:44,778 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.16
2025-11-25 14:50:44,779 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/g0y6/0007d0/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:3598', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-11-25 14:50:44,780 - DEBUG - ya.test - execute: Command pid: 3772591
2025-11-25 14:50:44,940 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) rc: 0
2025-11-25 14:50:44,940 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) elapsed time (sec): 0.15919017791748047
2025-11-25 14:50:44,940 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) idrss: 0
2025-11-25 14:50:44,941 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) inblock: 0
2025-11-25 14:50:44,941 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) isrss: 0
2025-11-25 14:50:44,942 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) ixrss: 0
2025-11-25 14:50:44,942 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) majflt: 0
2025-11-25 14:50:44,943 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) maxrss: 531328
2025-11-25 14:50:44,943 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) minflt: 3479
2025-11-25 14:50:44,943 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) msgrcv: 0
2025-11-25 14:50:44,943 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) msgsnd: 0
2025-11-25 14:50:44,943 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) nivcsw: 14
2025-11-25 14:50:44,943 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) nsignals: 0
2025-11-25 14:50:44,944 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) nswap: 0
2025-11-25 14:50:44,944 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) nvcsw: 164
2025-11-25 14:50:44,944 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) oublock: 8
2025-11-25 14:50:44,944 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) stime: 0.032182999999999996
2025-11-25 14:50:44,944 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) utime: 0.046486
2025-11-25 14:50:44,945 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) wtime: 0.16
2025-11-25 14:50:44,945 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) output:
Remove path log...
Remove path log...Ok

2025-11-25 14:50:44,945 - DEBUG - ya.test - _save_outputs: Command (pid 3772591) errors:

2025-11-25 14:50:44,948 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-11-25 14:50:44,949 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, b55b85e9-5c9e-4a39-ad34-fa12990e12a7, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:44,950 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, b55b85e9-5c9e-4a39-ad34-fa12990e12a7, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NzFlMGJkMDEtZWNhMzk3ZGQtNTgxNzliZTctODc1NmZiNDI=" }
2025-11-25 14:50:44,993 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, b55b85e9-5c9e-4a39-ad34-fa12990e12a7, ghrun-n7olz6xni4.auto.internal:3598): response = { status: SUCCESS }
2025-11-25 14:50:44,994 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 8058e353-a25e-4748-8b44-8a1a4aeaa9db, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:44,995 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 8058e353-a25e-4748-8b44-8a1a4aeaa9db, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=ZDBjODU1NmMtMmZmYzE2NDAtNDhmMTk3YS1lNDJhMzJiYg==" }
2025-11-25 14:50:45,005 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 8058e353-a25e-4748-8b44-8a1a4aeaa9db, ghrun-n7olz6xni4.auto.internal:3598): response = { status: SUCCESS }
2025-11-25 14:50:45,006 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 83f4b5e6-7e14-4987-9ff5-aafbff43a7de, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:45,008 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 83f4b5e6-7e14-4987-9ff5-aafbff43a7de, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MjJhOGMyZmItZjM4ODEzY2YtMjU4ZTMzOTktM2I0OTU4ZmM=" }
2025-11-25 14:50:45,011 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 83f4b5e6-7e14-4987-9ff5-aafbff43a7de, ghrun-n7olz6xni4.auto.internal:3598): response = { status: SUCCESS }
2025-11-25 14:50:45,012 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 6eb1d712-8cb2-4f03-b3e7-4992b1ac91f9, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:45,013 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 6eb1d712-8cb2-4f03-b3e7-4992b1ac91f9, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NjEwNDA3NzctZjk0MjU1MWMtMTQxYjhlYTgtMTEwZDk4NA==" }
2025-11-25 14:50:45,015 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 6eb1d712-8cb2-4f03-b3e7-4992b1ac91f9, ghrun-n7olz6xni4.auto.internal:3598): response = { status: SUCCESS }
2025-11-25 14:50:45,021 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, af185643-f9aa-4c0e-b468-95cd3024cfac, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:45,021 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, af185643-f9aa-4c0e-b468-95cd3024cfac, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NTVkYThmZDktNzk3NThmYi1mMTMyYjE1LWE4Mzg2NWMx" }
2025-11-25 14:50:45,024 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, af185643-f9aa-4c0e-b468-95cd3024cfac, ghrun-n7olz6xni4.auto.internal:3598): response = { status: SUCCESS }
2025-11-25 14:50:45,025 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, d7b3f9d5-9c9f-41df-89d8-9268c9d5189e, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:45,025 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, d7b3f9d5-9c9f-41df-89d8-9268c9d5189e, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MzljNGQ3YTUtOTljZjJhNzYtNWIxODZjYjAtZTRjMjllOTA=" }
2025-11-25 14:50:45,027 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, d7b3f9d5-9c9f-41df-89d8-9268c9d5189e, ghrun-n7olz6xni4.auto.internal:3598): response = { status: SUCCESS }
2025-11-25 14:50:45,029 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, f1e7ff0c-7ea9-4bac-b6dc-867f7266528b, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:45,031 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, f1e7ff0c-7ea9-4bac-b6dc-867f7266528b, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=YzFiZWI2NDktOWYxZjRmYzYtYWU3NjNjLTFhZDZmMTk1" }
2025-11-25 14:50:45,032 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, f1e7ff0c-7ea9-4bac-b6dc-867f7266528b, ghrun-n7olz6xni4.auto.internal:3598): response = { status: SUCCESS }
2025-11-25 14:50:45,043 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 35c477f5-826f-41e4-8665-ed3e14cf076a, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:45,043 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 35c477f5-826f-41e4-8665-ed3e14cf076a, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=MTVhZTVmMGItMTBjNmU2MDItNDljOGJlMmQtNTY4NmZkNjA=" }
2025-11-25 14:50:45,045 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 35c477f5-826f-41e4-8665-ed3e14cf076a, ghrun-n7olz6xni4.auto.internal:3598): response = { status: SUCCESS }
2025-11-25 14:50:45,047 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, a1a320d3-b121-4773-ba20-5a0361f02ade, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:45,049 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, a1a320d3-b121-4773-ba20-5a0361f02ade, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=OGQxZjJlODgtOTU4ODNhZTAtNzIyY2QwYS04NzZiYTU2MA==" }
2025-11-25 14:50:45,051 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, a1a320d3-b121-4773-ba20-5a0361f02ade, ghrun-n7olz6xni4.auto.internal:3598): response = { status: SUCCESS }
2025-11-25 14:50:45,053 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, e19cdfe6-9aef-4355-b726-f6b1a39564d0, ghrun-n7olz6xni4.auto.internal:3598): creating call state
2025-11-25 14:50:45,054 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, e19cdfe6-9aef-4355-b726-f6b1a39564d0, ghrun-n7olz6xni4.auto.internal:3598): request = { session_id: "ydb://session/3?node_id=1&id=NzFhYThlNmQtNTJkZmNjNGQtNjJlZTEyY2EtZDkzMjgyYzU=" }
2025-11-25 14:50:45,055 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, e19cdfe6-9aef-4355-b726-f6b1a39564d0, ghrun-n7olz6xni4.auto.internal:3598): response = { status: SUCCESS }
2025-11-25 14:50:45,058 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-11-25 14:50:45,066 - DEBUG - ydb.topic - close: Close topic client
2025-11-25 14:50:45,066 - DEBUG - ydb.topic - close: Topic client was closed
2025-11-25 14:50:45,067 - INFO - ydb.connection - close: Closing channel for endpoint localhost:3598
2025-11-25 14:50:45,067 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-n7olz6xni4.auto.internal:3598
2025-11-25 14:50:45,073 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-11-25 14:50:52,077 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:3598/1
2025-11-25 14:50:52,079 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/6082
2025-11-25 14:50:52,079 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2095
2025-11-25 14:50:52,079 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/2973
2025-11-25 14:50:52,080 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/3598
2025-11-25 14:50:52,080 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11771
