Closed Bug 1685912 Opened 4 years ago Closed 3 years ago

Intermittent mochitest TEST-UNEXPECTED-TIMEOUT | Last test finished | application timed out after 370 seconds with no output

Categories

(Core :: DOM: Networking, defect, P5)

defect

Tracking

()

RESOLVED DUPLICATE of bug 1358898

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell disable-recommended])

Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=326295338&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/L9DOnmfMREm9f5ai4Z9cqg/runs/0/artifacts/public/logs/live_backing.log


[task 2021-01-10T13:12:43.283Z] 13:12:43     INFO - TEST-START | dom/fetch/tests/browser_blobFromFile.js
[task 2021-01-10T13:12:44.904Z] 13:12:44     INFO - GECKO(3793) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2021-01-10T13:12:44.904Z] 13:12:44     INFO - GECKO(3793) | MEMORY STAT heapAllocated not supported in this build configuration.
[task 2021-01-10T13:12:44.904Z] 13:12:44     INFO - GECKO(3793) | MEMORY STAT | vsize 130550970MB | residentFast 1709MB
[task 2021-01-10T13:12:44.904Z] 13:12:44     INFO - TEST-OK | dom/fetch/tests/browser_blobFromFile.js | took 1621ms
[task 2021-01-10T13:12:45.014Z] 13:12:45     INFO - checking window state
[task 2021-01-10T13:12:47.857Z] 13:12:47     INFO - GECKO(3793) | Completed ShutdownLeaks collections in process 3979
[task 2021-01-10T13:12:47.872Z] 13:12:47     INFO - GECKO(3793) | Completed ShutdownLeaks collections in process 3865
[task 2021-01-10T13:12:48.049Z] 13:12:48     INFO - GECKO(3793) | Completed ShutdownLeaks collections in process 3890
[task 2021-01-10T13:12:48.165Z] 13:12:48     INFO - GECKO(3793) | Completed ShutdownLeaks collections in process 3947
[task 2021-01-10T13:18:58.178Z] 13:18:58     INFO - Buffered messages finished
[task 2021-01-10T13:18:58.179Z] 13:18:58    ERROR - TEST-UNEXPECTED-TIMEOUT | Last test finished | application timed out after 370 seconds with no output
[task 2021-01-10T13:18:58.180Z] 13:18:58    ERROR - Force-terminating active process(es).```
Summary: Intermittent TEST-UNEXPECTED-TIMEOUT | Last test finished | application timed out after 370 seconds with no output → Intermittent mochitest TEST-UNEXPECTED-TIMEOUT | Last test finished | application timed out after 370 seconds with no output
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

There have been a total of 31 failures in the past 7 days.

Platforms affected:

  • linux1804-64 opt/debug
  • linux1804-64-asan opt
  • linux1804-64-asan-webrender opt

here have been 53 total failures in the last 7 days, recent failure log.
Affected platforms are:

  • linux1804-64-ccov-qr
  • linux1804-64-qr
  • linux1804-64-tsan-qr
  • macosx1015-64-qr
  • macosx1015-64-shippable-qr
task 2021-09-30T01:21:46.845Z] 01:21:46     INFO - GECKO(1542) | [Parent 1542, Main Thread] WARNING: NS_ENSURE_TRUE(mReady) failed: file /builds/worker/checkouts/gecko/xpfe/appshell/nsWindowMediator.cpp:161
[task 2021-09-30T01:21:46.846Z] 01:21:46     INFO - GECKO(1542) | JavaScript error: resource:///modules/sessionstore/SessionStore.jsm, line 5097: NS_ERROR_UNEXPECTED: Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIWindowMediator.getEnumerator]
[task 2021-09-30T01:22:39.123Z] 01:22:39     INFO - GECKO(1542) | XPCOM_MEM_BLOAT_LOG: /tmp/tmp1oqanzxw.mozrunner/runtests_leaks.log
[task 2021-09-30T01:22:39.125Z] 01:22:39     INFO - GECKO(1542) | Writing to log: /tmp/tmp1oqanzxw.mozrunner/runtests_leaks.log
[task 2021-09-30T01:22:40.137Z] 01:22:40     INFO - GECKO(1542) | Hit MOZ_CRASH(Shutdown hanging after all known phases and workers finished.) at /builds/worker/checkouts/gecko/toolkit/components/terminator/nsTerminator.cpp:246
[task 2021-09-30T01:22:40.139Z] 01:22:40     INFO - GECKO(1542) | #01: mozilla::(anonymous namespace)::RunWatchdog(void*) [toolkit/components/terminator/nsTerminator.cpp:246]
[task 2021-09-30T01:22:40.155Z] 01:22:40     INFO - GECKO(1542) | #02: _pt_root [nsprpub/pr/src/pthreads/ptthread.c:204]
[task 2021-09-30T01:22:40.158Z] 01:22:40     INFO - GECKO(1542) | #03: ??? [/lib/x86_64-linux-gnu/libpthread.so.0 + 0x76db]
[task 2021-09-30T01:22:40.160Z] 01:22:40     INFO - GECKO(1542) | #04: clone [/lib/x86_64-linux-gnu/libc.so.6 + 0x121a3f]
[task 2021-09-30T01:22:40.163Z] 01:22:40     INFO - GECKO(1542) | #05: ??? (???:???)
[task 2021-09-30T01:22:40.164Z] 01:22:40     INFO - GECKO(1542) | ExceptionHandler::GenerateDump cloned child 13007
[task 2021-09-30T01:22:40.165Z] 01:22:40     INFO - GECKO(1542) | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2021-09-30T01:22:40.165Z] 01:22:40     INFO - GECKO(1542) | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2021-09-30T01:22:40.318Z] 01:22:40     INFO - GECKO(1542) | [Child 11225, IPC I/O Child] WARNING: [A906E2DFE15A4950.59CFCCA1BACC26FA]: Ignoring message 'EVENT_MESSAGE' to peer 1.1 due to a missing broker: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:297
[task 2021-09-30T01:22:40.320Z] 01:22:40     INFO - GECKO(1542) | [Child 11225, IPC I/O Child] WARNING: [A906E2DFE15A4950.59CFCCA1BACC26FA]: Ignoring message 'EVENT_MESSAGE' to peer 1.1 due to a missing broker: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:297
[task 2021-09-30T01:22:40.324Z] 01:22:40     INFO - GECKO(1542) | [Child 11225, IPC I/O Child] WARNING: [A906E2DFE15A4950.59CFCCA1BACC26FA]: Ignoring message 'EVENT_MESSAGE' to peer 1.1 due to a missing broker: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:297
[task 2021-09-30T01:22:40.326Z] 01:22:40     INFO - GECKO(1542) | [Child 11225, IPC I/O Child] WARNING: [A906E2DFE15A4950.59CFCCA1BACC26FA]: Ignoring message 'EVENT_MESSAGE' to peer 1.1 due to a missing broker: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:297
[task 2021-09-30T01:22:40.329Z] 01:22:40     INFO - GECKO(1542) | [Child 11225, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-09-30T01:22:40.330Z] 01:22:40     INFO - GECKO(1542) | [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2021-09-30T01:28:50.337Z] 01:28:50     INFO - Buffered messages finished
[task 2021-09-30T01:28:50.338Z] 01:28:50    ERROR - TEST-UNEXPECTED-TIMEOUT | Last test finished | application timed out after 370 seconds with no output
[task 2021-09-30T01:28:50.338Z] 01:28:50    ERROR - Force-terminating active process(es).
[task 2021-09-30T01:28:50.339Z] 01:28:50     INFO - Determining child pids from psutil...
[task 2021-09-30T01:28:50.371Z] 01:28:50     INFO - []
[task 2021-09-30T01:28:50.374Z] 01:28:50     INFO - ==> process 1542 launched child process 1560

There have been 69 total failures in the last 7 days, recent failure log.
Affected platforms are:

  • linux1804-64-ccov-qr
  • linux1804-64-qr
  • linux1804-64-tsan-qr
  • macosx1015-64-qr
  • macosx1015-64-shippable-qr
Flags: needinfo?(jstutte)

So these are probably just random shutdown hangs like bug 1358898.
In the linux flavor of the logs I looked at we directly see the:

[task 2021-10-06T17:13:59.775Z] 17:13:59     INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ _pt_root]
[task 2021-10-06T17:13:59.775Z] 17:13:59     INFO - Mozilla crash reason: MOZ_CRASH(Shutdown hanging after all known phases and workers finished.)

In the OSX version of the hang there is no shutdown crash, not sure what that means. But I think, this is just a dupe of bug 1358898.

Flags: needinfo?(jstutte)

I checked the last 15 failure logs and all f them seem to indicate 1358898, same for the existing mac failures classified here.
Based on above comment from Jens, closing as a duplicate of bug 1358898.

Status: REOPENED → RESOLVED
Closed: 4 years ago3 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.