2025-10-27 12:38:34,407 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-27 12:38:34,407 - INFO - ya.test - pytest_runtest_setup: test_log_deviation[180]
2025-10-27 12:38:34,407 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-10-27 12:38:34,407 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-10-27 12:38:34,409 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_deviation[180])
2025-10-27 12:38:34,410 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb
2025-10-27 12:38:34,410 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb -e grpc://localhost:28057 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-10-27 12:38:34,412 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.5
2025-10-27 12:38:34,414 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.5
2025-10-27 12:38:34,414 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:28057', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-10-27 12:38:34,415 - DEBUG - ya.test - execute: Command pid: 3896805
2025-10-27 12:38:35,144 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) rc: 0
2025-10-27 12:38:35,144 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) elapsed time (sec): 0.7277765274047852
2025-10-27 12:38:35,144 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) idrss: 0
2025-10-27 12:38:35,145 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) inblock: 0
2025-10-27 12:38:35,145 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) isrss: 0
2025-10-27 12:38:35,145 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) ixrss: 0
2025-10-27 12:38:35,145 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) majflt: 0
2025-10-27 12:38:35,149 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) maxrss: 230720
2025-10-27 12:38:35,162 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) minflt: 3494
2025-10-27 12:38:35,162 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) msgrcv: 0
2025-10-27 12:38:35,162 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) msgsnd: 0
2025-10-27 12:38:35,162 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) nivcsw: 42
2025-10-27 12:38:35,162 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) nsignals: 0
2025-10-27 12:38:35,163 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) nswap: 0
2025-10-27 12:38:35,163 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) nvcsw: 153
2025-10-27 12:38:35,163 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) oublock: 8
2025-10-27 12:38:35,163 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) stime: 0.021591
2025-10-27 12:38:35,163 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) utime: 0.064774
2025-10-27 12:38:35,164 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) wtime: 0.729
2025-10-27 12:38:35,164 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) output:
Init tables ...
Init tables ...Ok

2025-10-27 12:38:35,164 - DEBUG - ya.test - _save_outputs: Command (pid 3896805) errors:

2025-10-27 12:38:35,164 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:38:35,166 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bedc3311-aded-45ff-a334-58935af92e37, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:35,167 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bedc3311-aded-45ff-a334-58935af92e37, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=" 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-27 12:38:35,168 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bedc3311-aded-45ff-a334-58935af92e37, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:35,219 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:38:35,228 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-10-27 12:38:35,228 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb -e grpc://localhost:28057 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-10-27 12:38:35,229 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.6
2025-10-27 12:38:35,232 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.6
2025-10-27 12:38:35,241 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:28057', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-10-27 12:38:35,253 - DEBUG - ya.test - execute: Command pid: 3897042
2025-10-27 12:38:39,076 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-27 12:38:39,076 - DEBUG - ya.test - _save_outputs: Command (pid 3897042) rc: 0
2025-10-27 12:38:39,076 - DEBUG - ya.test - _save_outputs: Command (pid 3897042) elapsed time (sec): 3.8162474632263184
2025-10-27 12:38:39,077 - DEBUG - ya.test - _save_outputs: Command (pid 3897042) wtime: 3.824
2025-10-27 12:38:39,077 - DEBUG - ya.test - _save_outputs: Command (pid 3897042) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	     10	10	0	0	535	783	783	783
2	     12	12	0	0	723	763	771	771
3	     24	24	0	0	719	803	847	847

Total	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
3	     46	15.3333	0	0	715	799	847	847

2025-10-27 12:38:39,077 - DEBUG - ya.test - _save_outputs: Command (pid 3897042) errors:

2025-10-27 12:38:39,078 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:38:39,080 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a7611039-02b6-41c3-a0ab-6d61714c6d66, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:39,080 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a7611039-02b6-41c3-a0ab-6d61714c6d66, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=" 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-27 12:38:39,091 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a7611039-02b6-41c3-a0ab-6d61714c6d66, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:39,626 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:38:39,627 - INFO - root - test_log_deviation: Count rows after insert 23000 before wait
2025-10-27 12:38:39,627 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=
2025-10-27 12:38:39,628 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 375c4f40-6716-457c-a1f7-883ae5fefa71, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:39,628 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 375c4f40-6716-457c-a1f7-883ae5fefa71, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=" 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-27 12:38:39,629 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 375c4f40-6716-457c-a1f7-883ae5fefa71, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:40,006 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=
2025-10-27 12:38:40,007 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:40,007 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 229fba81-bffd-48c5-8507-594598b44b34, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:40,008 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 229fba81-bffd-48c5-8507-594598b44b34, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=" 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-27 12:38:40,009 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 229fba81-bffd-48c5-8507-594598b44b34, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:40,241 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:40,243 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-10-27 12:38:40,244 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb -e grpc://localhost:28057 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-10-27 12:38:40,244 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-10-27 12:38:40,243 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-10-27 12:38:40,245 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==
2025-10-27 12:38:40,249 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb -e grpc://localhost:28057 -d /Root workload log --path log run upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-10-27 12:38:40,250 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb -e grpc://localhost:28057 -d /Root workload log --path log run insert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-10-27 12:38:40,251 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.7
2025-10-27 12:38:40,251 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, af7f936c-c041-493c-b7e4-3fc57a082935, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:40,251 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:40,258 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.8
2025-10-27 12:38:40,262 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.9
2025-10-27 12:38:40,263 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, af7f936c-c041-493c-b7e4-3fc57a082935, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:40,264 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 38dbf4e4-4be0-454d-bf90-c2f002bd66f6, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:40,264 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.7
2025-10-27 12:38:40,264 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=
2025-10-27 12:38:40,266 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.8
2025-10-27 12:38:40,272 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:28057', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-10-27 12:38:40,270 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.9
2025-10-27 12:38:40,271 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 38dbf4e4-4be0-454d-bf90-c2f002bd66f6, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:40,268 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, af7f936c-c041-493c-b7e4-3fc57a082935, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:40,273 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3451322c-e351-4fab-8185-b0c46c667c9a, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:40,273 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:40,281 - DEBUG - ya.test - execute: Command pid: 3898811
2025-10-27 12:38:40,280 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:28057', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-10-27 12:38:40,284 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:28057', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'insert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-10-27 12:38:40,285 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 38dbf4e4-4be0-454d-bf90-c2f002bd66f6, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:40,286 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3451322c-e351-4fab-8185-b0c46c667c9a, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:40,294 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3451322c-e351-4fab-8185-b0c46c667c9a, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:40,287 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==
2025-10-27 12:38:40,297 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 60c8b1ad-00ea-470c-ad6b-3b6023c56c7a, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:40,293 - DEBUG - ya.test - execute: Command pid: 3898817
2025-10-27 12:38:40,290 - DEBUG - ya.test - execute: Command pid: 3898815
2025-10-27 12:38:40,298 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:38:40,300 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 808efbcb-ec5a-4e7b-a9b8-16b240bf87b4, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:40,300 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 808efbcb-ec5a-4e7b-a9b8-16b240bf87b4, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:40,301 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 808efbcb-ec5a-4e7b-a9b8-16b240bf87b4, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:40,301 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:38:40,302 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4892ae36-ca4a-4983-9667-83e0dfd218a1, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:40,302 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4892ae36-ca4a-4983-9667-83e0dfd218a1, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:40,303 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4892ae36-ca4a-4983-9667-83e0dfd218a1, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:40,286 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f21d956e-0b0e-478b-869d-40cb0622b075, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:40,304 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f21d956e-0b0e-478b-869d-40cb0622b075, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:40,298 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 60c8b1ad-00ea-470c-ad6b-3b6023c56c7a, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:40,307 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 60c8b1ad-00ea-470c-ad6b-3b6023c56c7a, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:40,306 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:38:40,308 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8808d37a-9da7-4ddf-a85a-a33601e18658, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:40,308 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8808d37a-9da7-4ddf-a85a-a33601e18658, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:40,309 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8808d37a-9da7-4ddf-a85a-a33601e18658, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:40,309 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=
2025-10-27 12:38:40,310 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2cf191f9-431d-43b2-a9a9-fb6a12258367, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:40,310 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2cf191f9-431d-43b2-a9a9-fb6a12258367, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:40,311 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2cf191f9-431d-43b2-a9a9-fb6a12258367, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:40,312 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:40,312 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3a862665-a0c4-4fd2-9d2d-1baedd2a3c01, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:40,313 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3a862665-a0c4-4fd2-9d2d-1baedd2a3c01, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:40,313 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3a862665-a0c4-4fd2-9d2d-1baedd2a3c01, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:40,306 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f21d956e-0b0e-478b-869d-40cb0622b075, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:44,137 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-27 12:38:44,141 - DEBUG - ya.test - _save_outputs: Command (pid 3898815) rc: 0
2025-10-27 12:38:44,145 - DEBUG - ya.test - _save_outputs: Command (pid 3898815) elapsed time (sec): 3.8385210037231445
2025-10-27 12:38:44,146 - DEBUG - ya.test - _save_outputs: Command (pid 3898815) wtime: 3.849
2025-10-27 12:38:44,153 - DEBUG - ya.test - _save_outputs: Command (pid 3898815) 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-27 12:38:44,157 - DEBUG - ya.test - _save_outputs: Command (pid 3898815) errors:

2025-10-27 12:38:44,251 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-27 12:38:44,252 - DEBUG - ya.test - _save_outputs: Command (pid 3898817) rc: 0
2025-10-27 12:38:44,253 - DEBUG - ya.test - _save_outputs: Command (pid 3898817) elapsed time (sec): 3.953444242477417
2025-10-27 12:38:44,253 - DEBUG - ya.test - _save_outputs: Command (pid 3898817) wtime: 3.96
2025-10-27 12:38:44,254 - DEBUG - ya.test - _save_outputs: Command (pid 3898817) 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-27 12:38:44,255 - DEBUG - ya.test - _save_outputs: Command (pid 3898817) errors:

2025-10-27 12:38:50,992 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=
2025-10-27 12:38:50,992 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=
2025-10-27 12:38:50,993 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0e5e75a4-6f0d-4741-9639-c78cadc8f962, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:51,000 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0e5e75a4-6f0d-4741-9639-c78cadc8f962, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:51,001 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0e5e75a4-6f0d-4741-9639-c78cadc8f962, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:51,014 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:38:51,014 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:38:51,021 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 5d297e3d-9666-4496-8558-56492007d629, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:51,021 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 5d297e3d-9666-4496-8558-56492007d629, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=" 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-27 12:38:51,050 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 5d297e3d-9666-4496-8558-56492007d629, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:51,038 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:51,095 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=
2025-10-27 12:38:51,022 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=
2025-10-27 12:38:51,117 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:51,038 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:38:51,149 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:38:51,095 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, faf7d5ab-3102-4e3e-b2a0-85b2bcfad884, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:51,118 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a57e09c1-d3de-4146-a6fc-df9653bf1dc8, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:51,150 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 817d3eab-3dda-4c75-88f6-f89c1586298f, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:51,169 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, faf7d5ab-3102-4e3e-b2a0-85b2bcfad884, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=" 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-27 12:38:51,170 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a57e09c1-d3de-4146-a6fc-df9653bf1dc8, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=" 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-27 12:38:51,171 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 817d3eab-3dda-4c75-88f6-f89c1586298f, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:51,175 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, faf7d5ab-3102-4e3e-b2a0-85b2bcfad884, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:51,182 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:51,192 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a57e09c1-d3de-4146-a6fc-df9653bf1dc8, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:51,192 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 817d3eab-3dda-4c75-88f6-f89c1586298f, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:51,195 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:51,199 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3bc13783-418f-47fb-8e71-af24fca6f9be, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:51,200 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3bc13783-418f-47fb-8e71-af24fca6f9be, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=" 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-27 12:38:51,213 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3bc13783-418f-47fb-8e71-af24fca6f9be, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:51,225 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==
2025-10-27 12:38:51,229 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==
2025-10-27 12:38:51,231 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a781e30a-71e6-40b1-9280-d0781d79d8f8, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:51,232 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a781e30a-71e6-40b1-9280-d0781d79d8f8, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==" 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-27 12:38:51,233 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a781e30a-71e6-40b1-9280-d0781d79d8f8, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:51,250 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:51,250 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:51,251 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 90cffa4a-f61f-492f-9523-899b4d88e603, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:51,251 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 90cffa4a-f61f-492f-9523-899b4d88e603, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=" 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-27 12:38:51,254 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 90cffa4a-f61f-492f-9523-899b4d88e603, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:51,309 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==
2025-10-27 12:38:51,310 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==
2025-10-27 12:38:51,317 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, dd5bb154-c9bb-4b3f-9fb3-a54abe11eaa9, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:51,318 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, dd5bb154-c9bb-4b3f-9fb3-a54abe11eaa9, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==" 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-27 12:38:51,330 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, dd5bb154-c9bb-4b3f-9fb3-a54abe11eaa9, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:51,617 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:38:51,658 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:38:51,658 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7105f898-f3be-4640-a638-f9c568d0864c, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:51,659 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7105f898-f3be-4640-a638-f9c568d0864c, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=" 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-27 12:38:51,661 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7105f898-f3be-4640-a638-f9c568d0864c, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:52,262 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:52,263 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:52,264 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b8cd676a-3a6c-4152-925e-1cac06286e32, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:52,264 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b8cd676a-3a6c-4152-925e-1cac06286e32, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=" 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-27 12:38:52,264 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b8cd676a-3a6c-4152-925e-1cac06286e32, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:52,764 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:52,765 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:52,765 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2245cef1-2023-451b-a1d8-11603fa3ee84, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:52,766 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2245cef1-2023-451b-a1d8-11603fa3ee84, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=" 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-27 12:38:52,767 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2245cef1-2023-451b-a1d8-11603fa3ee84, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:53,304 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:55,748 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==
2025-10-27 12:38:55,787 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:55,792 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0dd029e8-934b-45f3-a957-48358c31e73c, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:55,793 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0dd029e8-934b-45f3-a957-48358c31e73c, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=" 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-27 12:38:55,845 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0dd029e8-934b-45f3-a957-48358c31e73c, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:55,968 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:38:55,993 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==
2025-10-27 12:38:56,093 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 4529aa33-3c22-401a-b9e4-f30bf8e9f729, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:56,130 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 4529aa33-3c22-401a-b9e4-f30bf8e9f729, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==" 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-27 12:38:56,161 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 4529aa33-3c22-401a-b9e4-f30bf8e9f729, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:56,242 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:56,273 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:38:56,282 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d9d3987b-eecf-4f63-87c9-069b7b55ecd6, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:56,316 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d9d3987b-eecf-4f63-87c9-069b7b55ecd6, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=" 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-27 12:38:56,329 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d9d3987b-eecf-4f63-87c9-069b7b55ecd6, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:56,518 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==
2025-10-27 12:38:56,536 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:56,594 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 804ed4dd-d7f9-417c-9994-caf0ca3b2c35, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:56,595 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 804ed4dd-d7f9-417c-9994-caf0ca3b2c35, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=" 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-27 12:38:56,617 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 804ed4dd-d7f9-417c-9994-caf0ca3b2c35, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:56,632 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=
2025-10-27 12:38:56,753 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==
2025-10-27 12:38:56,793 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, aafa1b30-8d72-497d-a110-e8ba8d7aee83, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:56,824 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, aafa1b30-8d72-497d-a110-e8ba8d7aee83, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==" 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-27 12:38:56,874 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, aafa1b30-8d72-497d-a110-e8ba8d7aee83, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:57,026 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:38:57,091 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=
2025-10-27 12:38:57,111 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c5ee9357-a3d4-42b2-be3f-149c8beef849, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:57,111 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c5ee9357-a3d4-42b2-be3f-149c8beef849, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=" 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-27 12:38:57,149 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c5ee9357-a3d4-42b2-be3f-149c8beef849, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:57,163 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=
2025-10-27 12:38:57,204 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:38:57,205 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, d9cd57ba-347e-4997-b323-94e4473fd557, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:57,227 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, d9cd57ba-347e-4997-b323-94e4473fd557, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:57,228 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, d9cd57ba-347e-4997-b323-94e4473fd557, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:57,253 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:57,333 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=
2025-10-27 12:38:57,350 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:38:57,372 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 53c50db2-cf21-476f-9697-8382c973c654, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:57,420 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:38:57,429 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 53c50db2-cf21-476f-9697-8382c973c654, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=" 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-27 12:38:57,473 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3be66065-af0e-4149-ab31-a2d9ba51e200, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:57,479 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3be66065-af0e-4149-ab31-a2d9ba51e200, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:38:57,480 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3be66065-af0e-4149-ab31-a2d9ba51e200, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:57,478 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 53c50db2-cf21-476f-9697-8382c973c654, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:57,938 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-10-27 12:38:57,954 - DEBUG - ya.test - _save_outputs: Command (pid 3898811) rc: 0
2025-10-27 12:38:57,954 - DEBUG - ya.test - _save_outputs: Command (pid 3898811) elapsed time (sec): 17.665021896362305
2025-10-27 12:38:57,954 - DEBUG - ya.test - _save_outputs: Command (pid 3898811) wtime: 17.673
2025-10-27 12:38:57,970 - DEBUG - ya.test - _save_outputs: Command (pid 3898811) 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	     10	10	0	0	14399	17279	17279	17279

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

2025-10-27 12:38:57,971 - DEBUG - ya.test - _save_outputs: Command (pid 3898811) errors:

2025-10-27 12:38:58,273 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:38:58,305 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:38:58,306 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9a128279-ac7e-4b42-8731-a0deca83ac77, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:58,306 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9a128279-ac7e-4b42-8731-a0deca83ac77, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=" 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-27 12:38:58,307 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9a128279-ac7e-4b42-8731-a0deca83ac77, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:58,855 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:59,003 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:38:59,007 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, be15f116-e8ed-443d-b651-b20bc505ed24, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:59,008 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, be15f116-e8ed-443d-b651-b20bc505ed24, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=" 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-27 12:38:59,015 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, be15f116-e8ed-443d-b651-b20bc505ed24, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:59,239 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==
2025-10-27 12:38:59,245 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:59,266 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:38:59,273 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==
2025-10-27 12:38:59,287 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6e61b46d-2a48-49e1-b0bf-21ae6a774043, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:59,309 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6e61b46d-2a48-49e1-b0bf-21ae6a774043, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=" 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-27 12:38:59,310 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6e61b46d-2a48-49e1-b0bf-21ae6a774043, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:59,288 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==
2025-10-27 12:38:59,311 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 21f982eb-912e-496d-b144-637e9e3b1b11, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:59,311 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 21f982eb-912e-496d-b144-637e9e3b1b11, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==" 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-27 12:38:59,320 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 21f982eb-912e-496d-b144-637e9e3b1b11, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:38:59,317 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:38:59,326 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 282f96c7-9b8f-4fd5-9c39-b929d943e460, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:38:59,327 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 282f96c7-9b8f-4fd5-9c39-b929d943e460, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=" 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-27 12:38:59,329 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 282f96c7-9b8f-4fd5-9c39-b929d943e460, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:39:00,454 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:39:00,465 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-10-27 12:39:00,466 - 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-27 12:39:00,485 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-10-27 12:39:00,486 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 192f08fd-630a-4fc3-938a-fd7ca9ce5a17, localhost:18995): creating call state
2025-10-27 12:39:00,486 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 192f08fd-630a-4fc3-938a-fd7ca9ce5a17, localhost:18995): request = { database: "/Root" }
2025-10-27 12:39:00,494 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 192f08fd-630a-4fc3-938a-fd7ca9ce5a17, localhost:18995): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-5rmqn7spre.auto.internal" port: 18995 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-27 12:39:00,498 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:39:00,504 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:39:00,530 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDExOTIyNGUtYzM1Zjg0ZWQtOTI3OTU4NTMtYTMwODNhZWE=
2025-10-27 12:39:00,513 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MWI5YTJiMzItODg5MjYyMDQtNGM3MGU3NmQtMzgxY2IxOGU=
2025-10-27 12:39:00,517 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGQyZDYzMTktMjcwOTg0YzctNDVmZDNlNi1iNGI3MmQzNw==
2025-10-27 12:39:00,519 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-5rmqn7spre.auto.internal:18995, location 1, ssl: False>]>
2025-10-27 12:39:00,522 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YzljZjM4ZTktYzg1NjViNTUtMWM2NmNlYzQtY2M2ZTZkNjA=
2025-10-27 12:39:00,523 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YjVhMzQ1MGItZGIwODRkZDItMjk2NWY0MmMtNDQyOGQwNWU=
2025-10-27 12:39:00,510 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:39:00,539 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==
2025-10-27 12:39:00,541 - DEBUG - ydb.connection - close: Closing channel for endpoint localhost:18995
2025-10-27 12:39:00,549 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:39:00,550 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0f2c4523-7ade-41c5-a8d1-fcf062117073, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:39:00,551 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0f2c4523-7ade-41c5-a8d1-fcf062117073, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=" 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-27 12:39:00,552 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0f2c4523-7ade-41c5-a8d1-fcf062117073, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:39:00,553 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:39:00,553 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 97902c9c-324e-480c-9357-04a8859872be, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:39:00,554 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f63b2c29-a604-4611-9ca0-8cbd27511488, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:39:00,554 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:39:00,593 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 99d397b3-50c4-4550-8d9b-0b847dc4414b, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:39:00,566 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 97902c9c-324e-480c-9357-04a8859872be, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2))" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:39:00,573 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f63b2c29-a604-4611-9ca0-8cbd27511488, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=" 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-27 12:39:00,594 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 99d397b3-50c4-4550-8d9b-0b847dc4414b, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2))" } stats_mode: STATS_MODE_NONE }
2025-10-27 12:39:00,596 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 99d397b3-50c4-4550-8d9b-0b847dc4414b, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:39:00,595 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f63b2c29-a604-4611-9ca0-8cbd27511488, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:39:00,595 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 97902c9c-324e-480c-9357-04a8859872be, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:39:02,364 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=Yzk3ZWJkNjMtMTI2NGZlZTAtOWFkNWExNjctZWI1OGI2YTM=
2025-10-27 12:39:02,365 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=NGJhY2JhMWQtNzdjZGE3OTAtOGIxMjZjNzgtN2UxZDllZQ==
2025-10-27 12:39:02,365 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTZlM2ZhYjItY2Q4MTY0NmMtYzg2OTUwNzYtMWY3NzlkOWU=
2025-10-27 12:39:02,366 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=N2JjYWMzODYtMmNiNjc2NGItNjM2N2VjZTQtM2QxOThhYzc=
2025-10-27 12:39:02,396 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:39:02,398 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a443aa78-ae1d-42c9-b362-db39a8878676, ghrun-5rmqn7spre.auto.internal:28057): creating call state
2025-10-27 12:39:02,416 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a443aa78-ae1d-42c9-b362-db39a8878676, ghrun-5rmqn7spre.auto.internal:28057): request = { session_id: "ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=" 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-27 12:39:02,418 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a443aa78-ae1d-42c9-b362-db39a8878676, ghrun-5rmqn7spre.auto.internal:28057): response = { <_MultiThreadedRendezvous object> }
2025-10-27 12:39:02,843 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=OGY1NjZjYTEtYjc0MWIwNzgtYzliOGI3YmItNmZiNWNhMGM=
2025-10-27 12:39:02,843 - INFO - root - test_log_deviation: check insert: 28000 23000
2025-10-27 12:39:02,845 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.10
2025-10-27 12:39:02,846 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.10
2025-10-27 12:39:02,846 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/cpsh/0002e2/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:28057', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-10-27 12:39:02,852 - DEBUG - ya.test - execute: Command pid: 3909574
2025-10-27 12:39:03,076 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) rc: 0
2025-10-27 12:39:03,077 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) elapsed time (sec): 0.22350764274597168
2025-10-27 12:39:03,077 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) idrss: 0
2025-10-27 12:39:03,077 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) inblock: 0
2025-10-27 12:39:03,077 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) isrss: 0
2025-10-27 12:39:03,077 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) ixrss: 0
2025-10-27 12:39:03,086 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) majflt: 0
2025-10-27 12:39:03,086 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) maxrss: 301700
2025-10-27 12:39:03,086 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) minflt: 3491
2025-10-27 12:39:03,086 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) msgrcv: 0
2025-10-27 12:39:03,086 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) msgsnd: 0
2025-10-27 12:39:03,086 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) nivcsw: 45
2025-10-27 12:39:03,090 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) nsignals: 0
2025-10-27 12:39:03,091 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) nswap: 0
2025-10-27 12:39:03,092 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) nvcsw: 151
2025-10-27 12:39:03,093 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) oublock: 8
2025-10-27 12:39:03,093 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) stime: 0.022971
2025-10-27 12:39:03,094 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) utime: 0.055132
2025-10-27 12:39:03,094 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) wtime: 0.229
2025-10-27 12:39:03,095 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) output:
Remove path log...
Remove path log...Ok

2025-10-27 12:39:03,096 - DEBUG - ya.test - _save_outputs: Command (pid 3909574) errors:

2025-10-27 12:39:03,100 - INFO - ya.test - pytest_runtest_teardown: Test teardown
