Closed
Bug 1471122
Opened 7 years ago
Closed 7 years ago
Intermittent TEST-UNEXPECTED-TIMEOUT | parser/htmlparser/tests/mochitest/browser_viewsource.js | application timed out after 370 seconds with no output
Categories
(Core :: XML, defect, P5)
Core
XML
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: csabou [at] mozilla.com
https://treeherder.mozilla.org/logviewer.html#?job_id=184835166&repo=mozilla-inbound
https://queue.taskcluster.net/v1/task/RwQDXY60TkaCdKfDYZ5uug/runs/0/artifacts/public/logs/live_backing.log
[task 2018-06-26T02:38:58.651Z] 02:38:58 ERROR - TEST-UNEXPECTED-TIMEOUT | parser/htmlparser/tests/mochitest/browser_viewsource.js | application timed out after 370 seconds with no output
[task 2018-06-26T02:38:58.652Z] 02:38:58 ERROR - Force-terminating active process(es).
[task 2018-06-26T02:38:58.654Z] 02:38:58 INFO - Determining child pids from psutil...
[task 2018-06-26T02:38:58.662Z] 02:38:58 INFO - []
[task 2018-06-26T02:38:58.663Z] 02:38:58 INFO - ==> process 4089 launched child process 4107
[task 2018-06-26T02:38:58.664Z] 02:38:58 INFO - ==> process 4089 launched child process 4134
[task 2018-06-26T02:38:58.665Z] 02:38:58 INFO - ==> process 4089 launched child process 4166
[task 2018-06-26T02:38:58.666Z] 02:38:58 INFO - ==> process 4089 launched child process 4195
[task 2018-06-26T02:38:58.668Z] 02:38:58 INFO - ==> process 4089 launched child process 4211
[task 2018-06-26T02:38:58.669Z] 02:38:58 INFO - Found child pids: set([4211, 4107, 4195, 4166, 4134])
[task 2018-06-26T02:38:58.670Z] 02:38:58 INFO - Failed to get child procs
[task 2018-06-26T02:38:58.671Z] 02:38:58 INFO - Killing process: 4211
[task 2018-06-26T02:38:58.672Z] 02:38:58 INFO - TEST-INFO | started process screentopng
[task 2018-06-26T02:38:59.338Z] 02:38:59 INFO - TEST-INFO | screentopng: exit 0
[task 2018-06-26T02:38:59.340Z] 02:38:59 INFO - Can't trigger Breakpad, just killing process
[task 2018-06-26T02:38:59.341Z] 02:38:59 INFO - Error: Failed to kill process 4211: psutil.NoSuchProcess no process found with pid 4211
[task 2018-06-26T02:38:59.342Z] 02:38:59 INFO - Killing process: 4107
[task 2018-06-26T02:38:59.343Z] 02:38:59 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-06-26T02:38:59.344Z] 02:38:59 INFO - Can't trigger Breakpad, just killing process
[task 2018-06-26T02:39:29.383Z] 02:39:29 INFO - failed to kill pid 4107 after 30s
[task 2018-06-26T02:39:29.383Z] 02:39:29 INFO - Killing process: 4195
[task 2018-06-26T02:39:29.384Z] 02:39:29 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-06-26T02:39:29.385Z] 02:39:29 INFO - Can't trigger Breakpad, just killing process
[task 2018-06-26T02:39:29.386Z] 02:39:29 INFO - Error: Failed to kill process 4195: psutil.NoSuchProcess no process found with pid 4195
[task 2018-06-26T02:39:29.387Z] 02:39:29 INFO - Killing process: 4166
[task 2018-06-26T02:39:29.388Z] 02:39:29 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-06-26T02:39:29.390Z] 02:39:29 INFO - Can't trigger Breakpad, just killing process
[task 2018-06-26T02:39:29.391Z] 02:39:29 INFO - Error: Failed to kill process 4166: psutil.NoSuchProcess no process found with pid 4166
[task 2018-06-26T02:39:29.392Z] 02:39:29 INFO - Killing process: 4134
[task 2018-06-26T02:39:29.393Z] 02:39:29 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-06-26T02:39:29.394Z] 02:39:29 INFO - Can't trigger Breakpad, just killing process
[task 2018-06-26T02:39:29.395Z] 02:39:29 INFO - Error: Failed to kill process 4134: psutil.NoSuchProcess no process found with pid 4134
[task 2018-06-26T02:39:29.396Z] 02:39:29 INFO - Killing process: 4089
[task 2018-06-26T02:39:29.397Z] 02:39:29 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-06-26T02:39:29.398Z] 02:39:29 INFO - Can't trigger Breakpad, just killing process
[task 2018-06-26T02:39:29.405Z] 02:39:29 INFO - psutil found pid 4089 dead
[task 2018-06-26T02:39:29.406Z] 02:39:29 INFO - psutil found pid 4089 dead
[task 2018-06-26T02:39:29.452Z] 02:39:29 WARNING - runtests.py | Failed to get app exit code - running/crashed?
[task 2018-06-26T02:39:29.452Z] 02:39:29 INFO - TEST-INFO | Main app process: exit 0
[task 2018-06-26T02:39:29.452Z] 02:39:29 INFO - runtests.py | Application ran for: 0:07:05.410262
[task 2018-06-26T02:39:29.452Z] 02:39:29 INFO - zombiecheck | Reading PID log: /tmp/tmpGeNLH2pidlog
[task 2018-06-26T02:39:29.452Z] 02:39:29 INFO - ==> process 4089 launched child process 4107
[task 2018-06-26T02:39:29.452Z] 02:39:29 INFO - ==> process 4089 launched child process 4134
[task 2018-06-26T02:39:29.453Z] 02:39:29 INFO - ==> process 4089 launched child process 4166
[task 2018-06-26T02:39:29.455Z] 02:39:29 INFO - ==> process 4089 launched child process 4195
[task 2018-06-26T02:39:29.455Z] 02:39:29 INFO - ==> process 4089 launched child process 4211
[task 2018-06-26T02:39:29.456Z] 02:39:29 INFO - zombiecheck | Checking for orphan process with PID: 4211
[task 2018-06-26T02:39:29.457Z] 02:39:29 INFO - zombiecheck | Checking for orphan process with PID: 4107
[task 2018-06-26T02:39:29.458Z] 02:39:29 INFO - zombiecheck | Checking for orphan process with PID: 4195
[task 2018-06-26T02:39:29.459Z] 02:39:29 INFO - zombiecheck | Checking for orphan process with PID: 4166
[task 2018-06-26T02:39:29.461Z] 02:39:29 INFO - zombiecheck | Checking for orphan process with PID: 4134
[task 2018-06-26T02:39:29.462Z] 02:39:29 INFO - Stopping web server
[task 2018-06-26T02:39:29.470Z] 02:39:29 INFO - Stopping web socket server
[task 2018-06-26T02:39:29.490Z] 02:39:29 INFO - Stopping ssltunnel
[task 2018-06-26T02:39:29.511Z] 02:39:29 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2018-06-26T02:39:29.511Z] 02:39:29 INFO - runtests.py | Running tests: end.
[task 2018-06-26T02:39:29.528Z] 02:39:29 INFO - Buffered messages finished
[task 2018-06-26T02:39:29.528Z] 02:39:29 INFO - Running manifest: testing/mochitest/tests/browser/browser.ini
[task 2018-06-26T02:39:29.529Z] 02:39:29 INFO - The following extra prefs will be set:
[task 2018-06-26T02:39:29.530Z] 02:39:29 INFO - dom.animations-api.core.enabled=true
[task 2018-06-26T02:39:29.567Z] 02:39:29 INFO - Setting pipeline to PAUSED ...
[task 2018-06-26T02:39:29.568Z] 02:39:29 INFO - Pipeline is PREROLLING ...
[task 2018-06-26T02:39:29.568Z] 02:39:29 INFO - Pipeline is PREROLLED ...
[task 2018-06-26T02:39:29.569Z] 02:39:29 INFO - Setting pipeline to PLAYING ...
[task 2018-06-26T02:39:29.569Z] 02:39:29 INFO - New clock: GstSystemClock
[task 2018-06-26T02:39:29.597Z] 02:39:29 INFO - Got EOS from element "pipeline0".
[task 2018-06-26T02:39:29.598Z] 02:39:29 INFO - Execution ended after 0:00:00.033231455
[task 2018-06-26T02:39:29.598Z] 02:39:29 INFO - Setting pipeline to PAUSED ...
[task 2018-06-26T02:39:29.599Z] 02:39:29 INFO - Setting pipeline to READY ...
[task 2018-06-26T02:39:29.600Z] 02:39:29 INFO - (gst-launch-1.0:4241): GStreamer-CRITICAL **: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2018-06-26T02:39:29.601Z] 02:39:29 INFO - Setting pipeline to NULL ...
[task 2018-06-26T02:39:29.601Z] 02:39:29 INFO - Freeing pipeline ...
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•7 years ago
|
||
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•