2025-11-14 21:11:35,194 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 21:11:35,210 - INFO - ya.test - pytest_runtest_setup: test_log_deviation[180]
2025-11-14 21:11:35,210 - INFO - ya.test - pytest_runtest_setup: ####################################################################################################
2025-11-14 21:11:35,210 - INFO - ya.test - pytest_runtest_setup: Test setup
2025-11-14 21:11:35,236 - INFO - ya.test - pytest_runtest_call: Test call (class_name: test_log_scenario.py::TestLogScenario, test_name: test_log_deviation[180])
2025-11-14 21:11:35,237 - DEBUG - ya.test - get_binary: Binary was found by /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb
2025-11-14 21:11:35,237 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb -e grpc://localhost:18008 -d /Root workload log --path log init --path log --store column --ttl 1000 with wait = True
2025-11-14 21:11:35,244 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.5
2025-11-14 21:11:35,248 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.5
2025-11-14 21:11:35,249 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-d', '/Root', 'workload', 'log', '--path', 'log', 'init', '--path', 'log', '--store', 'column', '--ttl', '1000']' in 'None' (waiting)
2025-11-14 21:11:35,264 - DEBUG - ya.test - execute: Command pid: 3685678
2025-11-14 21:11:36,047 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) rc: 0
2025-11-14 21:11:36,047 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) elapsed time (sec): 0.769965648651123
2025-11-14 21:11:36,047 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) idrss: 0
2025-11-14 21:11:36,048 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) inblock: 0
2025-11-14 21:11:36,048 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) isrss: 0
2025-11-14 21:11:36,048 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) ixrss: 0
2025-11-14 21:11:36,048 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) majflt: 0
2025-11-14 21:11:36,048 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) maxrss: 684424
2025-11-14 21:11:36,048 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) minflt: 3485
2025-11-14 21:11:36,048 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) msgrcv: 0
2025-11-14 21:11:36,049 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) msgsnd: 0
2025-11-14 21:11:36,067 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) nivcsw: 48
2025-11-14 21:11:36,073 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) nsignals: 0
2025-11-14 21:11:36,074 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) nswap: 0
2025-11-14 21:11:36,074 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) nvcsw: 192
2025-11-14 21:11:36,094 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) oublock: 8
2025-11-14 21:11:36,095 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) stime: 0.039382
2025-11-14 21:11:36,095 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) utime: 0.056886
2025-11-14 21:11:36,095 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) wtime: 0.791
2025-11-14 21:11:36,095 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) output:
Init tables ...
Init tables ...Ok

2025-11-14 21:11:36,101 - DEBUG - ya.test - _save_outputs: Command (pid 3685678) errors:

2025-11-14 21:11:36,119 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:11:36,120 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0bb2c669-197a-4c4d-952f-ca28fcbd4d8f, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:36,156 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0bb2c669-197a-4c4d-952f-ca28fcbd4d8f, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=" 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-11-14 21:11:36,157 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0bb2c669-197a-4c4d-952f-ca28fcbd4d8f, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:36,237 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:11:36,237 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-14 21:11:36,237 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb -e grpc://localhost:18008 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-14 21:11:36,238 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.6
2025-11-14 21:11:36,239 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.6
2025-11-14 21:11:36,239 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-14 21:11:36,250 - DEBUG - ya.test - execute: Command pid: 3685978
2025-11-14 21:11:40,499 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-14 21:11:40,501 - DEBUG - ya.test - _save_outputs: Command (pid 3685978) rc: 0
2025-11-14 21:11:40,501 - DEBUG - ya.test - _save_outputs: Command (pid 3685978) elapsed time (sec): 4.2236716747283936
2025-11-14 21:11:40,502 - DEBUG - ya.test - _save_outputs: Command (pid 3685978) wtime: 4.261
2025-11-14 21:11:40,503 - DEBUG - ya.test - _save_outputs: Command (pid 3685978) output:
Window	    Txs	Txs/Sec	Retries	Errors	p50(ms)	p95(ms)	p99(ms)	pMax(ms)
1	      2	2	0	0	647	947	947	947
2	      9	9	0	0	1199	1431	1431	1431
3	     19	19	0	0	1183	1247	1255	1255

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

2025-11-14 21:11:40,503 - DEBUG - ya.test - _save_outputs: Command (pid 3685978) errors:

2025-11-14 21:11:40,504 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:11:40,505 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, fa39e871-734d-44aa-9fe0-5e0698ba56e9, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:40,507 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, fa39e871-734d-44aa-9fe0-5e0698ba56e9, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:40,508 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, fa39e871-734d-44aa-9fe0-5e0698ba56e9, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:41,847 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:11:41,876 - INFO - root - test_log_deviation: Count rows after insert 15000 before wait
2025-11-14 21:11:41,878 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:11:41,879 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e3766cc4-e7ed-414a-bf0a-f98e721981b6, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:41,880 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e3766cc4-e7ed-414a-bf0a-f98e721981b6, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:41,881 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e3766cc4-e7ed-414a-bf0a-f98e721981b6, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:42,790 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:11:42,790 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:11:42,796 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 7546df83-7d6e-41ce-9108-4c84596a9ea1, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:42,799 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 7546df83-7d6e-41ce-9108-4c84596a9ea1, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:42,809 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 7546df83-7d6e-41ce-9108-4c84596a9ea1, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:43,656 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:11:43,657 - INFO - root - _insert_rows: YdbWorkloadLog bulk_upsert
2025-11-14 21:11:43,658 - INFO - root - _insert_rows: YdbWorkloadLog insert
2025-11-14 21:11:43,658 - INFO - root - _insert_rows: YdbWorkloadLog upsert
2025-11-14 21:11:43,660 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb -e grpc://localhost:18008 -d /Root workload log --path log run upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-14 21:11:43,659 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:11:43,659 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb -e grpc://localhost:18008 -d /Root workload log --path log run insert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-14 21:11:43,661 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.7
2025-11-14 21:11:43,658 - INFO - root - _call: YdbWorkloadLog execute /home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb -e grpc://localhost:18008 -d /Root workload log --path log run bulk_upsert --seconds 3 --threads 10 --rows 500 --timestamp_deviation 180 with wait = True
2025-11-14 21:11:43,663 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 91b7c6e4-914c-480c-93d8-58a2f92d4e8c, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:43,671 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.8
2025-11-14 21:11:43,675 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:11:43,676 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.7
2025-11-14 21:11:43,688 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.9
2025-11-14 21:11:43,691 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 91b7c6e4-914c-480c-93d8-58a2f92d4e8c, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:43,699 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bbf92b59-bff0-4744-848e-eb2fde6f0d7c, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:43,707 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:11:43,707 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.8
2025-11-14 21:11:43,708 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-14 21:11:43,709 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 91b7c6e4-914c-480c-93d8-58a2f92d4e8c, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:43,710 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bbf92b59-bff0-4744-848e-eb2fde6f0d7c, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:43,711 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a2c2184f-aa64-4e2b-b4d0-fd7bf7c19ed7, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:43,711 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:11:43,721 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3c019a6d-08a4-4618-b501-ffb3f73335e4, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:43,712 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'insert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-14 21:11:43,714 - DEBUG - ya.test - execute: Command pid: 3691444
2025-11-14 21:11:43,720 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bbf92b59-bff0-4744-848e-eb2fde6f0d7c, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:43,720 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a2c2184f-aa64-4e2b-b4d0-fd7bf7c19ed7, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:43,732 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a2c2184f-aa64-4e2b-b4d0-fd7bf7c19ed7, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:43,712 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.9
2025-11-14 21:11:43,733 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-d', '/Root', 'workload', 'log', '--path', 'log', 'run', 'bulk_upsert', '--seconds', '3', '--threads', '10', '--rows', '500', '--timestamp_deviation', '180']' in 'None' (waiting)
2025-11-14 21:11:43,727 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:11:43,737 - DEBUG - ya.test - execute: Command pid: 3691462
2025-11-14 21:11:43,738 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, da962de6-276e-4c81-a678-f898b7a4cd99, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:43,721 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3c019a6d-08a4-4618-b501-ffb3f73335e4, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:43,727 - DEBUG - ya.test - execute: Command pid: 3691459
2025-11-14 21:11:43,742 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, da962de6-276e-4c81-a678-f898b7a4cd99, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:43,738 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:11:43,764 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3c019a6d-08a4-4618-b501-ffb3f73335e4, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:43,772 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, da962de6-276e-4c81-a678-f898b7a4cd99, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:43,779 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cdb09131-1809-4fa5-b2f1-474e9644075e, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:43,782 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cdb09131-1809-4fa5-b2f1-474e9644075e, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:43,780 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:11:43,784 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cdb09131-1809-4fa5-b2f1-474e9644075e, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:43,786 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 3b3d78d9-860b-4141-8871-aefbfa62a311, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:43,786 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:11:43,788 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 3b3d78d9-860b-4141-8871-aefbfa62a311, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:43,789 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6f123287-36dc-44bf-804c-6980ef56f3d1, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:43,790 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 3b3d78d9-860b-4141-8871-aefbfa62a311, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:43,789 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:11:43,791 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6f123287-36dc-44bf-804c-6980ef56f3d1, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:43,797 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ed2060b1-3890-4a59-8fd7-9cfeb1a2c4f2, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:43,798 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:11:43,800 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bd131e4b-3923-4f29-aca0-7a97c1368d64, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:43,799 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ed2060b1-3890-4a59-8fd7-9cfeb1a2c4f2, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:43,807 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ed2060b1-3890-4a59-8fd7-9cfeb1a2c4f2, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:43,799 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6f123287-36dc-44bf-804c-6980ef56f3d1, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:43,801 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bd131e4b-3923-4f29-aca0-7a97c1368d64, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` " } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:43,812 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bd131e4b-3923-4f29-aca0-7a97c1368d64, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:48,471 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-14 21:11:48,475 - DEBUG - ya.test - _save_outputs: Command (pid 3691444) rc: 0
2025-11-14 21:11:48,476 - DEBUG - ya.test - _save_outputs: Command (pid 3691444) elapsed time (sec): 4.745896577835083
2025-11-14 21:11:48,477 - DEBUG - ya.test - _save_outputs: Command (pid 3691444) wtime: 4.762
2025-11-14 21:11:48,479 - DEBUG - ya.test - _save_outputs: Command (pid 3691444) 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	14	0	0	0	0

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

2025-11-14 21:11:48,479 - DEBUG - ya.test - _save_outputs: Command (pid 3691444) errors:

2025-11-14 21:11:48,514 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-14 21:11:48,515 - DEBUG - ya.test - _save_outputs: Command (pid 3691459) rc: 0
2025-11-14 21:11:48,522 - DEBUG - ya.test - _save_outputs: Command (pid 3691459) elapsed time (sec): 4.743416786193848
2025-11-14 21:11:48,524 - DEBUG - ya.test - _save_outputs: Command (pid 3691459) wtime: 4.793
2025-11-14 21:11:48,525 - DEBUG - ya.test - _save_outputs: Command (pid 3691459) 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	14	0	0	0	0

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

2025-11-14 21:11:48,525 - DEBUG - ya.test - _save_outputs: Command (pid 3691459) errors:

2025-11-14 21:11:58,156 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:11:58,156 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:11:58,157 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, bc8591bc-4040-45bf-9f1b-95cdb753bc7d, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:58,161 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, bc8591bc-4040-45bf-9f1b-95cdb753bc7d, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:58,167 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, bc8591bc-4040-45bf-9f1b-95cdb753bc7d, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:58,434 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:11:58,435 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:11:58,435 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 41affeb4-45cb-49eb-86f3-6996d819fa21, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:58,437 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 41affeb4-45cb-49eb-86f3-6996d819fa21, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:58,439 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 41affeb4-45cb-49eb-86f3-6996d819fa21, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:58,478 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:11:58,478 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:11:58,479 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, ca4ffdae-d03e-4cda-a43d-83c84de9a66e, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:58,484 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, ca4ffdae-d03e-4cda-a43d-83c84de9a66e, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:58,485 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, ca4ffdae-d03e-4cda-a43d-83c84de9a66e, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:58,583 - DEBUG - ya.test - _wait: Process resource usage is not available as process finished before wait4 was called
2025-11-14 21:11:58,613 - DEBUG - ya.test - _save_outputs: Command (pid 3691462) rc: 0
2025-11-14 21:11:58,631 - DEBUG - ya.test - _save_outputs: Command (pid 3691462) elapsed time (sec): 14.871233463287354
2025-11-14 21:11:58,638 - DEBUG - ya.test - _save_outputs: Command (pid 3691462) wtime: 14.879
2025-11-14 21:11:58,639 - DEBUG - ya.test - _save_outputs: Command (pid 3691462) 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	13311	14399	14399	14399

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

2025-11-14 21:11:58,639 - DEBUG - ya.test - _save_outputs: Command (pid 3691462) errors:

2025-11-14 21:11:59,077 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:11:59,079 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:11:59,080 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 396e35c6-16ab-4047-a33b-dc16de42228d, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:59,080 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 396e35c6-16ab-4047-a33b-dc16de42228d, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:59,082 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 396e35c6-16ab-4047-a33b-dc16de42228d, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:59,183 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:11:59,185 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:11:59,185 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cbc2639a-52f0-4b56-817f-a45a77a6b337, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:59,186 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cbc2639a-52f0-4b56-817f-a45a77a6b337, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:59,191 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cbc2639a-52f0-4b56-817f-a45a77a6b337, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:59,391 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:11:59,392 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:11:59,392 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 8def09ef-2ea2-4bc4-b118-38943067409e, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:59,395 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 8def09ef-2ea2-4bc4-b118-38943067409e, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:59,396 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 8def09ef-2ea2-4bc4-b118-38943067409e, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:59,503 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:11:59,504 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:11:59,504 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:11:59,506 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, f0aac417-f073-4f68-8fa2-66eae495a845, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:59,507 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, f0aac417-f073-4f68-8fa2-66eae495a845, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:59,505 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:11:59,508 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, f0aac417-f073-4f68-8fa2-66eae495a845, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:59,508 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:11:59,510 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:11:59,510 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 99b1ab30-721f-4713-9930-9da54d7afcb9, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:59,511 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:11:59,515 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 99b1ab30-721f-4713-9930-9da54d7afcb9, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:59,517 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:11:59,516 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 154212a2-7c1a-44e9-a82c-1948c35c9228, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:59,517 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, c190b471-d497-4bc3-b508-e3a36f278944, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:11:59,519 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 154212a2-7c1a-44e9-a82c-1948c35c9228, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:59,520 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 99b1ab30-721f-4713-9930-9da54d7afcb9, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:59,520 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, c190b471-d497-4bc3-b508-e3a36f278944, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT * FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:11:59,522 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 154212a2-7c1a-44e9-a82c-1948c35c9228, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:11:59,524 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, c190b471-d497-4bc3-b508-e3a36f278944, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:03,336 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:12:03,337 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:12:03,338 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e1fba36c-0095-4178-b7d9-c03ea8fc7f7e, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:03,338 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e1fba36c-0095-4178-b7d9-c03ea8fc7f7e, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(9)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:03,343 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e1fba36c-0095-4178-b7d9-c03ea8fc7f7e, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:03,698 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:12:03,698 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:12:03,700 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 6a4aeb13-ed41-474c-b81f-e72f14fc3c4e, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:03,747 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 6a4aeb13-ed41-474c-b81f-e72f14fc3c4e, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(10)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(9))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:03,748 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 6a4aeb13-ed41-474c-b81f-e72f14fc3c4e, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:04,490 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:12:05,292 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:12:05,298 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:12:05,316 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 48141d8a-5315-4237-ab4e-2bfef50d9c41, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:05,318 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 48141d8a-5315-4237-ab4e-2bfef50d9c41, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:05,324 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 48141d8a-5315-4237-ab4e-2bfef50d9c41, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:07,824 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:12:07,829 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:12:07,834 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 503a9b6e-0a8a-4fc5-94bc-6535c3f1774f, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:07,834 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 503a9b6e-0a8a-4fc5-94bc-6535c3f1774f, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(6)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:07,836 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 503a9b6e-0a8a-4fc5-94bc-6535c3f1774f, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:08,259 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:12:08,265 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:12:08,265 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 954c9f38-8388-4ffe-b8da-5d9ff1c5aedd, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:08,266 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 954c9f38-8388-4ffe-b8da-5d9ff1c5aedd, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(8)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(7))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:08,266 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 954c9f38-8388-4ffe-b8da-5d9ff1c5aedd, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:10,907 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:12:10,908 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:12:10,908 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 580372bc-dea9-4982-b112-716a8a65ea6f, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:10,909 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 580372bc-dea9-4982-b112-716a8a65ea6f, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(7)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(6))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:10,910 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 580372bc-dea9-4982-b112-716a8a65ea6f, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:11,417 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:12:11,460 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:12:11,475 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 646d5c87-1f60-4bf8-aa70-6f5705f06a0d, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:11,476 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 646d5c87-1f60-4bf8-aa70-6f5705f06a0d, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:11,487 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 646d5c87-1f60-4bf8-aa70-6f5705f06a0d, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:11,630 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:12:11,711 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:12:11,768 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:12:11,777 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, b4a1e2c9-f005-4e65-b0a8-f4679e746d9a, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:11,778 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, b4a1e2c9-f005-4e65-b0a8-f4679e746d9a, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:11,788 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, b4a1e2c9-f005-4e65-b0a8-f4679e746d9a, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:11,814 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:12:11,872 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:12:11,889 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, cd5ad643-1946-424d-b90d-1a3f3354f79b, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:11,890 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, cd5ad643-1946-424d-b90d-1a3f3354f79b, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:11,890 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, cd5ad643-1946-424d-b90d-1a3f3354f79b, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:16,872 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:12:16,965 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:12:17,021 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:12:17,022 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0bdf5117-c840-4a66-94a5-f0f5e63d614a, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:17,022 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0bdf5117-c840-4a66-94a5-f0f5e63d614a, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(3)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:17,024 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0bdf5117-c840-4a66-94a5-f0f5e63d614a, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:17,095 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:12:17,305 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:12:17,306 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, a57e9f90-3ada-492c-a812-9d993d6475a2, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:17,306 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, a57e9f90-3ada-492c-a812-9d993d6475a2, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:17,307 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, a57e9f90-3ada-492c-a812-9d993d6475a2, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:17,329 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:12:17,565 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:12:17,565 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:12:17,566 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 9dcecf10-affb-423a-a83e-49ef1923a328, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:17,566 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:12:17,572 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:12:17,571 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 9dcecf10-affb-423a-a83e-49ef1923a328, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(5)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(4))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:17,585 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 9dcecf10-affb-423a-a83e-49ef1923a328, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:17,582 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:12:17,586 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e422667e-5c93-4838-94e4-a383796f3647, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:17,567 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:12:17,581 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 2031e370-634c-49a6-90a7-9e7dbf39326e, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:17,586 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e422667e-5c93-4838-94e4-a383796f3647, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:17,593 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:12:17,594 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:12:17,595 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 2031e370-634c-49a6-90a7-9e7dbf39326e, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(5)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(4))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:17,689 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 2031e370-634c-49a6-90a7-9e7dbf39326e, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:17,597 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e422667e-5c93-4838-94e4-a383796f3647, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:17,597 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 46409612-b342-4b69-9739-440bec925022, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:17,692 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 46409612-b342-4b69-9739-440bec925022, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(5)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(4))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:17,693 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 46409612-b342-4b69-9739-440bec925022, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:17,692 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:12:17,694 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 0fc4233f-aef8-4f2c-ada4-53c92c1e261a, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:17,694 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 0fc4233f-aef8-4f2c-ada4-53c92c1e261a, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE timestamp < CurrentUtcTimestamp() - DateTime::IntervalFromHours(1)" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:17,695 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 0fc4233f-aef8-4f2c-ada4-53c92c1e261a, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:25,004 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:12:25,019 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:12:25,063 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTFlYjA5YTItYjk1NTEwODMtZDNjNTBjYTYtZDIxNTY5ZDY=
2025-11-14 21:12:25,183 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZTcwZWQ2MTUtMmYxOTQ2N2MtZmRkODE3MjgtNmFmZTE2YmQ=
2025-11-14 21:12:25,184 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:12:25,184 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 24e349b0-1209-409c-99ad-31d9fd8634f7, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:25,199 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 24e349b0-1209-409c-99ad-31d9fd8634f7, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(4)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(3))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:25,206 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 24e349b0-1209-409c-99ad-31d9fd8634f7, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:25,355 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVjODI0MTYtYjg4MjhlY2ItNGFmODUzOGQtNGVlZjA5NDE=
2025-11-14 21:12:25,363 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:12:25,363 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 469c9a3a-35c9-4649-83ab-173a3e2e83cd, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:25,364 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 469c9a3a-35c9-4649-83ab-173a3e2e83cd, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(1))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:25,375 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 469c9a3a-35c9-4649-83ab-173a3e2e83cd, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:25,383 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YThkNjk2NTItNDcwNWNhYTEtMzZmY2QyZjUtZmExMzY3MGU=
2025-11-14 21:12:25,384 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:12:25,384 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 289b1501-7ae6-4ad0-8e11-a0e938af0258, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:25,385 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 289b1501-7ae6-4ad0-8e11-a0e938af0258, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(1))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:25,393 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=MTM1NTFjZWQtNzA4NTRhZC0zYmJkNzRmNS1jMTI3NWYyNQ==
2025-11-14 21:12:25,416 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 289b1501-7ae6-4ad0-8e11-a0e938af0258, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:25,425 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:12:25,426 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, e932959d-9668-4611-b7db-86bdde7cee70, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:25,427 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, e932959d-9668-4611-b7db-86bdde7cee70, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "SELECT COUNT(*) FROM `log` WHERE (timestamp >= CurrentUtcTimestamp() - DateTime::IntervalFromHours(2)) AND (timestamp <= CurrentUtcTimestamp() - DateTime::IntervalFromHours(1))" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:25,428 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, e932959d-9668-4611-b7db-86bdde7cee70, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:28,168 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Preparing initial endpoint to resolve endpoints
2025-11-14 21:12:28,203 - DEBUG - ydb.connection - channel_factory: Channel options: [('grpc.max_receive_message_length', 64000000), ('grpc.max_send_message_length', 64000000), ('grpc.primary_user_agent', 'python-library'), ('grpc.lb_policy_name', 'round_robin'), ('grpc.keepalive_time_ms', 1250), ('grpc.keepalive_timeout_ms', 10000), ('grpc.http2.max_pings_without_data', 0), ('grpc.keepalive_permit_without_calls', 0)]
2025-11-14 21:12:28,219 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - context_resolve: Resolving endpoints for database /Root
2025-11-14 21:12:28,221 - DEBUG - ydb.connection - _prepare_call: RpcState(ListEndpoints, 27dfa2bf-6292-4c1c-81bc-78faf556a6b7, localhost:18008): creating call state
2025-11-14 21:12:28,222 - DEBUG - ydb.connection - _log_request: RpcState(ListEndpoints, 27dfa2bf-6292-4c1c-81bc-78faf556a6b7, localhost:18008): request = { database: "/Root" }
2025-11-14 21:12:28,289 - DEBUG - ydb.connection - _log_response: RpcState(ListEndpoints, 27dfa2bf-6292-4c1c-81bc-78faf556a6b7, localhost:18008): response = { operation { ready: true status: SUCCESS result { [type.googleapis.com/Ydb.Discovery.ListEndpointsResult] { endpoints { address: "ghrun-alaktn3s7q.auto.internal" port: 18008 service: "legacy" service: "tablet_service" service: "yql" service: "discovery" service: "cms" service: "locking" service: "kesus" service: "pq" service: "pqcd" service: "pqv1" service: "topic" service: "datastreams" service: "scripting" service: "clickhouse_internal" service: "rate_limiter" service: "analytics" service: "export" service: "import" service: "yq" service: "keyvalue" service: "monitoring" service: "auth" service: "query_service" service: "view" location: "1" node_id: 1 } self_location: "1" } } } }
2025-11-14 21:12:28,292 - DEBUG - ydb.resolver.DiscoveryEndpointsResolver - _add_debug_details: Resolved endpoints for database /Root: DiscoveryResult <self_location: 1, endpoints [<Endpoint ghrun-alaktn3s7q.auto.internal:18008, location 1, ssl: False>]>
2025-11-14 21:12:28,302 - INFO - ydb.connection - close: Closing channel for endpoint localhost:18008
2025-11-14 21:12:30,006 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjMwMTYwN2MtZmRiMTM1ZGUtZDkzMTBiMzAtMmEyODI1NTk=
2025-11-14 21:12:30,106 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=YTliMzRhYzAtMTEzYjZkMzUtOTIzNTU4ZTEtODgzOWM5MQ==
2025-11-14 21:12:30,128 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZjE3ZWI1MjktY2U5MzgyMTYtOGMxM2QyMWUtYWQ0NTBkMjg=
2025-11-14 21:12:30,131 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ZDJmNzhhZjAtZDAwMzVmZmItNWZmYWVjMGMtMjdjYTg3ZA==
2025-11-14 21:12:30,137 - DEBUG - ydb.query.pool - acquire: Acquired active session from queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:12:30,151 - DEBUG - ydb.connection - _prepare_call: RpcState(ExecuteQuery, 85f78055-46cb-4556-898b-f87a977a94c3, ghrun-alaktn3s7q.auto.internal:18008): creating call state
2025-11-14 21:12:30,152 - DEBUG - ydb.connection - _log_request: RpcState(ExecuteQuery, 85f78055-46cb-4556-898b-f87a977a94c3, ghrun-alaktn3s7q.auto.internal:18008): request = { session_id: "ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=" exec_mode: EXEC_MODE_EXECUTE query_content { syntax: SYNTAX_YQL_V1 text: "select count(*) as Rows from `log`" } stats_mode: STATS_MODE_NONE }
2025-11-14 21:12:30,152 - DEBUG - ydb.connection - _log_response: RpcState(ExecuteQuery, 85f78055-46cb-4556-898b-f87a977a94c3, ghrun-alaktn3s7q.auto.internal:18008): response = { <_MultiThreadedRendezvous object> }
2025-11-14 21:12:31,397 - DEBUG - ydb.query.pool - release: Session returned to queue: ydb://session/3?node_id=1&id=ODVlMWRlZGItMTkyMTJlMzgtZmNmNDMxNTgtODc0YTcwMjY=
2025-11-14 21:12:31,412 - INFO - root - test_log_deviation: check insert: 20000 15000
2025-11-14 21:12:31,418 - DEBUG - ya.test - _get_command_output_file: Command out will be placed to ydb.out.10
2025-11-14 21:12:31,427 - DEBUG - ya.test - _get_command_output_file: Command err will be placed to ydb.err.10
2025-11-14 21:12:31,434 - DEBUG - ya.test - execute: Executing '['/home/runner/.ya/build/build_root/9c3s/0007d8/ydb/apps/ydb/ydb', '-e', 'grpc://localhost:18008', '-d', '/Root', 'workload', 'log', '--path', 'log', 'clean']' in 'None' (waiting)
2025-11-14 21:12:31,437 - DEBUG - ya.test - execute: Command pid: 3717235
2025-11-14 21:12:31,777 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) rc: 0
2025-11-14 21:12:31,778 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) elapsed time (sec): 0.3395838737487793
2025-11-14 21:12:31,778 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) idrss: 0
2025-11-14 21:12:31,780 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) inblock: 0
2025-11-14 21:12:31,781 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) isrss: 0
2025-11-14 21:12:31,781 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) ixrss: 0
2025-11-14 21:12:31,781 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) majflt: 0
2025-11-14 21:12:31,781 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) maxrss: 684424
2025-11-14 21:12:31,781 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) minflt: 3497
2025-11-14 21:12:31,782 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) msgrcv: 0
2025-11-14 21:12:31,782 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) msgsnd: 0
2025-11-14 21:12:31,782 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) nivcsw: 66
2025-11-14 21:12:31,788 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) nsignals: 0
2025-11-14 21:12:31,789 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) nswap: 0
2025-11-14 21:12:31,794 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) nvcsw: 135
2025-11-14 21:12:31,795 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) oublock: 8
2025-11-14 21:12:31,795 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) stime: 0.028252
2025-11-14 21:12:31,796 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) utime: 0.051795
2025-11-14 21:12:31,796 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) wtime: 0.342
2025-11-14 21:12:31,797 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) output:
Remove path log...
Remove path log...Ok

2025-11-14 21:12:31,797 - DEBUG - ya.test - _save_outputs: Command (pid 3717235) errors:

2025-11-14 21:12:31,804 - INFO - ya.test - pytest_runtest_teardown: Test teardown
