2025-10-14 15:19:36,246 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-14 15:19:36,246 - INFO - ya.test - pytest_runtest_setup: test_log_uniform
2025-10-14 15:19:36,246 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-14 15:19:36,247 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-14 15:19:36,248 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_uniform)
2025-10-14 15:19:36,248 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/0sfb/00077a/ydb/apps/ydb/ydb
2025-10-14 15:19:36,248 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/0sfb/00077a/ydb/apps/ydb/ydb -e grpc://localhost:15118 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-10-14 15:19:36,250 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.11
2025-10-14 15:19:36,251 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.11
2025-10-14 15:19:36,251 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/0sfb/00077a/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:15118', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-10-14 15:19:36,256 - DEBUG - ya.test - execute: Command pid: 3720129
2025-10-14 15:19:36,859 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) rc: 0
2025-10-14 15:19:36,859 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) elapsed time (sec): 0.6016201972961426
2025-10-14 15:19:36,859 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) idrss: 0
2025-10-14 15:19:36,860 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) inblock: 0
2025-10-14 15:19:36,860 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) isrss: 0
2025-10-14 15:19:36,860 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) ixrss: 0
2025-10-14 15:19:36,860 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) majflt: 0
2025-10-14 15:19:36,860 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) maxrss: 819672
2025-10-14 15:19:36,860 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) minflt: 3471
2025-10-14 15:19:36,861 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) msgrcv: 0
2025-10-14 15:19:36,866 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) msgsnd: 0
2025-10-14 15:19:36,866 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) nivcsw: 28
2025-10-14 15:19:36,866 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) nsignals: 0
2025-10-14 15:19:36,866 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) nswap: 0
2025-10-14 15:19:36,866 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) nvcsw: 169
2025-10-14 15:19:36,866 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) oublock: 8
2025-10-14 15:19:36,872 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) stime: 0.049164
2025-10-14 15:19:36,872 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) utime: 0.030254999999999997
2025-10-14 15:19:36,873 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) wtime: 0.607
2025-10-14 15:19:36,874 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) output:
Init tables ...
Init tables ...Ok

2025-10-14 15:19:36,874 - DEBUG - ya.test - _save_outputs: Command (pid 3720129) errors:

2025-10-14 15:19:36,875 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-10-14 15:19:36,877 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/0sfb/00077a/ydb/apps/ydb/ydb -e grpc://localhost:15118 -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-10-14 15:19:36,881 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.12
2025-10-14 15:19:36,888 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.12
2025-10-14 15:19:36,890 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/0sfb/00077a/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:15118', '-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-10-14 15:19:36,894 - DEBUG - ya.test - execute: Command pid: 3720585
2025-10-14 15:19:51,179 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-14 15:19:51,180 - 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-10-14 15:19:51,198 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-14 15:19:51,222 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 594d51e5-05e4-4a38-8350-244d33f6c70b, localhost:15118): creating call state
2025-10-14 15:19:51,222 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 594d51e5-05e4-4a38-8350-244d33f6c70b, localhost:15118): request = { database: "/Root" }
2025-10-14 15:19:51,228 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 594d51e5-05e4-4a38-8350-244d33f6c70b, localhost:15118): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-3ux3yv5ocu.auto.internal" port: 15118 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-10-14 15:19:51,246 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-3ux3yv5ocu.auto.internal:15118, location 1, ssl: False>]>
2025-10-14 15:19:51,246 - INFO - ydb.connection - close: Closing channel for endpoint localhost:15118
2025-10-14 15:20:07,094 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-14 15:20:07,095 - DEBUG - ya.test - _save_outputs: Command (pid 3720585) rc: 0
2025-10-14 15:20:07,095 - DEBUG - ya.test - _save_outputs: Command (pid 3720585) elapsed time (sec): 30.200291633605957
2025-10-14 15:20:07,097 - DEBUG - ya.test - _save_outputs: Command (pid 3720585) wtime: 30.205
2025-10-14 15:20:07,097 - DEBUG - ya.test - _save_outputs: Command (pid 3720585) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	    422	422	0	0	20	41	53	60
2	    374	374	0	0	24	47	58	68
3	    380	380	0	0	22	52	70	83
4	    442	442	0	0	20	40	46	51
5	    443	443	0	0	21	34	43	47
6	    370	370	0	0	23	56	65	77
7	    383	383	0	0	22	52	58	62
8	    426	426	0	0	20	42	53	57
9	    356	356	0	0	26	47	59	67
10	    428	428	0	0	22	38	41	44
11	    433	433	0	0	20	42	68	88
12	    455	455	0	0	19	40	54	65
13	    645	645	0	0	14	25	32	35
14	    659	659	0	0	14	22	28	33
15	    574	574	0	0	14	32	46	52
16	    672	672	0	0	13	23	33	50
17	    599	599	0	0	15	25	28	32
18	    561	561	0	0	15	32	46	51
19	    660	660	0	0	13	26	37	53
20	    605	605	0	0	14	26	47	52
21	    478	478	0	0	18	39	47	59
22	    516	516	0	0	17	36	44	47
23	    648	648	0	0	15	23	27	33
24	    732	732	0	0	13	19	26	33
25	    667	667	0	0	14	22	27	33
26	    630	630	0	0	14	27	32	39
27	    694	694	0	0	13	22	34	45
28	    835	835	0	0	10	17	23	28
29	    569	569	0	0	15	31	40	46
30	    659	659	0	0	14	25	29	36

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	  16315	543.833	0	0	16	35	50	88

2025-10-14 15:20:07,097 - DEBUG - ya.test - _save_outputs: Command (pid 3720585) errors:

2025-10-14 15:20:07,098 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:20:07,098 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dec83df0-6ff9-46fd-a264-c70714aac7d9, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:20:07,100 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dec83df0-6ff9-46fd-a264-c70714aac7d9, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:20:07,102 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dec83df0-6ff9-46fd-a264-c70714aac7d9, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:20:14,686 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:20:14,687 - INFO - root - test_log_uniform: Count rows after insert 16304 before wait
2025-10-14 15:20:14,687 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:20:14,687 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2da9c020-c174-48c6-990c-a406c7dcfb6f, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:20:14,688 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2da9c020-c174-48c6-990c-a406c7dcfb6f, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:20:14,690 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2da9c020-c174-48c6-990c-a406c7dcfb6f, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:20:21,075 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:20:21,076 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:20:21,076 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 82ce3e5a-7fe3-42f7-8d71-9103947694d4, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:20:21,077 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 82ce3e5a-7fe3-42f7-8d71-9103947694d4, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:20:21,077 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 82ce3e5a-7fe3-42f7-8d71-9103947694d4, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:20:27,391 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:20:27,394 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-10-14 15:20:27,395 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-10-14 15:20:27,395 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-10-14 15:20:27,396 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=
2025-10-14 15:20:27,405 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/0sfb/00077a/ydb/apps/ydb/ydb -e grpc://localhost:15118 -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-10-14 15:20:27,406 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/0sfb/00077a/ydb/apps/ydb/ydb -e grpc://localhost:15118 -d /Root workload log --path log run insert --seconds 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-10-14 15:20:27,406 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/0sfb/00077a/ydb/apps/ydb/ydb -e grpc://localhost:15118 -d /Root workload log --path log run upsert --seconds 30 --threads 10 --rows 1 --date-from 0 --date-to 2678400000 with wait = True
2025-10-14 15:20:27,408 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ce05cba4-a106-4864-81da-8e42891e5e67, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:20:27,408 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm
2025-10-14 15:20:27,411 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.13
2025-10-14 15:20:27,414 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ce05cba4-a106-4864-81da-8e42891e5e67, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-14 15:20:27,415 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.14
2025-10-14 15:20:27,415 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c009f354-2828-4f73-b307-9239159ebc6a, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:20:27,416 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:20:27,417 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.15
2025-10-14 15:20:27,418 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c009f354-2828-4f73-b307-9239159ebc6a, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-14 15:20:27,419 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ce05cba4-a106-4864-81da-8e42891e5e67, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:20:27,420 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a9545587-f25a-4b46-9097-e4375fb7a4b0, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:20:27,421 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==
2025-10-14 15:20:27,423 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.13
2025-10-14 15:20:27,441 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/0sfb/00077a/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:15118', '-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-10-14 15:20:27,426 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.14
2025-10-14 15:20:27,427 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a9545587-f25a-4b46-9097-e4375fb7a4b0, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-14 15:20:27,428 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.15
2025-10-14 15:20:27,428 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 28517675-25d0-40c7-87cb-58addfa9ba31, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:20:27,429 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:20:27,425 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c009f354-2828-4f73-b307-9239159ebc6a, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:20:27,452 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 28517675-25d0-40c7-87cb-58addfa9ba31, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-14 15:20:27,450 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/0sfb/00077a/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:15118', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'insert', '--seconds', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-10-14 15:20:27,451 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a9545587-f25a-4b46-9097-e4375fb7a4b0, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:20:27,449 - DEBUG - ya.test - execute: Command pid: 3761931
2025-10-14 15:20:27,453 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6da5801c-5c03-41da-a22f-e5fe0eb5c4bb, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:20:27,454 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/0sfb/00077a/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:15118', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'upsert', '--seconds', '30', '--threads', '10', '--rows', '1', '--date-from', '0', '--date-to', '2678400000']' in 'None' (waiting)
2025-10-14 15:20:27,454 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:20:27,457 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 28517675-25d0-40c7-87cb-58addfa9ba31, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:20:27,464 - DEBUG - ya.test - execute: Command pid: 3761945
2025-10-14 15:20:27,463 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6da5801c-5c03-41da-a22f-e5fe0eb5c4bb, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-14 15:20:27,459 - DEBUG - ya.test - execute: Command pid: 3761938
2025-10-14 15:20:27,465 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4432ce2d-157b-48b2-af39-9f9ce1950464, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:20:27,465 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjA5ZTdiNjctMjA4ZTA5NGMtMjlhOTBhMy0yYTdjYmVjNg==
2025-10-14 15:20:27,467 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6da5801c-5c03-41da-a22f-e5fe0eb5c4bb, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:20:27,469 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 320de45d-1b3e-4c81-bfcb-516fed268725, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:20:27,469 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4432ce2d-157b-48b2-af39-9f9ce1950464, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-14 15:20:27,470 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:20:27,471 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 320de45d-1b3e-4c81-bfcb-516fed268725, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NjA5ZTdiNjctMjA4ZTA5NGMtMjlhOTBhMy0yYTdjYmVjNg==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-14 15:20:27,472 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4432ce2d-157b-48b2-af39-9f9ce1950464, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:20:27,473 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ad41c5dc-e3cb-4a35-b20a-3518885189c3, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:20:27,474 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:20:27,475 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 320de45d-1b3e-4c81-bfcb-516fed268725, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:20:27,481 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ad41c5dc-e3cb-4a35-b20a-3518885189c3, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-14 15:20:27,482 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f0e41def-10bc-47cf-ba63-0e56166fb80d, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:20:27,482 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:20:27,485 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ad41c5dc-e3cb-4a35-b20a-3518885189c3, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:20:27,485 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f0e41def-10bc-47cf-ba63-0e56166fb80d, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-14 15:20:27,498 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f0e41def-10bc-47cf-ba63-0e56166fb80d, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:20:27,496 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 47becebe-fa46-48ef-b7a4-c2c8cf420089, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:20:27,498 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 47becebe-fa46-48ef-b7a4-c2c8cf420089, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-14 15:20:27,499 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 47becebe-fa46-48ef-b7a4-c2c8cf420089, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:00,459 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-14 15:21:00,459 - DEBUG - ya.test - _save_outputs: Command (pid 3761945) rc: 0
2025-10-14 15:21:00,459 - DEBUG - ya.test - _save_outputs: Command (pid 3761945) elapsed time (sec): 32.99285078048706
2025-10-14 15:21:00,459 - DEBUG - ya.test - _save_outputs: Command (pid 3761945) wtime: 32.996
2025-10-14 15:21:00,460 - DEBUG - ya.test - _save_outputs: Command (pid 3761945) 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	0	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	2	0	0	0	0
8	      0	0	0	8	0	0	0	0
9	      0	0	0	0	0	0	0	0
10	      0	0	0	0	0	0	0	0
11	      0	0	0	0	0	0	0	0
12	      0	0	0	10	0	0	0	0
13	      0	0	0	2	0	0	0	0
14	      0	0	0	8	0	0	0	0
15	      0	0	0	10	0	0	0	0
16	      0	0	0	0	0	0	0	0
17	      0	0	0	0	0	0	0	0
18	      0	0	0	10	0	0	0	0
19	      0	0	0	0	0	0	0	0
20	      0	0	0	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	0	0	0	0	0
27	      0	0	0	10	0	0	0	0
28	      0	0	0	0	0	0	0	0
29	      0	0	0	0	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	130	0	0	0	0

2025-10-14 15:21:00,460 - DEBUG - ya.test - _save_outputs: Command (pid 3761945) errors:

2025-10-14 15:21:00,497 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-14 15:21:00,498 - DEBUG - ya.test - _save_outputs: Command (pid 3761938) rc: 0
2025-10-14 15:21:00,498 - DEBUG - ya.test - _save_outputs: Command (pid 3761938) elapsed time (sec): 33.02943253517151
2025-10-14 15:21:00,498 - DEBUG - ya.test - _save_outputs: Command (pid 3761938) wtime: 33.039
2025-10-14 15:21:00,498 - DEBUG - ya.test - _save_outputs: Command (pid 3761938) 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	0	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	4	0	0	0	0
8	      0	0	0	6	0	0	0	0
9	      0	0	0	0	0	0	0	0
10	      0	0	0	0	0	0	0	0
11	      0	0	0	0	0	0	0	0
12	      0	0	0	10	0	0	0	0
13	      0	0	0	4	0	0	0	0
14	      0	0	0	6	0	0	0	0
15	      0	0	0	10	0	0	0	0
16	      0	0	0	0	0	0	0	0
17	      0	0	0	0	0	0	0	0
18	      0	0	0	10	0	0	0	0
19	      0	0	0	0	0	0	0	0
20	      0	0	0	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	7	0	0	0	0
24	      0	0	0	3	0	0	0	0
25	      0	0	0	10	0	0	0	0
26	      0	0	0	0	0	0	0	0
27	      0	0	0	10	0	0	0	0
28	      0	0	0	0	0	0	0	0
29	      0	0	0	0	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	130	0	0	0	0

2025-10-14 15:21:00,498 - DEBUG - ya.test - _save_outputs: Command (pid 3761938) errors:

2025-10-14 15:21:00,885 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-14 15:21:00,886 - DEBUG - ya.test - _save_outputs: Command (pid 3761931) rc: 0
2025-10-14 15:21:00,887 - DEBUG - ya.test - _save_outputs: Command (pid 3761931) elapsed time (sec): 33.425806760787964
2025-10-14 15:21:00,887 - DEBUG - ya.test - _save_outputs: Command (pid 3761931) wtime: 33.444
2025-10-14 15:21:00,887 - DEBUG - ya.test - _save_outputs: Command (pid 3761931) 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	      2	2	0	0	6815	6943	6943	6943
8	      0	0	0	0	0	0	0	0
9	      0	0	0	0	0	0	0	0
10	      0	0	0	0	0	0	0	0
11	      5	5	0	0	10431	11007	11007	11007
12	      2	2	0	0	11199	11839	11839	11839
13	      1	1	0	0	12031	12031	12031	12031
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	      0	0	0	0	0	0	0	0
22	      0	0	0	0	0	0	0	0
23	      0	0	0	0	0	0	0	0
24	      0	0	0	0	0	0	0	0
25	      0	0	0	0	0	0	0	0
26	      0	0	0	0	0	0	0	0
27	      2	2	0	0	19711	19839	19839	19839
28	      0	0	0	0	0	0	0	0
29	      0	0	0	0	0	0	0	0
30	      8	8	0	2	19967	20863	20863	20863

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
30	     20	0.666667	0	2	11199	20863	20863	20863

2025-10-14 15:21:00,887 - DEBUG - ya.test - _save_outputs: Command (pid 3761931) errors:

2025-10-14 15:21:31,280 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:21:31,281 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:21:31,282 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c9253f48-c317-4c01-91f6-63533006fce1, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:31,282 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c9253f48-c317-4c01-91f6-63533006fce1, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=" 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-10-14 15:21:31,283 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c9253f48-c317-4c01-91f6-63533006fce1, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:31,335 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:21:31,336 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:21:31,336 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 285fddb7-0b76-41d9-8c83-021ceba838da, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:31,336 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 285fddb7-0b76-41d9-8c83-021ceba838da, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=" 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-10-14 15:21:31,337 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 285fddb7-0b76-41d9-8c83-021ceba838da, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:31,573 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=
2025-10-14 15:21:31,582 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=
2025-10-14 15:21:31,585 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 75c5bf4a-2e15-497c-b5ab-5a581cd14d5e, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:31,587 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 75c5bf4a-2e15-497c-b5ab-5a581cd14d5e, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=" 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-10-14 15:21:31,601 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 75c5bf4a-2e15-497c-b5ab-5a581cd14d5e, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:31,618 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:21:31,619 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm
2025-10-14 15:21:31,633 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:21:31,642 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ada91917-69d8-4b04-ad0f-ab3bf09779f2, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:31,653 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ada91917-69d8-4b04-ad0f-ab3bf09779f2, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=" 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-10-14 15:21:31,660 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm
2025-10-14 15:21:31,663 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b7dfa59e-cafe-482b-a6d6-bed3f1460dcb, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:31,663 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ada91917-69d8-4b04-ad0f-ab3bf09779f2, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:31,665 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b7dfa59e-cafe-482b-a6d6-bed3f1460dcb, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm" 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-10-14 15:21:31,665 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:21:31,682 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b7dfa59e-cafe-482b-a6d6-bed3f1460dcb, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:31,690 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:21:31,719 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0b03489e-7bc0-4e45-81f5-54cd61521c7f, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:31,723 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==
2025-10-14 15:21:31,731 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0b03489e-7bc0-4e45-81f5-54cd61521c7f, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==" 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-10-14 15:21:31,753 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==
2025-10-14 15:21:31,757 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d8c43ddb-4175-4912-8e5f-122c6024a150, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:31,758 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d8c43ddb-4175-4912-8e5f-122c6024a150, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==" 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-10-14 15:21:31,759 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:21:31,760 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:21:31,760 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c956cb94-029a-4afe-ad67-d2cefa3e023f, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:31,761 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0b03489e-7bc0-4e45-81f5-54cd61521c7f, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:31,762 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c956cb94-029a-4afe-ad67-d2cefa3e023f, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=" 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-10-14 15:21:31,763 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d8c43ddb-4175-4912-8e5f-122c6024a150, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:31,775 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c956cb94-029a-4afe-ad67-d2cefa3e023f, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:31,776 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:21:31,778 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:21:31,784 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3933ce1d-2651-4908-8f92-0a2d8151546c, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:31,784 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3933ce1d-2651-4908-8f92-0a2d8151546c, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=" 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-10-14 15:21:31,805 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3933ce1d-2651-4908-8f92-0a2d8151546c, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:31,829 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjA5ZTdiNjctMjA4ZTA5NGMtMjlhOTBhMy0yYTdjYmVjNg==
2025-10-14 15:21:31,831 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjA5ZTdiNjctMjA4ZTA5NGMtMjlhOTBhMy0yYTdjYmVjNg==
2025-10-14 15:21:31,854 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1f9da3d5-da07-4480-bb47-cce545be1cbe, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:31,863 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1f9da3d5-da07-4480-bb47-cce545be1cbe, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NjA5ZTdiNjctMjA4ZTA5NGMtMjlhOTBhMy0yYTdjYmVjNg==" 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-10-14 15:21:31,871 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1f9da3d5-da07-4480-bb47-cce545be1cbe, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:32,103 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:21:32,123 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:21:32,133 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, de58d685-a3aa-49e5-9bfa-11e688399090, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:32,133 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, de58d685-a3aa-49e5-9bfa-11e688399090, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=" 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-10-14 15:21:32,142 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, de58d685-a3aa-49e5-9bfa-11e688399090, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:32,177 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:21:32,190 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:21:32,191 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2137e2aa-f83e-4f82-a47f-626e60ac33db, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:32,192 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2137e2aa-f83e-4f82-a47f-626e60ac33db, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=" 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-10-14 15:21:32,217 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2137e2aa-f83e-4f82-a47f-626e60ac33db, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:32,731 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=
2025-10-14 15:21:32,752 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=
2025-10-14 15:21:32,758 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e00f7467-bc06-4cfb-97ad-9ee842ba2f91, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:32,766 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e00f7467-bc06-4cfb-97ad-9ee842ba2f91, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=" 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-10-14 15:21:32,785 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e00f7467-bc06-4cfb-97ad-9ee842ba2f91, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,117 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:21:33,124 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=
2025-10-14 15:21:33,127 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:21:33,133 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:21:33,136 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:21:33,151 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 09ecfd75-1ea5-4fb3-95d5-46e6cf555c7f, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,151 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=
2025-10-14 15:21:33,175 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 09ecfd75-1ea5-4fb3-95d5-46e6cf555c7f, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=" 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-10-14 15:21:33,187 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 590e23a3-2bc4-49c9-a091-623c34146b35, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,200 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:21:33,207 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 590e23a3-2bc4-49c9-a091-623c34146b35, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=" 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-10-14 15:21:33,210 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 09ecfd75-1ea5-4fb3-95d5-46e6cf555c7f, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,210 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm
2025-10-14 15:21:33,209 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c6da3b5a-d016-43f6-ac63-e151e28a0cad, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,227 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:21:33,228 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 590e23a3-2bc4-49c9-a091-623c34146b35, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,228 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c6da3b5a-d016-43f6-ac63-e151e28a0cad, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=" 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-10-14 15:21:33,229 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1902029b-cb2e-4601-85c2-67c6bae5c042, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,230 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm
2025-10-14 15:21:33,249 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1902029b-cb2e-4601-85c2-67c6bae5c042, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=" 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-10-14 15:21:33,255 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, df73a43f-d9ff-49b9-82e6-1001de5fe88e, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,258 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, df73a43f-d9ff-49b9-82e6-1001de5fe88e, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm" 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-10-14 15:21:33,259 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c6da3b5a-d016-43f6-ac63-e151e28a0cad, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,268 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1902029b-cb2e-4601-85c2-67c6bae5c042, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,277 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, df73a43f-d9ff-49b9-82e6-1001de5fe88e, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,323 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:21:33,353 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:21:33,354 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2a72f695-9756-422a-82e2-7e078788731c, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,354 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2a72f695-9756-422a-82e2-7e078788731c, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==" 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-10-14 15:21:33,363 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2a72f695-9756-422a-82e2-7e078788731c, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,411 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==
2025-10-14 15:21:33,427 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==
2025-10-14 15:21:33,428 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 447a64b5-a940-4802-814f-3649fada4bf8, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,428 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:21:33,431 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 447a64b5-a940-4802-814f-3649fada4bf8, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==" 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-10-14 15:21:33,434 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:21:33,435 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 447a64b5-a940-4802-814f-3649fada4bf8, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,457 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:21:33,458 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:21:33,459 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c8c1752d-a9f7-4761-b538-24ab14fef9cf, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,460 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:21:33,461 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=
2025-10-14 15:21:33,477 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:21:33,478 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c8c1752d-a9f7-4761-b538-24ab14fef9cf, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=" 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-10-14 15:21:33,479 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjA5ZTdiNjctMjA4ZTA5NGMtMjlhOTBhMy0yYTdjYmVjNg==
2025-10-14 15:21:33,481 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ca4ee2c3-29f6-413e-aeee-496b7f49d852, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,481 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm
2025-10-14 15:21:33,500 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:21:33,500 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ca4ee2c3-29f6-413e-aeee-496b7f49d852, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=" 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-10-14 15:21:33,500 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:21:33,502 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c8c1752d-a9f7-4761-b538-24ab14fef9cf, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,503 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e2f8d725-9193-4b02-bedf-b4ddd68b8f00, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,504 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e2f8d725-9193-4b02-bedf-b4ddd68b8f00, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=" 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-10-14 15:21:33,506 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:21:33,507 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 463537ea-f16a-47a7-bdb6-63bffdb6244c, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,507 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=
2025-10-14 15:21:33,507 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 463537ea-f16a-47a7-bdb6-63bffdb6244c, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=" 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-10-14 15:21:33,508 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ca4ee2c3-29f6-413e-aeee-496b7f49d852, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,508 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 14a5f56e-bd12-4093-8324-632b2fd3c1a2, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,509 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:21:33,510 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e2f8d725-9193-4b02-bedf-b4ddd68b8f00, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,511 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 14a5f56e-bd12-4093-8324-632b2fd3c1a2, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=" 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-10-14 15:21:33,511 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 463537ea-f16a-47a7-bdb6-63bffdb6244c, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,512 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjA5ZTdiNjctMjA4ZTA5NGMtMjlhOTBhMy0yYTdjYmVjNg==
2025-10-14 15:21:33,514 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 14a5f56e-bd12-4093-8324-632b2fd3c1a2, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,514 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c2811e36-0b68-48c2-b801-b15debd6b9fd, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,515 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c2811e36-0b68-48c2-b801-b15debd6b9fd, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NjA5ZTdiNjctMjA4ZTA5NGMtMjlhOTBhMy0yYTdjYmVjNg==" 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-10-14 15:21:33,516 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c2811e36-0b68-48c2-b801-b15debd6b9fd, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,736 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:21:33,737 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm
2025-10-14 15:21:33,738 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e46b5d12-c461-4192-916d-a78878fccd50, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,738 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e46b5d12-c461-4192-916d-a78878fccd50, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm" 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-10-14 15:21:33,739 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e46b5d12-c461-4192-916d-a78878fccd50, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,753 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==
2025-10-14 15:21:33,754 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:21:33,754 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 17b8d8b4-2d47-45e7-81cf-0fbabe9b46d4, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,755 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 17b8d8b4-2d47-45e7-81cf-0fbabe9b46d4, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==" 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-10-14 15:21:33,756 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:21:33,757 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 17b8d8b4-2d47-45e7-81cf-0fbabe9b46d4, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,760 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:21:33,760 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:21:33,760 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjA5ZTdiNjctMjA4ZTA5NGMtMjlhOTBhMy0yYTdjYmVjNg==
2025-10-14 15:21:33,761 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:21:33,762 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=
2025-10-14 15:21:33,763 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1314bdf2-a587-44b0-b5f0-ba71b05debcd, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,763 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:21:33,764 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1314bdf2-a587-44b0-b5f0-ba71b05debcd, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=" 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-10-14 15:21:33,764 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 58da1348-04e0-4bf4-930a-a2fd253e5d62, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,766 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 58da1348-04e0-4bf4-930a-a2fd253e5d62, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=" 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-10-14 15:21:33,766 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1314bdf2-a587-44b0-b5f0-ba71b05debcd, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,767 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 58da1348-04e0-4bf4-930a-a2fd253e5d62, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:33,784 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm
2025-10-14 15:21:33,945 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:21:33,945 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:21:33,946 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:21:33,948 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==
2025-10-14 15:21:33,953 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5c711ddd-483c-4c7c-a8ca-b9f5621170a5, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:33,953 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5c711ddd-483c-4c7c-a8ca-b9f5621170a5, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:21:33,956 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5c711ddd-483c-4c7c-a8ca-b9f5621170a5, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:21:34,006 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==
2025-10-14 15:21:34,006 - INFO - root - test_log_uniform: check insert: 7364 16304
2025-10-14 15:21:34,008 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.16
2025-10-14 15:21:34,010 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.16
2025-10-14 15:21:34,010 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/0sfb/00077a/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:15118', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-10-14 15:21:34,011 - DEBUG - ya.test - execute: Command pid: 3833621
2025-10-14 15:21:34,175 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) rc: 0
2025-10-14 15:21:34,176 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) elapsed time (sec): 0.16183018684387207
2025-10-14 15:21:34,176 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) idrss: 0
2025-10-14 15:21:34,176 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) inblock: 0
2025-10-14 15:21:34,176 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) isrss: 0
2025-10-14 15:21:34,176 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) ixrss: 0
2025-10-14 15:21:34,189 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) majflt: 0
2025-10-14 15:21:34,194 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) maxrss: 819672
2025-10-14 15:21:34,194 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) minflt: 3479
2025-10-14 15:21:34,195 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) msgrcv: 0
2025-10-14 15:21:34,195 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) msgsnd: 0
2025-10-14 15:21:34,195 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) nivcsw: 6
2025-10-14 15:21:34,196 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) nsignals: 0
2025-10-14 15:21:34,196 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) nswap: 0
2025-10-14 15:21:34,196 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) nvcsw: 176
2025-10-14 15:21:34,196 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) oublock: 8
2025-10-14 15:21:34,197 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) stime: 0.022747
2025-10-14 15:21:34,197 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) utime: 0.053077
2025-10-14 15:21:34,198 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) wtime: 0.164
2025-10-14 15:21:34,198 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) output:
Remove path log...
Remove path log...Ok

2025-10-14 15:21:34,198 - DEBUG - ya.test - _save_outputs: Command (pid 3833621) errors:

2025-10-14 15:21:34,199 - INFO - ya.test - pytest_runtest_teardown: Test teardown
2025-10-14 15:21:34,200 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, c7a17462-ab16-4414-9331-4f4afc053811, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:34,201 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, c7a17462-ab16-4414-9331-4f4afc053811, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=" }
2025-10-14 15:21:34,240 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, c7a17462-ab16-4414-9331-4f4afc053811, ghrun-3ux3yv5ocu.auto.internal:15118): response = { status: SUCCESS }
2025-10-14 15:21:34,242 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, cc61235c-e744-4164-840a-7d80e5d24e7f, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:34,242 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, cc61235c-e744-4164-840a-7d80e5d24e7f, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=" }
2025-10-14 15:21:34,244 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, cc61235c-e744-4164-840a-7d80e5d24e7f, ghrun-3ux3yv5ocu.auto.internal:15118): response = { status: SUCCESS }
2025-10-14 15:21:34,249 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 4f85e91d-ca7a-4a43-b3c0-d7415a47ced1, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:34,250 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 4f85e91d-ca7a-4a43-b3c0-d7415a47ced1, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=" }
2025-10-14 15:21:34,257 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 4f85e91d-ca7a-4a43-b3c0-d7415a47ced1, ghrun-3ux3yv5ocu.auto.internal:15118): response = { status: SUCCESS }
2025-10-14 15:21:34,259 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, bfcba414-0a4e-4ff8-b899-fa8a9f56fdd5, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:34,260 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, bfcba414-0a4e-4ff8-b899-fa8a9f56fdd5, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NjA5ZTdiNjctMjA4ZTA5NGMtMjlhOTBhMy0yYTdjYmVjNg==" }
2025-10-14 15:21:34,262 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, bfcba414-0a4e-4ff8-b899-fa8a9f56fdd5, ghrun-3ux3yv5ocu.auto.internal:15118): response = { status: SUCCESS }
2025-10-14 15:21:34,264 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, e99beb81-da12-446f-a3fc-27a1a2b4a0a1, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:34,264 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, e99beb81-da12-446f-a3fc-27a1a2b4a0a1, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=" }
2025-10-14 15:21:34,266 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, e99beb81-da12-446f-a3fc-27a1a2b4a0a1, ghrun-3ux3yv5ocu.auto.internal:15118): response = { status: SUCCESS }
2025-10-14 15:21:34,268 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 902177c5-1505-42b7-a9d2-511c230d0396, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:34,268 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 902177c5-1505-42b7-a9d2-511c230d0396, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm" }
2025-10-14 15:21:34,270 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 902177c5-1505-42b7-a9d2-511c230d0396, ghrun-3ux3yv5ocu.auto.internal:15118): response = { status: SUCCESS }
2025-10-14 15:21:34,271 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 7947c2df-e011-47d3-bba1-8afeb57b9442, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:34,272 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 7947c2df-e011-47d3-bba1-8afeb57b9442, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==" }
2025-10-14 15:21:34,273 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 7947c2df-e011-47d3-bba1-8afeb57b9442, ghrun-3ux3yv5ocu.auto.internal:15118): response = { status: SUCCESS }
2025-10-14 15:21:34,275 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, fa30c7e0-38fd-412f-9832-8b21a58f79d4, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:34,275 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, fa30c7e0-38fd-412f-9832-8b21a58f79d4, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=" }
2025-10-14 15:21:34,277 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, fa30c7e0-38fd-412f-9832-8b21a58f79d4, ghrun-3ux3yv5ocu.auto.internal:15118): response = { status: SUCCESS }
2025-10-14 15:21:34,277 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, 6a2a47c4-a5f8-499e-9c88-73de5a15dc74, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:34,279 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, 6a2a47c4-a5f8-499e-9c88-73de5a15dc74, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=" }
2025-10-14 15:21:34,280 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, 6a2a47c4-a5f8-499e-9c88-73de5a15dc74, ghrun-3ux3yv5ocu.auto.internal:15118): response = { status: SUCCESS }
2025-10-14 15:21:34,283 - DEBUG - ydb.connection - _prepare_call: RpcState(DeleteSession, ea53e1aa-eac2-47ac-897c-1ff1fbc2180c, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:21:34,284 - DEBUG - ydb.connection - _log_request: RpcState(DeleteSession, ea53e1aa-eac2-47ac-897c-1ff1fbc2180c, ghrun-3ux3yv5ocu.auto.internal:15118): request = { session_id: "ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==" }
2025-10-14 15:21:34,285 - DEBUG - ydb.connection - _log_response: RpcState(DeleteSession, ea53e1aa-eac2-47ac-897c-1ff1fbc2180c, ghrun-3ux3yv5ocu.auto.internal:15118): response = { status: SUCCESS }
2025-10-14 15:21:34,287 - DEBUG - ydb.query.pool - stop: All session were deleted.
2025-10-14 15:21:34,287 - DEBUG - ydb.topic - close: Close topic client
2025-10-14 15:21:34,287 - DEBUG - ydb.topic - close: Topic client was closed
2025-10-14 15:21:34,287 - INFO - ydb.connection - close: Closing channel for endpoint localhost:15118
2025-10-14 15:21:34,289 - INFO - ydb.connection - close: Closing channel for endpoint ghrun-3ux3yv5ocu.auto.internal:15118
2025-10-14 15:21:34,290 - INFO - ydb.pool.Discovery - run: Successfully terminated discovery process
2025-10-14 15:21:41,298 - INFO - ydb.tests.library.harness.kikimr_runner - stop: Stopped node localhost:15118/1
2025-10-14 15:21:41,303 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/11745
2025-10-14 15:21:41,303 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/19209
2025-10-14 15:21:41,304 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/20050
2025-10-14 15:21:41,304 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/15118
2025-10-14 15:21:41,304 - DEBUG - library.python.filelock - release: Ensuring FileLock released: /home/runner/.ya/build/port_sync_dir/28532
