2025-10-14 15:18:23,355 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-14 15:18:23,374 - INFO - ya.test - pytest_runtest_setup: test_log_deviation[180]
2025-10-14 15:18:23,374 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-14 15:18:23,375 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-14 15:18:23,387 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_deviation[180])
2025-10-14 15:18:23,388 - 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:18:23,398 - 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:18:23,409 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.5
2025-10-14 15:18:23,410 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.5
2025-10-14 15:18:23,412 - 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:18:23,427 - DEBUG - ya.test - execute: Command pid: 3690282
2025-10-14 15:18:23,962 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) rc: 0
2025-10-14 15:18:23,966 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) elapsed time (sec): 0.5221958160400391
2025-10-14 15:18:23,966 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) idrss: 0
2025-10-14 15:18:23,966 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) inblock: 0
2025-10-14 15:18:23,966 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) isrss: 0
2025-10-14 15:18:23,968 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) ixrss: 0
2025-10-14 15:18:23,968 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) majflt: 0
2025-10-14 15:18:23,968 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) maxrss: 819672
2025-10-14 15:18:23,968 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) minflt: 3488
2025-10-14 15:18:23,968 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) msgrcv: 0
2025-10-14 15:18:23,969 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) msgsnd: 0
2025-10-14 15:18:23,969 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) nivcsw: 40
2025-10-14 15:18:23,969 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) nsignals: 0
2025-10-14 15:18:23,969 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) nswap: 0
2025-10-14 15:18:23,969 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) nvcsw: 197
2025-10-14 15:18:23,971 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) oublock: 8
2025-10-14 15:18:23,972 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) stime: 0.023797
2025-10-14 15:18:23,975 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) utime: 0.057115
2025-10-14 15:18:23,975 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) wtime: 0.549
2025-10-14 15:18:23,975 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) output:
Init tables ...
Init tables ...Ok

2025-10-14 15:18:23,976 - DEBUG - ya.test - _save_outputs: Command (pid 3690282) errors:

2025-10-14 15:18:23,976 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:18:23,977 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, de493151-35ad-40d0-b3cd-7d9c7dd3b008, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:18:23,977 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, de493151-35ad-40d0-b3cd-7d9c7dd3b008, 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: "ALTER OBJECT `//Root/log` (TYPE TABLE) SET (ACTION=UPSERT_OPTIONS, `SCAN_READER_POLICY_NAME`=`SIMPLE`) " } stats_mode: STATS_MODE_NONE }
2025-10-14 15:18:23,978 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, de493151-35ad-40d0-b3cd-7d9c7dd3b008, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:18:24,027 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:18:24,029 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-10-14 15:18:24,029 - 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 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-10-14 15:18:24,039 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.6
2025-10-14 15:18:24,040 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.6
2025-10-14 15:18:24,050 - 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', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-10-14 15:18:24,052 - DEBUG - ya.test - execute: Command pid: 3690582
2025-10-14 15:18:27,774 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-14 15:18:27,775 - DEBUG - ya.test - _save_outputs: Command (pid 3690582) rc: 0
2025-10-14 15:18:27,775 - DEBUG - ya.test - _save_outputs: Command (pid 3690582) elapsed time (sec): 3.722641944885254
2025-10-14 15:18:27,776 - DEBUG - ya.test - _save_outputs: Command (pid 3690582) wtime: 3.724
2025-10-14 15:18:27,785 - DEBUG - ya.test - _save_outputs: Command (pid 3690582) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	     12	12	0	0	501	587	639	639
2	     17	17	0	0	599	663	687	687
3	     25	25	0	0	611	691	699	699

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	     54	18	0	0	583	679	691	699

2025-10-14 15:18:27,789 - DEBUG - ya.test - _save_outputs: Command (pid 3690582) errors:

2025-10-14 15:18:27,799 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:18:27,813 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6364165f-e5f6-4942-ab5f-e640a07f9eb1, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:18:27,814 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6364165f-e5f6-4942-ab5f-e640a07f9eb1, 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(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:18:27,835 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6364165f-e5f6-4942-ab5f-e640a07f9eb1, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:18:30,660 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:18:30,669 - INFO - root - test_log_deviation: Count rows after insert 27000 before wait
2025-10-14 15:18:30,670 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:18:30,685 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 01cf6e23-c8e2-4b8a-8f24-2b660a635505, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:18:30,685 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 01cf6e23-c8e2-4b8a-8f24-2b660a635505, 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:18:30,691 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 01cf6e23-c8e2-4b8a-8f24-2b660a635505, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:18:33,507 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:18:33,531 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:18:33,531 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dc556523-1adc-4945-8c18-a79a5ef5a96c, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:18:33,531 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dc556523-1adc-4945-8c18-a79a5ef5a96c, 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:18:33,532 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dc556523-1adc-4945-8c18-a79a5ef5a96c, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:18:37,612 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:18:37,614 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-10-14 15:18:37,614 - 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 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-10-14 15:18:37,615 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.7
2025-10-14 15:18:37,616 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.7
2025-10-14 15:18:37,616 - 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', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-10-14 15:18:37,617 - DEBUG - ya.test - execute: Command pid: 3692985
2025-10-14 15:18:37,619 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-10-14 15:18:37,623 - 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 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-10-14 15:18:37,621 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:18:37,621 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-10-14 15:18:37,638 - 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 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-10-14 15:18:37,625 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.8
2025-10-14 15:18:37,628 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm
2025-10-14 15:18:37,627 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f0aeda7e-7234-439f-94d8-7e99f4095a54, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:18:37,651 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f0aeda7e-7234-439f-94d8-7e99f4095a54, 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:18:37,643 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.8
2025-10-14 15:18:37,641 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.9
2025-10-14 15:18:37,650 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==
2025-10-14 15:18:37,645 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ade6435f-4736-4216-a76d-8e23dfbbe7ed, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:18:37,653 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f0aeda7e-7234-439f-94d8-7e99f4095a54, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:18:37,655 - 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', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-10-14 15:18:37,657 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.9
2025-10-14 15:18:37,659 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6c7e604c-572e-425f-bd08-669e25e91457, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:18:37,659 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjA5ZTdiNjctMjA4ZTA5NGMtMjlhOTBhMy0yYTdjYmVjNg==
2025-10-14 15:18:37,660 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ade6435f-4736-4216-a76d-8e23dfbbe7ed, 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:18:37,664 - DEBUG - ya.test - execute: Command pid: 3693005
2025-10-14 15:18:37,667 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6c7e604c-572e-425f-bd08-669e25e91457, 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:18:37,668 - 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', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-10-14 15:18:37,671 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ade6435f-4736-4216-a76d-8e23dfbbe7ed, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:18:37,669 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:18:37,668 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 024b12f4-a1d5-4a0c-95b6-47565dcc943b, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:18:37,675 - DEBUG - ya.test - execute: Command pid: 3693006
2025-10-14 15:18:37,677 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6c7e604c-572e-425f-bd08-669e25e91457, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:18:37,677 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 1114e9b5-445e-46ed-baf4-9a558c80942d, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:18:37,678 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=
2025-10-14 15:18:37,679 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 024b12f4-a1d5-4a0c-95b6-47565dcc943b, 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:18:37,697 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 722967da-ad58-4081-bf31-5029b3abd664, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:18:37,697 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 1114e9b5-445e-46ed-baf4-9a558c80942d, 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:18:37,698 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:18:37,699 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 722967da-ad58-4081-bf31-5029b3abd664, 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:18:37,699 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 024b12f4-a1d5-4a0c-95b6-47565dcc943b, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:18:37,702 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 1114e9b5-445e-46ed-baf4-9a558c80942d, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:18:37,703 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e1e7c0f7-7503-4c51-9fcb-5b62523d2624, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:18:37,703 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:18:37,704 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 722967da-ad58-4081-bf31-5029b3abd664, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:18:37,708 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e1e7c0f7-7503-4c51-9fcb-5b62523d2624, 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:18:37,708 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 911839ba-be28-40ed-a345-adb386bdefcc, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:18:37,708 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:18:37,735 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e1e7c0f7-7503-4c51-9fcb-5b62523d2624, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:18:37,738 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 911839ba-be28-40ed-a345-adb386bdefcc, 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:18:37,740 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e01ed13d-748b-408a-af84-264d81f46a9c, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:18:37,740 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:18:37,746 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 911839ba-be28-40ed-a345-adb386bdefcc, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:18:37,748 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e01ed13d-748b-408a-af84-264d81f46a9c, 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:18:37,774 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e01ed13d-748b-408a-af84-264d81f46a9c, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:18:37,755 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 22d33c68-f252-488f-bdc2-a295ab70b7bc, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:18:37,790 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 22d33c68-f252-488f-bdc2-a295ab70b7bc, 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:18:37,791 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 22d33c68-f252-488f-bdc2-a295ab70b7bc, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:18:40,490 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-14 15:18:40,494 - 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:18:40,513 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-14 15:18:40,540 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 35656796-8e25-4acb-b96f-0082bcf76ab5, localhost:15118): creating call state
2025-10-14 15:18:40,541 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 35656796-8e25-4acb-b96f-0082bcf76ab5, localhost:15118): request = { database: "/Root" }
2025-10-14 15:18:40,856 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 35656796-8e25-4acb-b96f-0082bcf76ab5, 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:18:40,882 - 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:18:40,895 - INFO - ydb.connection - close: Closing channel for endpoint localhost:15118
2025-10-14 15:18:41,487 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-14 15:18:41,488 - DEBUG - ya.test - _save_outputs: Command (pid 3693005) rc: 0
2025-10-14 15:18:41,488 - DEBUG - ya.test - _save_outputs: Command (pid 3693005) elapsed time (sec): 3.817148447036743
2025-10-14 15:18:41,488 - DEBUG - ya.test - _save_outputs: Command (pid 3693005) wtime: 3.827
2025-10-14 15:18:41,489 - DEBUG - ya.test - _save_outputs: Command (pid 3693005) 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

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	      0	0	0	20	0	0	0	0

2025-10-14 15:18:41,495 - DEBUG - ya.test - _save_outputs: Command (pid 3693005) errors:

2025-10-14 15:18:42,519 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-14 15:18:42,534 - DEBUG - ya.test - _save_outputs: Command (pid 3693006) rc: 0
2025-10-14 15:18:42,535 - DEBUG - ya.test - _save_outputs: Command (pid 3693006) elapsed time (sec): 4.847572088241577
2025-10-14 15:18:42,535 - DEBUG - ya.test - _save_outputs: Command (pid 3693006) wtime: 4.863
2025-10-14 15:18:42,535 - DEBUG - ya.test - _save_outputs: Command (pid 3693006) 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	11	0	0	0	0

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	      0	0	0	21	0	0	0	0

2025-10-14 15:18:42,535 - DEBUG - ya.test - _save_outputs: Command (pid 3693006) errors:

2025-10-14 15:18:58,377 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-14 15:18:58,378 - DEBUG - ya.test - _save_outputs: Command (pid 3692985) rc: 0
2025-10-14 15:18:58,378 - DEBUG - ya.test - _save_outputs: Command (pid 3692985) elapsed time (sec): 20.759868144989014
2025-10-14 15:18:58,383 - DEBUG - ya.test - _save_outputs: Command (pid 3692985) wtime: 20.761
2025-10-14 15:18:58,425 - DEBUG - ya.test - _save_outputs: Command (pid 3692985) 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	10	0	0	0	0

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	      0	0	0	10	0	0	0	0

2025-10-14 15:18:58,426 - DEBUG - ya.test - _save_outputs: Command (pid 3692985) errors:

2025-10-14 15:19:25,994 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:19:25,997 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:19:25,997 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a753db54-51f4-4a2e-a513-94cf986687cf, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:25,998 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a753db54-51f4-4a2e-a513-94cf986687cf, 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(8)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:25,999 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a753db54-51f4-4a2e-a513-94cf986687cf, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:26,326 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:19:26,349 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:19:26,350 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 15fbb906-f024-4eaf-90ed-057148531991, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:26,359 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 15fbb906-f024-4eaf-90ed-057148531991, 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(7)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:26,366 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 15fbb906-f024-4eaf-90ed-057148531991, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:26,470 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:19:26,482 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:19:26,493 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d2b60c6f-7d83-49f6-8868-e1d28fcafc61, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:26,499 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d2b60c6f-7d83-49f6-8868-e1d28fcafc61, 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(1)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:26,510 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d2b60c6f-7d83-49f6-8868-e1d28fcafc61, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:26,692 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:19:26,699 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:19:26,706 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8b1e5ff5-1a15-442a-b455-f0ce0ffb37a8, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:26,744 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8b1e5ff5-1a15-442a-b455-f0ce0ffb37a8, 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(10)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:26,754 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8b1e5ff5-1a15-442a-b455-f0ce0ffb37a8, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:26,886 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:19:26,886 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:19:26,887 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c80ce263-1fb4-4dac-b2c9-7cacbc16b329, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:26,887 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c80ce263-1fb4-4dac-b2c9-7cacbc16b329, 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(3)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:26,888 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c80ce263-1fb4-4dac-b2c9-7cacbc16b329, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:27,880 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:19:27,881 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:19:27,889 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 92c68954-8a29-4fbc-89fb-da9568645b4f, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:27,891 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 92c68954-8a29-4fbc-89fb-da9568645b4f, 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(10)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:27,919 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 92c68954-8a29-4fbc-89fb-da9568645b4f, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:28,169 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:19:28,205 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:19:28,214 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3da342d5-ef0c-456c-91b8-a8f7a480cc0c, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:28,216 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3da342d5-ef0c-456c-91b8-a8f7a480cc0c, 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)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:28,247 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3da342d5-ef0c-456c-91b8-a8f7a480cc0c, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:28,552 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:19:28,553 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:19:28,561 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5fc8ba06-2b11-4fba-9ec7-fa9ccc13c85e, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:28,562 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5fc8ba06-2b11-4fba-9ec7-fa9ccc13c85e, 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(11)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10))" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:28,574 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5fc8ba06-2b11-4fba-9ec7-fa9ccc13c85e, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:28,884 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:19:28,889 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:19:28,936 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ce3431f5-f54c-48d4-898a-05a01da0893a, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:28,936 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ce3431f5-f54c-48d4-898a-05a01da0893a, 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(7)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:28,962 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ce3431f5-f54c-48d4-898a-05a01da0893a, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:29,326 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:19:29,360 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:19:29,360 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:19:29,361 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 398fe9d6-599f-48d8-9b9c-ddaf709946b0, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:29,370 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 398fe9d6-599f-48d8-9b9c-ddaf709946b0, 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(9)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(8))" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:29,379 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 398fe9d6-599f-48d8-9b9c-ddaf709946b0, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:29,832 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:19:29,849 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:19:29,850 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f14a8e33-db46-4d64-85c3-5cabe0fb78d5, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:29,850 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f14a8e33-db46-4d64-85c3-5cabe0fb78d5, 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:19:29,851 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f14a8e33-db46-4d64-85c3-5cabe0fb78d5, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:29,954 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:19:29,983 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:19:30,009 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:19:30,010 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7ca92282-27df-4c37-b3a3-a1e9faf0534c, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:30,039 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7ca92282-27df-4c37-b3a3-a1e9faf0534c, 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(9)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:30,040 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7ca92282-27df-4c37-b3a3-a1e9faf0534c, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:30,021 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:19:30,089 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:19:30,095 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2c56a927-11ff-4acc-9dbe-3dd03efb5ba1, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:30,098 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=
2025-10-14 15:19:30,105 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2c56a927-11ff-4acc-9dbe-3dd03efb5ba1, 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(3)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:30,108 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:19:30,111 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2c56a927-11ff-4acc-9dbe-3dd03efb5ba1, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:30,112 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==
2025-10-14 15:19:30,117 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c9cd2047-e8bc-4939-96b1-0e8320c3c04f, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:30,119 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:19:30,132 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dc63b4c3-5d1d-4972-bb31-4b823a27aff0, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:30,129 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c9cd2047-e8bc-4939-96b1-0e8320c3c04f, 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(10)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:30,136 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dc63b4c3-5d1d-4972-bb31-4b823a27aff0, 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(10)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:30,144 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c9cd2047-e8bc-4939-96b1-0e8320c3c04f, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:30,146 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dc63b4c3-5d1d-4972-bb31-4b823a27aff0, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:30,157 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:19:30,252 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=
2025-10-14 15:19:30,254 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dc3f3144-d932-40e9-b953-850bb8fff1c3, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:30,254 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dc3f3144-d932-40e9-b953-850bb8fff1c3, 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(1)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:30,263 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dc3f3144-d932-40e9-b953-850bb8fff1c3, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:30,327 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:19:30,426 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:19:30,427 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==
2025-10-14 15:19:30,428 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f7d1086b-1a6a-4c98-9d62-51900d9f0325, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:30,428 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f7d1086b-1a6a-4c98-9d62-51900d9f0325, 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(9)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:30,429 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f7d1086b-1a6a-4c98-9d62-51900d9f0325, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:30,442 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm
2025-10-14 15:19:30,442 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:19:30,442 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ed8fe352-9eda-4a47-981b-2bdc76fea4ef, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:30,443 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ed8fe352-9eda-4a47-981b-2bdc76fea4ef, 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(10)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:30,451 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ed8fe352-9eda-4a47-981b-2bdc76fea4ef, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:30,537 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:19:30,538 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:19:30,543 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 51450498-d60d-4b2e-97af-8133182634a6, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:30,544 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:19:30,545 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:19:30,546 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c0a02155-2240-4b86-bef0-671a3fd33899, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:30,544 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 51450498-d60d-4b2e-97af-8133182634a6, 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(10)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:30,553 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 51450498-d60d-4b2e-97af-8133182634a6, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:30,551 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c0a02155-2240-4b86-bef0-671a3fd33899, 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(10)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:30,562 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c0a02155-2240-4b86-bef0-671a3fd33899, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:30,586 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjA5ZTdiNjctMjA4ZTA5NGMtMjlhOTBhMy0yYTdjYmVjNg==
2025-10-14 15:19:30,587 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm
2025-10-14 15:19:30,587 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d9b278e9-7987-471d-845c-975e8f34167c, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:30,588 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d9b278e9-7987-471d-845c-975e8f34167c, 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(3)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:30,588 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d9b278e9-7987-471d-845c-975e8f34167c, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:30,678 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:19:30,678 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:19:30,679 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f911b475-cbe5-4a41-aca8-e5c4055bcf5e, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:30,680 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f911b475-cbe5-4a41-aca8-e5c4055bcf5e, 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(10)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:30,681 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f911b475-cbe5-4a41-aca8-e5c4055bcf5e, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:30,910 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:19:30,928 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:19:30,929 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ec183e04-feab-4952-9618-497142e8fb74, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:30,930 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ec183e04-feab-4952-9618-497142e8fb74, 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(11)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10))" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:30,932 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ec183e04-feab-4952-9618-497142e8fb74, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:30,931 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:19:30,933 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjA5ZTdiNjctMjA4ZTA5NGMtMjlhOTBhMy0yYTdjYmVjNg==
2025-10-14 15:19:30,960 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4b54f63d-b6f5-468b-bd9e-6828ef3bbb26, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:30,977 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==
2025-10-14 15:19:31,009 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4b54f63d-b6f5-468b-bd9e-6828ef3bbb26, 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(11)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10))" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:31,025 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4b54f63d-b6f5-468b-bd9e-6828ef3bbb26, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:31,024 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:19:31,038 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dff047eb-ba53-420e-a3e7-1a58231b5603, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:31,039 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dff047eb-ba53-420e-a3e7-1a58231b5603, 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:19:31,074 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dff047eb-ba53-420e-a3e7-1a58231b5603, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:31,150 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:19:31,198 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:19:31,199 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:19:31,199 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f733d88b-72af-4fcf-bdf9-72386a88a3c1, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:31,210 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f733d88b-72af-4fcf-bdf9-72386a88a3c1, 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(11)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10))" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:31,211 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f733d88b-72af-4fcf-bdf9-72386a88a3c1, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:31,283 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjA5ZTdiNjctMjA4ZTA5NGMtMjlhOTBhMy0yYTdjYmVjNg==
2025-10-14 15:19:31,813 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGIzN2EzMjQtOTliMTQ2NTMtYjA2MzkyMjMtNGU3N2RiMzQ=
2025-10-14 15:19:31,817 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTZlNGZhNTItYjNiMTcwYzQtZjdkY2FjZTgtNTczODk5Y2M=
2025-10-14 15:19:32,261 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODM3ZDZjNS00OTlmN2NiMS1hNDFkZDY5NC0yNmQ0YWE1Yw==
2025-10-14 15:19:32,285 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:19:32,286 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fcb65293-0230-4082-8b37-08171e54659f, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:32,286 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fcb65293-0230-4082-8b37-08171e54659f, 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(4)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3))" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:32,293 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fcb65293-0230-4082-8b37-08171e54659f, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:32,266 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjlhZjA1MTEtOTJjMzY0ZjEtM2I4MmU3YjItZGVhODA1YzA=
2025-10-14 15:19:32,300 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==
2025-10-14 15:19:32,301 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0374d76c-9757-4268-a36f-20f4b0212581, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:32,303 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0374d76c-9757-4268-a36f-20f4b0212581, 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(2)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(1))" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:32,303 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0374d76c-9757-4268-a36f-20f4b0212581, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:32,405 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NzQ0ZmQ2YWEtMmZlMjVkNDktODYyZmExOS1hOTVjN2Zm
2025-10-14 15:19:32,589 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:19:32,590 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 23150484-1611-44bd-b311-f5801fe87f1b, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:32,591 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 23150484-1611-44bd-b311-f5801fe87f1b, 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(3)" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:32,591 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 23150484-1611-44bd-b311-f5801fe87f1b, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:33,978 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2YzMDEzMTUtOGFhMWQ0ODYtYzljMWZlNTEtNzU2YjM2YWI=
2025-10-14 15:19:33,981 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OTVlNjhmZi1hYThjZDEyZi03OGRmNWFiNS01ZmFjNWIzOA==
2025-10-14 15:19:33,993 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjU4Y2RjZGYtZDkzODRlMzktNWE2MTU0MmUtNzhmNzdlNmY=
2025-10-14 15:19:33,997 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:19:33,998 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be77247c-3fc8-43f5-81e5-feae3b576940, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:33,999 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be77247c-3fc8-43f5-81e5-feae3b576940, 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(4)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3))" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:33,999 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be77247c-3fc8-43f5-81e5-feae3b576940, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:34,612 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Y2VkZWEzMjgtNzJkMmU5NmUtYWRhN2Q3Y2MtNDEyNTE0MzU=
2025-10-14 15:19:34,613 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NjA5ZTdiNjctMjA4ZTA5NGMtMjlhOTBhMy0yYTdjYmVjNg==
2025-10-14 15:19:34,625 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0921b7dd-cd3c-4330-84f9-9e3a34fa4eab, ghrun-3ux3yv5ocu.auto.internal:15118): creating call state
2025-10-14 15:19:34,641 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0921b7dd-cd3c-4330-84f9-9e3a34fa4eab, 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(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-10-14 15:19:34,645 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0921b7dd-cd3c-4330-84f9-9e3a34fa4eab, ghrun-3ux3yv5ocu.auto.internal:15118): response = { <_MultiThreadedRendezvous object> }
2025-10-14 15:19:35,795 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NjA5ZTdiNjctMjA4ZTA5NGMtMjlhOTBhMy0yYTdjYmVjNg==
2025-10-14 15:19:35,799 - INFO - root - test_log_deviation: check insert: 32000 27000
2025-10-14 15:19:35,816 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.10
2025-10-14 15:19:35,819 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.10
2025-10-14 15:19:35,819 - 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:19:35,861 - DEBUG - ya.test - execute: Command pid: 3719627
2025-10-14 15:19:36,144 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) rc: 0
2025-10-14 15:19:36,144 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) elapsed time (sec): 0.2668793201446533
2025-10-14 15:19:36,144 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) idrss: 0
2025-10-14 15:19:36,145 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) inblock: 0
2025-10-14 15:19:36,147 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) isrss: 0
2025-10-14 15:19:36,149 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) ixrss: 0
2025-10-14 15:19:36,155 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) majflt: 0
2025-10-14 15:19:36,155 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) maxrss: 819672
2025-10-14 15:19:36,155 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) minflt: 3476
2025-10-14 15:19:36,155 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) msgrcv: 0
2025-10-14 15:19:36,156 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) msgsnd: 0
2025-10-14 15:19:36,156 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) nivcsw: 32
2025-10-14 15:19:36,156 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) nsignals: 0
2025-10-14 15:19:36,156 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) nswap: 0
2025-10-14 15:19:36,156 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) nvcsw: 133
2025-10-14 15:19:36,156 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) oublock: 8
2025-10-14 15:19:36,157 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) stime: 0.033839
2025-10-14 15:19:36,157 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) utime: 0.046662999999999996
2025-10-14 15:19:36,157 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) wtime: 0.285
2025-10-14 15:19:36,173 - DEBUG - ya.test - _save_outputs: Command (pid 3719627) output:
Remove path log...
Remove path log...Ok

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

2025-10-14 15:19:36,191 - INFO - ya.test - pytest_runtest_teardown: Test teardown
