Closed
Bug 1471093
Opened 7 years ago
Closed 7 years ago
Intermittent TEST-UNEXPECTED-TIMEOUT | browser/extensions/pdfjs/test/browser_pdfjs_zoom.js | application timed out after 370 seconds with no output
Categories
(Firefox :: PDF Viewer, defect, P5)
Firefox
PDF Viewer
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=184804083&repo=mozilla-inbound
https://queue.taskcluster.net/v1/task/XBOG55cLTji7GQcxAaHXOQ/runs/0/artifacts/public/logs/live_backing.log
[task 2018-06-25T22:35:32.001Z] 22:35:32 ERROR - TEST-UNEXPECTED-TIMEOUT | browser/extensions/pdfjs/test/browser_pdfjs_zoom.js | application timed out after 370 seconds with no output
[task 2018-06-25T22:35:32.003Z] 22:35:32 ERROR - Force-terminating active process(es).
[task 2018-06-25T22:35:32.005Z] 22:35:32 INFO - Determining child pids from psutil...
[task 2018-06-25T22:35:32.010Z] 22:35:32 INFO - []
[task 2018-06-25T22:35:32.013Z] 22:35:32 INFO - ==> process 2163 launched child process 2181
[task 2018-06-25T22:35:32.014Z] 22:35:32 INFO - ==> process 2163 launched child process 2211
[task 2018-06-25T22:35:32.015Z] 22:35:32 INFO - ==> process 2163 launched child process 2246
[task 2018-06-25T22:35:32.017Z] 22:35:32 INFO - ==> process 2163 launched child process 2272
[task 2018-06-25T22:35:32.019Z] 22:35:32 INFO - ==> process 2163 launched child process 2300
[task 2018-06-25T22:35:32.020Z] 22:35:32 INFO - Found child pids: set([2272, 2211, 2300, 2181, 2246])
[task 2018-06-25T22:35:32.022Z] 22:35:32 INFO - Failed to get child procs
[task 2018-06-25T22:35:32.023Z] 22:35:32 INFO - Killing process: 2272
[task 2018-06-25T22:35:32.025Z] 22:35:32 INFO - TEST-INFO | started process screentopng
[task 2018-06-25T22:35:32.696Z] 22:35:32 INFO - TEST-INFO | screentopng: exit 0
[task 2018-06-25T22:35:32.696Z] 22:35:32 INFO - Can't trigger Breakpad, just killing process
[task 2018-06-25T22:35:32.698Z] 22:35:32 INFO - Error: Failed to kill process 2272: psutil.NoSuchProcess no process found with pid 2272
[task 2018-06-25T22:35:32.700Z] 22:35:32 INFO - Killing process: 2211
[task 2018-06-25T22:35:32.702Z] 22:35:32 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-06-25T22:35:32.703Z] 22:35:32 INFO - Can't trigger Breakpad, just killing process
[task 2018-06-25T22:35:32.705Z] 22:35:32 INFO - Error: Failed to kill process 2211: psutil.NoSuchProcess no process found with pid 2211
[task 2018-06-25T22:35:32.706Z] 22:35:32 INFO - Killing process: 2300
[task 2018-06-25T22:35:32.707Z] 22:35:32 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-06-25T22:35:32.708Z] 22:35:32 INFO - Can't trigger Breakpad, just killing process
[task 2018-06-25T22:35:32.709Z] 22:35:32 INFO - Error: Failed to kill process 2300: psutil.NoSuchProcess no process found with pid 2300
[task 2018-06-25T22:35:32.711Z] 22:35:32 INFO - Killing process: 2181
[task 2018-06-25T22:35:32.712Z] 22:35:32 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-06-25T22:35:32.713Z] 22:35:32 INFO - Can't trigger Breakpad, just killing process
[task 2018-06-25T22:36:02.736Z] 22:36:02 INFO - failed to kill pid 2181 after 30s
[task 2018-06-25T22:36:02.738Z] 22:36:02 INFO - Killing process: 2246
[task 2018-06-25T22:36:02.740Z] 22:36:02 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-06-25T22:36:02.741Z] 22:36:02 INFO - Can't trigger Breakpad, just killing process
[task 2018-06-25T22:36:02.742Z] 22:36:02 INFO - Error: Failed to kill process 2246: psutil.NoSuchProcess no process found with pid 2246
[task 2018-06-25T22:36:02.743Z] 22:36:02 INFO - Killing process: 2163
[task 2018-06-25T22:36:02.744Z] 22:36:02 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-06-25T22:36:02.745Z] 22:36:02 INFO - Can't trigger Breakpad, just killing process
[task 2018-06-25T22:36:02.752Z] 22:36:02 INFO - psutil found pid 2163 dead
[task 2018-06-25T22:36:02.756Z] 22:36:02 INFO - psutil found pid 2163 dead
[task 2018-06-25T22:36:02.756Z] 22:36:02 WARNING - runtests.py | Failed to get app exit code - running/crashed?
[task 2018-06-25T22:36:02.756Z] 22:36:02 INFO - TEST-INFO | Main app process: exit 0
[task 2018-06-25T22:36:02.756Z] 22:36:02 INFO - runtests.py | Application ran for: 0:07:35.918382
[task 2018-06-25T22:36:02.757Z] 22:36:02 INFO - zombiecheck | Reading PID log: /tmp/tmpJ73D2ypidlog
[task 2018-06-25T22:36:02.757Z] 22:36:02 INFO - ==> process 2163 launched child process 2181
[task 2018-06-25T22:36:02.757Z] 22:36:02 INFO - ==> process 2163 launched child process 2211
[task 2018-06-25T22:36:02.757Z] 22:36:02 INFO - ==> process 2163 launched child process 2246
[task 2018-06-25T22:36:02.758Z] 22:36:02 INFO - ==> process 2163 launched child process 2272
[task 2018-06-25T22:36:02.759Z] 22:36:02 INFO - ==> process 2163 launched child process 2300
[task 2018-06-25T22:36:02.760Z] 22:36:02 INFO - zombiecheck | Checking for orphan process with PID: 2272
[task 2018-06-25T22:36:02.760Z] 22:36:02 INFO - zombiecheck | Checking for orphan process with PID: 2211
[task 2018-06-25T22:36:02.761Z] 22:36:02 INFO - zombiecheck | Checking for orphan process with PID: 2300
[task 2018-06-25T22:36:02.762Z] 22:36:02 INFO - zombiecheck | Checking for orphan process with PID: 2181
[task 2018-06-25T22:36:02.763Z] 22:36:02 INFO - zombiecheck | Checking for orphan process with PID: 2246
[task 2018-06-25T22:36:02.764Z] 22:36:02 INFO - Stopping web server
[task 2018-06-25T22:36:02.786Z] 22:36:02 INFO - Stopping web socket server
[task 2018-06-25T22:36:02.805Z] 22:36:02 INFO - Stopping ssltunnel
[task 2018-06-25T22:36:02.826Z] 22:36:02 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2018-06-25T22:36:02.827Z] 22:36:02 INFO - runtests.py | Running tests: end.
[task 2018-06-25T22:36:02.844Z] 22:36:02 INFO - Buffered messages finished
[task 2018-06-25T22:36:02.844Z] 22:36:02 INFO - Running manifest: dom/credentialmanagement/tests/browser/browser.ini
[task 2018-06-25T22:36:02.844Z] 22:36:02 INFO - The following extra prefs will be set:
[task 2018-06-25T22:36:02.844Z] 22:36:02 INFO - dom.animations-api.core.enabled=true
[task 2018-06-25T22:36:02.880Z] 22:36:02 INFO - Setting pipeline to PAUSED ...
[task 2018-06-25T22:36:02.881Z] 22:36:02 INFO - Pipeline is PREROLLING ...
[task 2018-06-25T22:36:02.882Z] 22:36:02 INFO - Pipeline is PREROLLED ...
[task 2018-06-25T22:36:02.882Z] 22:36:02 INFO - Setting pipeline to PLAYING ...
[task 2018-06-25T22:36:02.883Z] 22:36:02 INFO - New clock: GstSystemClock
[task 2018-06-25T22:36:02.912Z] 22:36:02 INFO - Got EOS from element "pipeline0".
[task 2018-06-25T22:36:02.912Z] 22:36:02 INFO - Execution ended after 0:00:00.033176159
[task 2018-06-25T22:36:02.912Z] 22:36:02 INFO - Setting pipeline to PAUSED ...
[task 2018-06-25T22:36:02.912Z] 22:36:02 INFO - Setting pipeline to READY ...
[task 2018-06-25T22:36:02.912Z] 22:36:02 INFO - (gst-launch-1.0:2350): GStreamer-CRITICAL **: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2018-06-25T22:36:02.913Z] 22:36:02 INFO - Setting pipeline to NULL ...
[task 2018-06-25T22:36:02.914Z] 22:36:02 INFO - Freeing pipeline ...
[task 2018-06-25T22:36:02.974Z] 22:36:02 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2018-06-25T22:36:02.974Z] 22:36:02 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2018-06-25T22:36:03.176Z] 22:36:03 INFO - pk12util: PKCS12 IMPORT SUCCESSFUL
|
||
Comment 1•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
•