Closed Bug 1510523 Opened 6 years ago Closed 5 years ago

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

Categories

(DevTools :: Console, defect, P5)

Product:
DevTools
Component:
Console
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: cbrindusan [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=214289608&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/EY_qVYgFTZm5ArcSnTZ61A/runs/0/artifacts/public/logs/live_backing.log [task 2018-11-28T03:59:04.951Z] 03:59:04 INFO - TEST-START | devtools/client/webconsole/test/mochitest/browser_webconsole_message_categories.js [task 2018-11-28T04:08:04.016Z] 04:07:52 INFO - Buffered messages logged at 03:59:04 [task 2018-11-28T04:09:54.478Z] 04:09:42 INFO - Entering test bound [task 2018-11-28T04:11:15.746Z] 04:11:02 INFO - Buffered messages logged at 03:59:05 [task 2018-11-28T04:11:35.382Z] 04:11:35 INFO - Adding a new tab with URL: data:text/html;charset=utf-8,Web Console test for bug 595934 - message categories coverage. [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - Buffered messages logged at 03:59:06 [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - Tab added and finished loading [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - Opening the toolbox [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - Buffered messages logged at 03:59:24 [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - Toolbox opened and focused [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - Running test #0 [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - Load test file test-message-categories-css-loader.html [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - Buffered messages logged at 03:59:26 [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - Console message: [JavaScript Warning: "Stylesheets without CSSOM changes reparsed to check for errors. Refresh the page to also see errors from stylesheets changed from CSSOM and from style attributes."] [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - Buffered messages logged at 03:59:46 [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - Wait for log message to be observed with the correct category [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - Buffered messages logged at 03:59:50 [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_message_categories.js | Expected category [CSS Loader] received in observer - [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - Console message: [JavaScript Error: "The stylesheet http://example.com/browser/devtools/client/webconsole/test/mochitest/test-message-categories-css-loader.css was not loaded because its MIME type, “image/png”, is not “text/css”." {file: "http://example.com/browser/devtools/client/webconsole/test/mochitest/test-message-categories-css-loader.html" line: 0}] [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - Wait for log message to be displayed in the hud [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - Buffered messages logged at 03:59:52 [task 2018-11-28T04:11:35.387Z] 04:11:35 INFO - Matched a message with text: "text/css", all messages received. [task 2018-11-28T04:11:35.388Z] 04:11:35 INFO - Running test #1 [task 2018-11-28T04:11:35.388Z] 04:11:35 INFO - Load test file test-message-categories-imagemap.html [task 2018-11-28T04:11:35.388Z] 04:11:35 INFO - Buffered messages finished [task 2018-11-28T04:11:35.388Z] 04:11:35 ERROR - TEST-UNEXPECTED-TIMEOUT | devtools/client/webconsole/test/mochitest/browser_webconsole_message_categories.js | application timed out after 370 seconds with no output

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE

Recent log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=225952441&repo=autoland&lineNumber=6057

INFO - TEST-START | devtools/client/webconsole/test/mochitest/browser_webconsole_message_categories.js
[task 2019-02-04T20:05:38.095Z] 20:05:31 INFO - Buffered messages logged at 19:57:03
[task 2019-02-04T20:05:44.995Z] 20:05:44 INFO - Entering test bound
[task 2019-02-04T20:05:46.793Z] 20:05:46 INFO - Buffered messages logged at 19:57:04
[task 2019-02-04T20:05:47.538Z] 20:05:47 INFO - Adding a new tab with URL: data:text/html;charset=utf-8,Web Console test for bug 595934 - message categories coverage.
[task 2019-02-04T20:05:47.698Z] 20:05:47 INFO - Buffered messages logged at 19:57:05
[task 2019-02-04T20:05:47.965Z] 20:05:47 INFO - Tab added and finished loading
[task 2019-02-04T20:05:49.359Z] 20:05:49 INFO - Opening the toolbox
[task 2019-02-04T20:05:49.566Z] 20:05:49 INFO - Buffered messages logged at 19:57:23
[task 2019-02-04T20:05:49.603Z] 20:05:49 INFO - Toolbox opened and focused
[task 2019-02-04T20:05:49.603Z] 20:05:49 INFO - Running test #0
[task 2019-02-04T20:05:49.603Z] 20:05:49 INFO - Load test file test-message-categories-css-loader.html
[task 2019-02-04T20:05:49.603Z] 20:05:49 INFO - Buffered messages logged at 19:57:26
[task 2019-02-04T20:05:49.604Z] 20:05:49 INFO - Console message: [JavaScript Warning: "Stylesheets without CSSOM changes reparsed to check for errors. Refresh the page to also see errors from stylesheets changed from CSSOM and from style attributes."]
[task 2019-02-04T20:05:49.604Z] 20:05:49 INFO - Buffered messages logged at 19:57:49
[task 2019-02-04T20:05:49.605Z] 20:05:49 INFO - Wait for log message to be observed with the correct category
[task 2019-02-04T20:05:49.605Z] 20:05:49 INFO - Buffered messages logged at 19:57:53
[task 2019-02-04T20:05:49.605Z] 20:05:49 INFO - Console message: [JavaScript Error: "The stylesheet http://example.com/browser/devtools/client/webconsole/test/mochitest/test-message-categories-css-loader.css was not loaded because its MIME type, “image/png”, is not “text/css”." {file: "http://example.com/browser/devtools/client/webconsole/test/mochitest/test-message-categories-css-loader.html" line: 0}]
[task 2019-02-04T20:05:49.606Z] 20:05:49 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_message_categories.js | Expected category [CSS Loader] received in observer -
[task 2019-02-04T20:05:49.606Z] 20:05:49 INFO - Wait for log message to be displayed in the hud
[task 2019-02-04T20:05:49.606Z] 20:05:49 INFO - Buffered messages logged at 19:57:55
[task 2019-02-04T20:05:49.606Z] 20:05:49 INFO - Matched a message with text: "text/css", all messages received.
[task 2019-02-04T20:05:49.607Z] 20:05:49 INFO - Running test #1
[task 2019-02-04T20:05:49.607Z] 20:05:49 INFO - Load test file test-message-categories-imagemap.html
[task 2019-02-04T20:05:49.607Z] 20:05:49 INFO - Buffered messages logged at 19:58:03
[task 2019-02-04T20:05:49.607Z] 20:05:49 INFO - Wait for log message to be observed with the correct category
[task 2019-02-04T20:05:49.607Z] 20:05:49 INFO - Console message: [JavaScript Warning: "The “coords” attribute of the <area shape="rect"> tag is not in the “left,top,right,bottom” format." {file: "http://example.com/browser/devtools/client/webconsole/test/mochitest/test-message-categories-imagemap.html" line: 0 column: 0 source: "coords="0,0,10,10,5""}]
[task 2019-02-04T20:05:49.608Z] 20:05:49 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_message_categories.js | Expected category [Layout: ImageMap] received in observer -
[task 2019-02-04T20:05:49.608Z] 20:05:49 INFO - Wait for log message to be displayed in the hud
[task 2019-02-04T20:05:49.608Z] 20:05:49 INFO - Buffered messages finished
[task 2019-02-04T20:05:49.608Z] 20:05:49 ERROR - TEST-UNEXPECTED-TIMEOUT | devtools/client/webconsole/test/mochitest/browser_webconsole_message_categories.js | application timed out after 370 seconds with no output
[task 2019-02-04T20:05:49.609Z] 20:05:49 ERROR - Force-terminating active process(es).
[task 2019-02-04T20:05:49.609Z] 20:05:49 INFO - Determining child pids from psutil...
[task 2019-02-04T20:05:49.610Z] 20:05:49 INFO - [3932, 3771, 3792, 3851, 3888]
[task 2019-02-04T20:05:49.610Z] 20:05:49 INFO - ==> process 3661 launched child process 3686
[task 2019-02-04T20:05:49.610Z] 20:05:49 INFO - ==> process 3661 launched child process 3771
[task 2019-02-04T20:05:49.610Z] 20:05:49 INFO - ==> process 3661 launched child process 3792
[task 2019-02-04T20:05:49.610Z] 20:05:49 INFO - ==> process 3661 launched child process 3851
[task 2019-02-04T20:05:49.611Z] 20:05:49 INFO - ==> process 3661 launched child process 3888
[task 2019-02-04T20:05:49.611Z] 20:05:49 INFO - ==> process 3661 launched child process 3932
[task 2019-02-04T20:05:49.611Z] 20:05:49 INFO - Found child pids: set([3888, 3686, 3851, 3792, 3771, 3932])
[task 2019-02-04T20:05:49.611Z] 20:05:49 INFO - Failed to get child procs
[task 2019-02-04T20:05:49.612Z] 20:05:49 INFO - Killing process: 3888
[task 2019-02-04T20:05:49.612Z] 20:05:49 INFO - TEST-INFO | started process screentopng
[task 2019-02-04T20:05:49.612Z] 20:05:49 INFO - Failed to start /builds/worker/workspace/build/tests/bin/screentopng for screenshot: Cannot allocate memory
[task 2019-02-04T20:05:49.612Z] 20:05:49 INFO - Can't trigger Breakpad, just killing process
[task 2019-02-04T20:05:50.965Z] 20:05:50 INFO - psutil found pid 3888 dead
[task 2019-02-04T20:05:50.966Z] 20:05:50 INFO - Killing process: 3686
[task 2019-02-04T20:05:50.967Z] 20:05:50 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-02-04T20:05:50.968Z] 20:05:50 INFO - Can't trigger Breakpad, just killing process
[task 2019-02-04T20:05:50.969Z] 20:05:50 INFO - Error: Failed to kill process 3686: psutil.NoSuchProcess no process found with pid 3686
[task 2019-02-04T20:05:50.970Z] 20:05:50 INFO - Killing process: 3851
[task 2019-02-04T20:05:50.971Z] 20:05:50 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-02-04T20:05:50.972Z] 20:05:50 INFO - Can't trigger Breakpad, just killing process
[task 2019-02-04T20:05:52.249Z] 20:05:52 INFO - psutil found pid 3851 dead
[task 2019-02-04T20:05:52.249Z] 20:05:52 INFO - Killing process: 3792
[task 2019-02-04T20:05:52.250Z] 20:05:52 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-02-04T20:05:52.250Z] 20:05:52 INFO - Can't trigger Breakpad, just killing process
[task 2019-02-04T20:05:52.392Z] 20:05:52 INFO - psutil found pid 3792 dead
[task 2019-02-04T20:05:52.393Z] 20:05:52 INFO - Killing process: 3771
[task 2019-02-04T20:05:52.394Z] 20:05:52 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-02-04T20:05:52.394Z] 20:05:52 INFO - Can't trigger Breakpad, just killing process
[task 2019-02-04T20:05:52.537Z] 20:05:52 INFO - psutil found pid 3771 dead
[task 2019-02-04T20:05:52.537Z] 20:05:52 INFO - Killing process: 3932
[task 2019-02-04T20:05:52.537Z] 20:05:52 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-02-04T20:05:52.538Z] 20:05:52 INFO - Can't trigger Breakpad, just killing process
[task 2019-02-04T20:05:52.647Z] 20:05:52 INFO - psutil found pid 3932 dead
[task 2019-02-04T20:05:52.648Z] 20:05:52 INFO - Killing process: 3661
[task 2019-02-04T20:05:52.648Z] 20:05:52 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-02-04T20:05:52.648Z] 20:05:52 INFO - Can't trigger Breakpad, just killing process
[task 2019-02-04T20:06:22.656Z] 20:06:22 INFO - psutil found pid 3661 dead
[task 2019-02-04T20:06:22.656Z] 20:06:22 INFO - failed to kill pid 4567 after 30s
[task 2019-02-04T20:06:22.657Z] 20:06:22 INFO - psutil found pid 3661 dead
[task 2019-02-04T20:06:22.705Z] 20:06:22 WARNING - runtests.py | Failed to get app exit code - running/crashed?
[task 2019-02-04T20:06:22.706Z] 20:06:22 INFO - TEST-INFO | Main app process: exit 0
[task 2019-02-04T20:06:22.706Z] 20:06:22 INFO - runtests.py | Application ran for: 0:30:26.885138
[task 2019-02-04T20:06:22.707Z] 20:06:22 INFO - zombiecheck | Reading PID log: /tmp/tmp8KL0Ukpidlog
[task 2019-02-04T20:06:22.708Z] 20:06:22 INFO - ==> process 3661 launched child process 3686
[task 2019-02-04T20:06:22.708Z] 20:06:22 INFO - ==> process 3661 launched child process 3771
[task 2019-02-04T20:06:22.709Z] 20:06:22 INFO - ==> process 3661 launched child process 3792
[task 2019-02-04T20:06:22.709Z] 20:06:22 INFO - ==> process 3661 launched child process 3851
[task 2019-02-04T20:06:22.710Z] 20:06:22 INFO - ==> process 3661 launched child process 3888
[task 2019-02-04T20:06:22.710Z] 20:06:22 INFO - ==> process 3661 launched child process 3932
[task 2019-02-04T20:06:22.710Z] 20:06:22 INFO - ==> process 3661 launched child process 4567
[task 2019-02-04T20:06:22.711Z] 20:06:22 INFO - zombiecheck | Checking for orphan process with PID: 3888
[task 2019-02-04T20:06:22.711Z] 20:06:22 INFO - zombiecheck | Checking for orphan process with PID: 3686
[task 2019-02-04T20:06:22.711Z] 20:06:22 INFO - zombiecheck | Checking for orphan process with PID: 3851
[task 2019-02-04T20:06:22.712Z] 20:06:22 INFO - zombiecheck | Checking for orphan process with PID: 3792
[task 2019-02-04T20:06:22.712Z] 20:06:22 INFO - zombiecheck | Checking for orphan process with PID: 4567
[task 2019-02-04T20:06:22.712Z] 20:06:22 INFO - zombiecheck | Checking for orphan process with PID: 3771
[task 2019-02-04T20:06:22.713Z] 20:06:22 INFO - zombiecheck | Checking for orphan process with PID: 3932
[task 2019-02-04T20:06:22.713Z] 20:06:22 INFO - Stopping web server
[task 2019-02-04T20:06:22.750Z] 20:06:22 INFO - Stopping web socket server
[task 2019-02-04T20:06:22.751Z] 20:06:22 INFO - Stopping ssltunnel
[task 2019-02-04T20:06:22.773Z] 20:06:22 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2019-02-04T20:06:22.774Z] 20:06:22 INFO - runtests.py | Running tests: end.
[task 2019-02-04T20:06:22.810Z] 20:06:22 INFO - Buffered messages finished
[task 2019-02-04T20:06:22.811Z] 20:06:22 INFO - Running manifest: devtools/startup/aboutdevtools/test/browser.ini
[task 2019-02-04T20:06:22.811Z] 20:06:22 INFO - The following extra prefs will be set:
[task 2019-02-04T20:06:22.812Z] 20:06:22 INFO - dom.animations-api.core.enabled=true
[task 2019-02-04T20:06:22.812Z] 20:06:22 INFO - dom.animations-api.timelines.enabled=true
[task 2019-02-04T20:06:22.889Z] 20:06:22 INFO - Setting pipeline to PAUSED ...
[task 2019-02-04T20:06:22.890Z] 20:06:22 INFO - Pipeline is PREROLLING ...
[task 2019-02-04T20:06:22.894Z] 20:06:22 INFO - Pipeline is PREROLLED ...
[task 2019-02-04T20:06:22.894Z] 20:06:22 INFO - Setting pipeline to PLAYING ...
[task 2019-02-04T20:06:22.894Z] 20:06:22 INFO - New clock: GstSystemClock
[task 2019-02-04T20:06:22.931Z] 20:06:22 INFO - Got EOS from element "pipeline0".
[task 2019-02-04T20:06:22.931Z] 20:06:22 INFO - Execution ended after 0:00:00.033507216
[task 2019-02-04T20:06:22.931Z] 20:06:22 INFO - Setting pipeline to PAUSED ...
[task 2019-02-04T20:06:22.931Z] 20:06:22 INFO - Setting pipeline to READY ...
[task 2019-02-04T20:06:22.932Z] 20:06:22 INFO - (gst-launch-1.0:4575): GStreamer-CRITICAL *: gst_object_unref: assertion '((GObject ) object)->ref_count > 0' failed
[task 2019-02-04T20:06:22.932Z] 20:06:22 INFO - Setting pipeline to NULL ...
[task 2019-02-04T20:06:22.932Z] 20:06:22 INFO - Freeing pipeline ...
[task 2019-02-04T20:06:23.001Z] 20:06:23 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2019-02-04T20:06:23.009Z] 20:06:23 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2019-02-04T20:06:23.154Z] 20:06:23 INFO - pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2019-02-04T20:06:23.433Z] 20:06:23 INFO - Increasing default timeout to 90 seconds
[task 2019-02-04T20:06:23.435Z] 20:06:23 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2019-02-04T20:06:23.443Z] 20:06:23 INFO - LSan enabled.
[task 2019-02-04T20:06:23.444Z] 20:06:23 INFO - LSan using suppression file /builds/worker/workspace/build/tests/mochitest/lsan_suppressions.txt
[task 2019-02-04T20:06:23.445Z] 20:06:23 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2019-02-04T20:06:23.445Z] 20:06:23 INFO - UBSan enabled.
[task 2019-02-04T20:06:23.446Z] 20:06:23 INFO - UBSan using suppression file /builds/worker/workspace/build/tests/mochitest/ubsan_suppressions.txt
[task 2019-02-04T20:06:23.446Z] 20:06:23 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2019-02-04T20:06:23.448Z] 20:06:23 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2019-02-04T20:06:23.456Z] 20:06:23 INFO - MochitestServer : launching [u'/builds/worker/workspace/build/tests/bin/xpcshell', '-g', '/builds/worker/workspace/build/application/firefox', '-f', '/builds/worker/workspace/build/tests/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmpXhkgNP.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/worker/workspace/build/tests/mochitest/server.js']
[task 2019-02-04T20:06:23.457Z] 20:06:23 INFO - runtests.py | Server pid: 4603
[task 2019-02-04T20:06:23.466Z] 20:06:23 INFO - runtests.py | Websocket server pid: 4606
[task 2019-02-04T20:06:23.468Z] 20:06:23 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2019-02-04T20:06:23.484Z] 20:06:23 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2019-02-04T20:06:23.492Z] 20:06:23 INFO - runtests.py | SSL tunnel pid: 4611
[task 2019-02-04T20:06:23.792Z] 20:06:23 INFO - runtests.py | Running with scheme: http
[task 2019-02-04T20:06:23.792Z] 20:06:23 INFO - runtests.py | Running with e10s: True
[task 2019-02-04T20:06:23.792Z] 20:06:23 INFO - runtests.py | Running with serviceworker_e10s: False
[task 2019-02-04T20:06:23.793Z] 20:06:23 INFO - runtests.py | Running tests: start.
[task 2019-02-04T20:06:23.794Z] 20:06:23 INFO -
[task 2019-02-04T20:06:23.819Z] 20:06:23 WARNING - Found 'firefox' running before starting test browser!
[task 2019-02-04T20:06:23.819Z] 20:06:23 WARNING - {'username': 'worker', 'cmdline': [], 'ppid': 1, 'pid': 4567, 'name': 'firefox'}
[task 2019-02-04T20:06:23.819Z] 20:06:23 WARNING -
[task 2019-02-04T20:06:23.819Z] 20:06:23 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpXhkgNP.mozrunner
[task 2019-02-04T20:06:23.827Z] 20:06:23 INFO - runtests.py | Application pid: 4631
[task 2019-02-04T20:06:23.827Z] 20:06:23 INFO - TEST-INFO | started process GECKO(4631)
[task 2019-02-04T20:06:25.689Z] 20:06:25 INFO - GECKO(4631) | 1549310785685 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
[task 2019-02-04T20:06:25.691Z] 20:06:25 INFO - GECKO(4631) | 1549310785687 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2019-02-04T20:06:25.692Z] 20:06:25 INFO - GECKO(4631) | 1549310785687 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader
[task 2019-02-04T20:06:26.254Z] 20:06:26 INFO - GECKO(4631) | 1549310786248 Marionette TRACE Received observer notification profile-after-change
[task 2019-02-04T20:06:26.436Z] 20:06:26 INFO - GECKO(4631) | 1549310786433 Marionette TRACE Received observer notification command-line-startup
[task 2019-02-04T20:06:26.437Z] 20:06:26 INFO - GECKO(4631) | 1549310786434 Marionette TRACE Received observer notification nsPref:changed
[task 2019-02-04T20:06:26.438Z] 20:06:26 INFO - GECKO(4631) | 1549310786434 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false)
[task 2019-02-04T20:06:26.924Z] 20:06:26 INFO - GECKO(4631) | 1549310786920 Marionette TRACE Received observer notification toplevel-window-ready
[task 2019-02-04T20:06:31.092Z] 20:06:31 INFO - GECKO(4631) | 1549310791088 Marionette TRACE Received observer notification sessionstore-windows-restored
[task 2019-02-04T20:06:31.093Z] 20:06:31 INFO - GECKO(4631) | 1549310791089 Marionette TRACE Received observer notification marionette-startup-requested
[task 2019-02-04T20:06:31.094Z] 20:06:31 INFO - GECKO(4631) | 1549310791090 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
[task 2019-02-04T20:06:32.608Z] 20:06:32 INFO - GECKO(4631) | 1549310792591 Marionette TRACE All scripts recorded.
[task 2019-02-04T20:06:32.948Z] 20:06:32 INFO - GECKO(4631) | 1549310792936 Marionette INFO Listening on port 2828
[task 2019-02-04T20:06:32.948Z] 20:06:32 INFO - GECKO(4631) | 1549310792937 Marionette DEBUG Remote service is active
[task 2019-02-04T20:06:32.985Z] 20:06:32 INFO - GECKO(4631) | 1549310792977 Marionette DEBUG Accepted connection 0 from 127.0.0.1:57240
[task 2019-02-04T20:06:32.993Z] 20:06:32 INFO - GECKO(4631) | 1549310792987 Marionette DEBUG Closed connection 0
[task 2019-02-04T20:06:32.996Z] 20:06:32 INFO - GECKO(4631) | 1549310792989 Marionette DEBUG Accepted connection 1 from 127.0.0.1:57242
[task 2019-02-04T20:06:33.006Z] 20:06:33 INFO - GECKO(4631) | 1549310792996 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2019-02-04T20:06:33.225Z] 20:06:33 INFO - GECKO(4631) | 1549310793221 Marionette TRACE [4294967297] Frame script loaded
[task 2019-02-04T20:06:33.226Z] 20:06:33 INFO - GECKO(4631) | 1549310793225 Marionette TRACE [4294967297] Frame script registered
[task 2019-02-04T20:06:33.247Z] 20:06:33 INFO - GECKO(4631) | 1549310793244 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"2126f4e1-e272-4de3-9e8d-d69fb3bfd9a9","capabilities":{"browserName":"firefox","browserVersion":"67.0a ... p/tmpXhkgNP.mozrunner","moz:shutdownTimeout":180000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2019-02-04T20:06:33.284Z] 20:06:33 INFO - GECKO(4631) | 1549310793274 Marionette DEBUG 1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpr7IKQa.zip","temporary":false}]
[task 2019-02-04T20:06:33.503Z] 20:06:33 INFO - GECKO(4631) | 1549310793492 Marionette DEBUG 1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2019-02-04T20:06:33.560Z] 20:06:33 INFO - GECKO(4631) | 1549310793548 Marionette DEBUG 1 -> [0,3,"Addon:Install",{"path":"/tmp/tmpR6wOns.zip","temporary":false}]
[task 2019-02-04T20:06:33.677Z] 20:06:33 INFO - GECKO(4631) | 1549310793667 Marionette DEBUG 1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2019-02-04T20:06:33.680Z] 20:06:33 INFO - GECKO(4631) | 1549310793670 Marionette DEBUG 1 -> [0,4,"Marionette:GetContext",{}]
[task 2019-02-04T20:06:33.680Z] 20:06:33 INFO - GECKO(4631) | 1549310793672 Marionette DEBUG 1 <- [1,4,null,{"value":"content"}]
[task 2019-02-04T20:06:33.682Z] 20:06:33 INFO - GECKO(4631) | 1549310793676 Marionette DEBUG 1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-02-04T20:06:33.682Z] 20:06:33 INFO - GECKO(4631) | 1549310793677 Marionette DEBUG 1 <- [1,5,null,{"value":null}]
[task 2019-02-04T20:06:33.691Z] 20:06:33 INFO - GECKO(4631) | 1549310793683 Marionette DEBUG 1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/ This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... testUrl":"about:blank","flavor":"browser-chrome"}],"filename":"tests/mochitest/runtests.py","sandbox":"default","line":1712}]
[task 2019-02-04T20:06:33.752Z] 20:06:33 INFO - GECKO(4631) | 1549310793747 Marionette DEBUG 1 <- [1,6,null,{"value":null}]
[task 2019-02-04T20:06:33.854Z] 20:06:33 INFO - GECKO(4631) | 1549310793851 Marionette DEBUG 1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2019-02-04T20:06:33.856Z] 20:06:33 INFO - GECKO(4631) | 1549310793853 Marionette DEBUG 1 <- [1,7,null,{"value":null}]
[task 2019-02-04T20:06:33.865Z] 20:06:33 INFO - GECKO(4631) | 1549310793860 Marionette DEBUG 1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2019-02-04T20:06:33.867Z] 20:06:33 INFO - GECKO(4631) | 1549310793865 Marionette DEBUG 1 <- [1,8,null,{"value":null}]
[task 2019-02-04T20:06:33.876Z] 20:06:33 INFO - runtests.py | Waiting for browser...
[task 2019-02-04T20:06:33.886Z] 20:06:33 INFO - GECKO(4631) | 1549310793879 Marionette DEBUG Closed connection 1
[task 2019-02-04T20:06:34.257Z] 20:06:34 INFO - *** Start BrowserChrome Test Results ***
[task 2019-02-04T20:06:34.323Z] 20:06:34 INFO - checking window state
[task 2019-02-04T20:06:34.422Z] 20:06:34 INFO - TEST-START | devtools/startup/aboutdevtools/test/browser_aboutdevtools_closes_page.js

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: REOPENED → RESOLVED
Closed: 6 years ago → 6 years ago
Resolution: --- → INCOMPLETE

New occurrence:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=283835706&repo=autoland&lineNumber=3517

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

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