Closed Bug 1583391 Opened 6 years ago Closed 6 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | devtools/client/webconsole/test/browser/browser_webconsole_observer_notifications.js | application timed out after 370 seconds with no output

Categories

(DevTools :: Console, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, regression)

Filed by: aiakab [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=268042237&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/R9UBrChuTRWLGizTt6MbAw/runs/0/artifacts/public/logs/live_backing.log


[task 2019-09-23T22:10:19.837Z] 22:10:19 INFO - TEST-START | devtools/client/webconsole/test/browser/browser_webconsole_observer_notifications.js
[task 2019-09-23T22:17:02.482Z] 22:17:01 INFO - Buffered messages logged at 22:10:19
[task 2019-09-23T22:17:19.493Z] 22:17:19 INFO - Entering test bound
[task 2019-09-23T22:17:19.497Z] 22:17:19 INFO - Adding a new tab with URL: data:text/html;charset=utf-8,<p>Web Console test for observer notifications
[task 2019-09-23T22:17:19.497Z] 22:17:19 INFO - Buffered messages logged at 22:10:22
[task 2019-09-23T22:17:19.497Z] 22:17:19 INFO - Tab added and finished loading
[task 2019-09-23T22:17:19.497Z] 22:17:19 INFO - Opening the toolbox
[task 2019-09-23T22:17:19.497Z] 22:17:19 INFO - Buffered messages logged at 22:10:36
[task 2019-09-23T22:17:19.497Z] 22:17:19 INFO - Toolbox opened and focused
[task 2019-09-23T22:17:19.497Z] 22:17:19 INFO - Buffered messages finished
[task 2019-09-23T22:17:19.498Z] 22:17:19 ERROR - TEST-UNEXPECTED-TIMEOUT | devtools/client/webconsole/test/browser/browser_webconsole_observer_notifications.js | application timed out after 370 seconds with no output
[task 2019-09-23T22:17:19.498Z] 22:17:19 ERROR - Force-terminating active process(es).
[task 2019-09-23T22:17:19.498Z] 22:17:19 INFO - Determining child pids from psutil...
[task 2019-09-23T22:17:19.499Z] 22:17:19 INFO - [2606, 2637, 2671, 2531, 2552]
[task 2019-09-23T22:17:19.501Z] 22:17:19 INFO - ==> process 2480 launched child process 2501
[task 2019-09-23T22:17:19.501Z] 22:17:19 INFO - ==> process 2480 launched child process 2531
[task 2019-09-23T22:17:19.502Z] 22:17:19 INFO - ==> process 2480 launched child process 2552
[task 2019-09-23T22:17:19.503Z] 22:17:19 INFO - ==> process 2480 launched child process 2606
[task 2019-09-23T22:17:19.503Z] 22:17:19 INFO - ==> process 2480 launched child process 2637
[task 2019-09-23T22:17:19.503Z] 22:17:19 INFO - ==> process 2480 launched child process 2671
[task 2019-09-23T22:17:19.504Z] 22:17:19 INFO - Found child pids: set([2531, 2501, 2637, 2606, 2671, 2552])
[task 2019-09-23T22:17:19.504Z] 22:17:19 INFO - Failed to get child procs
[task 2019-09-23T22:17:19.504Z] 22:17:19 INFO - Killing process: 2531
[task 2019-09-23T22:17:19.504Z] 22:17:19 INFO - TEST-INFO | started process screentopng
[task 2019-09-23T22:17:38.292Z] 22:17:38 INFO - TEST-INFO | screentopng: exit 0
[task 2019-09-23T22:17:38.333Z] 22:17:38 INFO - Can't trigger Breakpad, just killing process
[task 2019-09-23T22:17:39.341Z] 22:17:39 INFO - psutil found pid 2531 dead
[task 2019-09-23T22:17:39.341Z] 22:17:39 INFO - Killing process: 2501
[task 2019-09-23T22:17:39.343Z] 22:17:39 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-09-23T22:17:39.343Z] 22:17:39 INFO - Can't trigger Breakpad, just killing process
[task 2019-09-23T22:17:39.343Z] 22:17:39 INFO - Error: Failed to kill process 2501: psutil.NoSuchProcess no process found with pid 2501
[task 2019-09-23T22:17:39.343Z] 22:17:39 INFO - Killing process: 2637
[task 2019-09-23T22:17:39.343Z] 22:17:39 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-09-23T22:17:39.343Z] 22:17:39 INFO - Can't trigger Breakpad, just killing process
[task 2019-09-23T22:17:39.432Z] 22:17:39 INFO - psutil found pid 2637 dead
[task 2019-09-23T22:17:39.432Z] 22:17:39 INFO - Killing process: 2606
[task 2019-09-23T22:17:39.432Z] 22:17:39 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-09-23T22:17:39.432Z] 22:17:39 INFO - Can't trigger Breakpad, just killing process
[task 2019-09-23T22:17:40.346Z] 22:17:40 INFO - psutil found pid 2606 dead
[task 2019-09-23T22:17:40.346Z] 22:17:40 INFO - Killing process: 2671
[task 2019-09-23T22:17:40.346Z] 22:17:40 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-09-23T22:17:40.346Z] 22:17:40 INFO - Can't trigger Breakpad, just killing process
[task 2019-09-23T22:17:40.464Z] 22:17:40 INFO - psutil found pid 2671 dead
[task 2019-09-23T22:17:40.466Z] 22:17:40 INFO - Killing process: 2552
[task 2019-09-23T22:17:40.466Z] 22:17:40 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-09-23T22:17:40.467Z] 22:17:40 INFO - Can't trigger Breakpad, just killing process
[task 2019-09-23T22:17:40.625Z] 22:17:40 INFO - psutil found pid 2552 dead
[task 2019-09-23T22:17:40.625Z] 22:17:40 INFO - Killing process: 2480
[task 2019-09-23T22:17:40.625Z] 22:17:40 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-09-23T22:17:40.625Z] 22:17:40 INFO - Can't trigger Breakpad, just killing process
[task 2019-09-23T22:18:10.628Z] 22:18:10 INFO - psutil found pid 2480 dead
[task 2019-09-23T22:18:10.629Z] 22:18:10 INFO - failed to kill pid 2838 after 30s
[task 2019-09-23T22:18:10.629Z] 22:18:10 INFO - failed to kill pid 2842 after 30s
[task 2019-09-23T22:18:10.629Z] 22:18:10 INFO - psutil found pid 2480 dead
[task 2019-09-23T22:18:10.645Z] 22:18:10 WARNING - runtests.py | Failed to get app exit code - running/crashed?
[task 2019-09-23T22:18:10.645Z] 22:18:10 INFO - TEST-INFO | Main app process: exit 0

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.