Closed
Bug 1494120
Opened 7 years ago
Closed 7 years ago
Intermittent TEST-UNEXPECTED-TIMEOUT | devtools/client/webconsole/test/mochitest/browser_webconsole_sourcemap_error.js | application timed out after 370 seconds with no output
Categories
(DevTools :: Console, defect, P5)
DevTools
Console
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: apavel [at] mozilla.com
https://treeherder.mozilla.org/logviewer.html#?job_id=201518101&repo=autoland
https://queue.taskcluster.net/v1/task/HJfkdOcQTtSU1p1Jl1CBQg/runs/0/artifacts/public/logs/live_backing.log
[task 2018-09-25T21:33:39.298Z] 21:33:39 INFO - TEST-START | devtools/client/webconsole/test/mochitest/browser_webconsole_sourcemap_error.js
[task 2018-09-25T21:41:11.802Z] 21:41:04 INFO - Buffered messages logged at 21:33:39
[task 2018-09-25T21:41:57.747Z] 21:41:47 INFO - Entering test bound
[task 2018-09-25T21:42:36.268Z] 21:42:28 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/webconsole/test/mochitest/test-sourcemap-error-01.html
[task 2018-09-25T21:43:01.653Z] 21:42:58 INFO - Buffered messages logged at 21:33:49
[task 2018-09-25T21:43:20.636Z] 21:43:14 INFO - Tab added and finished loading
[task 2018-09-25T21:43:33.348Z] 21:43:29 INFO - Opening the toolbox
[task 2018-09-25T21:43:58.977Z] 21:43:54 INFO - Buffered messages logged at 21:34:10
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - Toolbox opened and focused
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - Buffered messages logged at 21:34:11
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_sourcemap_error.js | logged text is displayed in web console -
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - Buffered messages logged at 21:34:13
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - Console message: [JavaScript Warning: "Source map error: request failed with status 404
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - Resource URL: http://example.com/browser/devtools/client/webconsole/test/mochitest/test-sourcemap-error-01.js
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - Source Map URL: no-such-file.js.map"]
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - Buffered messages logged at 21:34:14
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_sourcemap_error.js | source map error is displayed in web console -
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/webconsole/test/mochitest/test-sourcemap-error-02.html
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - Buffered messages logged at 21:34:25
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - Tab added and finished loading
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - Opening the toolbox
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - Buffered messages finished
[task 2018-09-25T21:43:58.980Z] 21:43:58 ERROR - TEST-UNEXPECTED-TIMEOUT | devtools/client/webconsole/test/mochitest/browser_webconsole_sourcemap_error.js | application timed out after 370 seconds with no output
[task 2018-09-25T21:43:58.980Z] 21:43:58 ERROR - Force-terminating active process(es).
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - Determining child pids from psutil...
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - [4245, 4300, 4330, 4371, 4224]
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - ==> process 4137 launched child process 4161
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - ==> process 4137 launched child process 4224
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - ==> process 4137 launched child process 4245
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - ==> process 4137 launched child process 4300
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - ==> process 4137 launched child process 4330
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - ==> process 4137 launched child process 4371
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - Found child pids: set([4224, 4161, 4330, 4300, 4371, 4245])
[task 2018-09-25T21:43:58.980Z] 21:43:58 INFO - Failed to get child procs
[task 2018-09-25T21:43:58.981Z] 21:43:58 INFO - Killing process: 4224
[task 2018-09-25T21:43:58.981Z] 21:43:58 INFO - TEST-INFO | started process screentopng
[task 2018-09-25T21:43:58.981Z] 21:43:58 INFO - Failed to start /builds/worker/workspace/build/tests/bin/screentopng for screenshot: Cannot allocate memory
[task 2018-09-25T21:43:58.981Z] 21:43:58 INFO - Can't trigger Breakpad, just killing process
[task 2018-09-25T21:44:00.418Z] 21:44:00 INFO - psutil found pid 4224 dead
[task 2018-09-25T21:44:00.421Z] 21:44:00 INFO - Killing process: 4161
[task 2018-09-25T21:44:00.421Z] 21:44:00 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-09-25T21:44:00.421Z] 21:44:00 INFO - Can't trigger Breakpad, just killing process
[task 2018-09-25T21:44:00.421Z] 21:44:00 INFO - Error: Failed to kill process 4161: psutil.NoSuchProcess no process found with pid 4161
[task 2018-09-25T21:44:00.421Z] 21:44:00 INFO - Killing process: 4330
[task 2018-09-25T21:44:00.421Z] 21:44:00 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-09-25T21:44:00.421Z] 21:44:00 INFO - Can't trigger Breakpad, just killing process
[task 2018-09-25T21:44:01.048Z] 21:44:01 INFO - psutil found pid 4330 dead
[task 2018-09-25T21:44:01.048Z] 21:44:01 INFO - Killing process: 4300
[task 2018-09-25T21:44:01.048Z] 21:44:01 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-09-25T21:44:01.048Z] 21:44:01 INFO - Can't trigger Breakpad, just killing process
[task 2018-09-25T21:44:01.710Z] 21:44:01 INFO - psutil found pid 4300 dead
[task 2018-09-25T21:44:01.711Z] 21:44:01 INFO - Killing process: 4371
[task 2018-09-25T21:44:01.711Z] 21:44:01 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-09-25T21:44:01.712Z] 21:44:01 INFO - Can't trigger Breakpad, just killing process
[task 2018-09-25T21:44:01.976Z] 21:44:01 INFO - psutil found pid 4371 dead
[task 2018-09-25T21:44:01.976Z] 21:44:01 INFO - Killing process: 4245
[task 2018-09-25T21:44:01.976Z] 21:44:01 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-09-25T21:44:01.977Z] 21:44:01 INFO - Can't trigger Breakpad, just killing process
[task 2018-09-25T21:44:02.119Z] 21:44:02 INFO - psutil found pid 4245 dead
[task 2018-09-25T21:44:02.119Z] 21:44:02 INFO - Killing process: 4137
[task 2018-09-25T21:44:02.120Z] 21:44:02 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-09-25T21:44:02.121Z] 21:44:02 INFO - Can't trigger Breakpad, just killing process
[task 2018-09-25T21:44:02.465Z] 21:44:02 INFO - psutil found pid 4137 dead
[task 2018-09-25T21:44:02.465Z] 21:44:02 INFO - psutil found pid 4137 dead
[task 2018-09-25T21:44:02.513Z] 21:44:02 WARNING - runtests.py | Failed to get app exit code - running/crashed?
[task 2018-09-25T21:44:02.514Z] 21:44:02 INFO - TEST-INFO | Main app process: exit 0
[task 2018-09-25T21:44:02.514Z] 21:44:02 INFO - runtests.py | Application ran for: 0:27:53.439225
[task 2018-09-25T21:44:02.515Z] 21:44:02 INFO - zombiecheck | Reading PID log: /tmp/tmpHoa9ODpidlog
[task 2018-09-25T21:44:02.516Z] 21:44:02 INFO - ==> process 4137 launched child process 4161
[task 2018-09-25T21:44:02.517Z] 21:44:02 INFO - ==> process 4137 launched child process 4224
[task 2018-09-25T21:44:02.518Z] 21:44:02 INFO - ==> process 4137 launched child process 4245
[task 2018-09-25T21:44:02.519Z] 21:44:02 INFO - ==> process 4137 launched child process 4300
[task 2018-09-25T21:44:02.519Z] 21:44:02 INFO - ==> process 4137 launched child process 4330
[task 2018-09-25T21:44:02.519Z] 21:44:02 INFO - ==> process 4137 launched child process 4371
[task 2018-09-25T21:44:02.520Z] 21:44:02 INFO - zombiecheck | Checking for orphan process with PID: 4224
[task 2018-09-25T21:44:02.520Z] 21:44:02 INFO - zombiecheck | Checking for orphan process with PID: 4161
[task 2018-09-25T21:44:02.521Z] 21:44:02 INFO - zombiecheck | Checking for orphan process with PID: 4330
[task 2018-09-25T21:44:02.521Z] 21:44:02 INFO - zombiecheck | Checking for orphan process with PID: 4300
[task 2018-09-25T21:44:02.522Z] 21:44:02 INFO - zombiecheck | Checking for orphan process with PID: 4371
[task 2018-09-25T21:44:02.522Z] 21:44:02 INFO - zombiecheck | Checking for orphan process with PID: 4245
[task 2018-09-25T21:44:02.522Z] 21:44:02 INFO - Stopping web server
[task 2018-09-25T21:44:02.543Z] 21:44:02 INFO - Stopping web socket server
[task 2018-09-25T21:44:02.559Z] 21:44:02 INFO - Stopping ssltunnel
[task 2018-09-25T21:44:02.587Z] 21:44:02 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2018-09-25T21:44:02.587Z] 21:44:02 INFO - runtests.py | Running tests: end.
[task 2018-09-25T21:44:02.624Z] 21:44:02 INFO - Buffered messages finished
[task 2018-09-25T21:44:02.624Z] 21:44:02 INFO - Running manifest: devtools/shared/tests/browser/browser.ini
[task 2018-09-25T21:44:02.624Z] 21:44:02 INFO - The following extra prefs will be set:
[task 2018-09-25T21:44:02.624Z] 21:44:02 INFO - dom.animations-api.core.enabled=true
[task 2018-09-25T21:44:02.625Z] 21:44:02 INFO - dom.animations-api.timelines.enabled=true
[task 2018-09-25T21:44:02.726Z] 21:44:02 INFO - Setting pipeline to PAUSED ...
[task 2018-09-25T21:44:02.726Z] 21:44:02 INFO - Pipeline is PREROLLING ...
[task 2018-09-25T21:44:02.728Z] 21:44:02 INFO - Pipeline is PREROLLED ...
[task 2018-09-25T21:44:02.729Z] 21:44:02 INFO - Setting pipeline to PLAYING ...
[task 2018-09-25T21:44:02.729Z] 21:44:02 INFO - New clock: GstSystemClock
[task 2018-09-25T21:44:02.765Z] 21:44:02 INFO - Got EOS from element "pipeline0".
[task 2018-09-25T21:44:02.765Z] 21:44:02 INFO - Execution ended after 0:00:00.033517360
[task 2018-09-25T21:44:02.766Z] 21:44:02 INFO - Setting pipeline to PAUSED ...
[task 2018-09-25T21:44:02.767Z] 21:44:02 INFO - Setting pipeline to READY ...
[task 2018-09-25T21:44:02.767Z] 21:44:02 INFO - (gst-launch-1.0:5568): GStreamer-CRITICAL **: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2018-09-25T21:44:02.767Z] 21:44:02 INFO - Setting pipeline to NULL ...
[task 2018-09-25T21:44:02.767Z] 21:44:02 INFO - Freeing pipeline ...
[task 2018-09-25T21:44:02.844Z] 21:44:02 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2018-09-25T21:44:02.851Z] 21:44:02 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2018-09-25T21:44:03.114Z] 21:44:03 INFO - pk12util: PKCS12 IMPORT SUCCESSFUL
| 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
•