Closed
Bug 1755812
Opened 4 years ago
Closed 3 years ago
Intermittent browser/extensions/formautofill/test/browser/browser_autocomplete_footer.js | application terminated with exit code 1
Categories
(Toolkit :: Form Autofill, defect, P5)
Toolkit
Form Autofill
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=368149105&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/EzwhDQm0SQCPhNr2F5FxuA/runs/0/artifacts/public/logs/live_backing.log
[task 2022-02-16T22:34:39.684Z] 22:34:39 INFO - TEST-START | browser/extensions/formautofill/test/browser/browser_autocomplete_footer.js
[task 2022-02-16T22:34:40.159Z] 22:34:40 INFO - GECKO(5644) | must wait for focus in content
[task 2022-02-16T22:34:40.914Z] 22:34:40 INFO - GECKO(5644) | JavaScript error: resource://gre/modules/LoginManagerChild.jsm, line 209: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIAutoCompleteController.getStyleAt]
[task 2022-02-16T22:34:40.917Z] 22:34:40 INFO - GECKO(5644) | Gdk-Message: 22:34:40.915: firefox-nightly-autoland: Fatal IO error 11 (Resource temporarily unavailable) on X server :0.
[task 2022-02-16T22:34:40.953Z] 22:34:40 INFO - GECKO(5644) | Exiting due to channel error.
[task 2022-02-16T22:34:40.956Z] 22:34:40 INFO - GECKO(5644) | Exiting due to channel error.
[task 2022-02-16T22:34:40.956Z] 22:34:40 INFO - GECKO(5644) | Exiting due to channel error.
[task 2022-02-16T22:34:40.956Z] 22:34:40 INFO - GECKO(5644) | Exiting due to channel error.
[task 2022-02-16T22:34:40.956Z] 22:34:40 INFO - GECKO(5644) | Exiting due to channel error.
[task 2022-02-16T22:34:40.982Z] 22:34:40 INFO - TEST-INFO | Main app process: exit 1
[task 2022-02-16T22:34:40.982Z] 22:34:40 INFO - Buffered messages logged at 22:34:39
[task 2022-02-16T22:34:40.982Z] 22:34:40 INFO - Entering test bound setup
[task 2022-02-16T22:34:40.982Z] 22:34:40 INFO - Leaving test bound setup
[task 2022-02-16T22:34:40.982Z] 22:34:40 INFO - Entering test bound setup_storage
[task 2022-02-16T22:34:40.982Z] 22:34:40 INFO - expecting address saved
[task 2022-02-16T22:34:40.983Z] 22:34:40 INFO - expecting address saved
[task 2022-02-16T22:34:40.983Z] 22:34:40 INFO - expecting address saved
[task 2022-02-16T22:34:40.984Z] 22:34:40 INFO - expecting address saved
[task 2022-02-16T22:34:40.985Z] 22:34:40 INFO - Leaving test bound setup_storage
[task 2022-02-16T22:34:40.985Z] 22:34:40 INFO - Entering test bound test_press_enter_on_footer
[task 2022-02-16T22:34:40.986Z] 22:34:40 INFO - Buffered messages logged at 22:34:40
[task 2022-02-16T22:34:40.987Z] 22:34:40 INFO - expecting the target input being focused and identified
[task 2022-02-16T22:34:40.988Z] 22:34:40 INFO - !previouslyFocused
[task 2022-02-16T22:34:40.988Z] 22:34:40 INFO - FieldsIdentified
[task 2022-02-16T22:34:40.989Z] 22:34:40 INFO - openPopupOn: before VK_DOWN on #organization
[task 2022-02-16T22:34:40.990Z] 22:34:40 INFO - expectPopupOpen
[task 2022-02-16T22:34:40.991Z] 22:34:40 INFO - getDisplayedPopupItems
[task 2022-02-16T22:34:40.991Z] 22:34:40 INFO - Buffered messages finished
[task 2022-02-16T22:34:40.992Z] 22:34:40 ERROR - TEST-UNEXPECTED-FAIL | browser/extensions/formautofill/test/browser/browser_autocomplete_footer.js | application terminated with exit code 1
[task 2022-02-16T22:34:40.993Z] 22:34:40 INFO - runtests.py | Application ran for: 0:00:07.839491
[task 2022-02-16T22:34:40.994Z] 22:34:40 INFO - zombiecheck | Reading PID log: /tmp/tmpgg8wd516pidlog
[task 2022-02-16T22:34:40.994Z] 22:34:40 INFO - ==> process 5644 launched child process 5665
[task 2022-02-16T22:34:40.995Z] 22:34:40 INFO - ==> process 5644 launched child process 5714
[task 2022-02-16T22:34:40.996Z] 22:34:40 INFO - ==> process 5644 launched child process 5736
[task 2022-02-16T22:34:40.997Z] 22:34:40 INFO - ==> process 5644 launched child process 5770
[task 2022-02-16T22:34:40.997Z] 22:34:40 INFO - ==> process 5644 launched child process 5842
[task 2022-02-16T22:34:40.998Z] 22:34:40 INFO - ==> process 5644 launched child process 5865
[task 2022-02-16T22:34:40.999Z] 22:34:40 INFO - zombiecheck | Checking for orphan process with PID: 5665
[task 2022-02-16T22:34:41.000Z] 22:34:40 INFO - zombiecheck | Checking for orphan process with PID: 5736
[task 2022-02-16T22:34:41.000Z] 22:34:40 INFO - zombiecheck | Checking for orphan process with PID: 5865
[task 2022-02-16T22:34:41.001Z] 22:34:40 INFO - zombiecheck | Checking for orphan process with PID: 5770
[task 2022-02-16T22:34:41.002Z] 22:34:40 INFO - zombiecheck | Checking for orphan process with PID: 5714
[task 2022-02-16T22:34:41.002Z] 22:34:40 INFO - zombiecheck | Checking for orphan process with PID: 5842
[task 2022-02-16T22:34:41.003Z] 22:34:41 INFO - Stopping web server
[task 2022-02-16T22:34:41.004Z] 22:34:41 INFO - Server shut down.
[task 2022-02-16T22:34:41.012Z] 22:34:41 INFO - Web server killed.
[task 2022-02-16T22:34:41.013Z] 22:34:41 INFO - Stopping web socket server
[task 2022-02-16T22:34:41.033Z] 22:34:41 INFO - Stopping ssltunnel
[task 2022-02-16T22:34:41.053Z] 22:34:41 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2022-02-16T22:34:41.053Z] 22:34:41 INFO - runtests.py | Running tests: end.
[task 2022-02-16T22:34:41.070Z] 22:34:41 INFO - Buffered messages finished
[task 2022-02-16T22:34:41.071Z] 22:34:41 INFO - Running manifest: browser/extensions/pictureinpicture/tests/browser/browser.ini
[task 2022-02-16T22:34:41.072Z] 22:34:41 INFO - The following extra prefs will be set:
[task 2022-02-16T22:34:41.072Z] 22:34:41 INFO - media.videocontrols.picture-in-picture.enabled=true
[task 2022-02-16T22:34:41.072Z] 22:34:41 INFO - media.videocontrols.picture-in-picture.video-toggle.enabled=true
[task 2022-02-16T22:34:41.072Z] 22:34:41 INFO - media.videocontrols.picture-in-picture.video-toggle.testing=true
[task 2022-02-16T22:34:41.073Z] 22:34:41 INFO - media.videocontrols.picture-in-picture.video-toggle.always-show=true
[task 2022-02-16T22:34:41.073Z] 22:34:41 INFO - media.videocontrols.picture-in-picture.video-toggle.has-used=true
[task 2022-02-16T22:34:41.073Z] 22:34:41 INFO - media.videocontrols.picture-in-picture.video-toggle.position="right"
[task 2022-02-16T22:34:41.093Z] 22:34:41 INFO - Setting pipeline to PAUSED ...
[task 2022-02-16T22:34:41.094Z] 22:34:41 INFO - Pipeline is PREROLLING ...
[task 2022-02-16T22:34:41.096Z] 22:34:41 INFO - Pipeline is PREROLLED ...
[task 2022-02-16T22:34:41.098Z] 22:34:41 INFO - Setting pipeline to PLAYING ...
[task 2022-02-16T22:34:41.098Z] 22:34:41 INFO - New clock: GstSystemClock
[task 2022-02-16T22:34:41.131Z] 22:34:41 INFO - Got EOS from element "pipeline0".
[task 2022-02-16T22:34:41.131Z] 22:34:41 INFO - Execution ended after 0:00:00.033403587
[task 2022-02-16T22:34:41.131Z] 22:34:41 INFO - Setting pipeline to PAUSED ...
[task 2022-02-16T22:34:41.131Z] 22:34:41 INFO - Setting pipeline to READY ...
[task 2022-02-16T22:34:41.132Z] 22:34:41 INFO - (gst-launch-1.0:5893): GStreamer-CRITICAL **: 22:34:41.130: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2022-02-16T22:34:41.132Z] 22:34:41 INFO - Setting pipeline to NULL ...
[task 2022-02-16T22:34:41.132Z] 22:34:41 INFO - Freeing pipeline ...
[task 2022-02-16T22:34:41.290Z] 22:34:41 INFO - PID 5900 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2022-02-16T22:34:41.442Z] 22:34:41 INFO - MochitestServer : launching ['/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/tmpg9enf7s3.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 2022-02-16T22:34:41.442Z] 22:34:41 INFO - runtests.py | Server pid: 5912
[task 2022-02-16T22:34:41.462Z] 22:34:41 INFO - runtests.py | Websocket server pid: 5915
[task 2022-02-16T22:34:41.480Z] 22:34:41 INFO - runtests.py | SSL tunnel pid: 5920
[task 2022-02-16T22:34:41.585Z] 22:34:41 INFO - runtests.py | Running with scheme: http
[task 2022-02-16T22:34:41.585Z] 22:34:41 INFO - runtests.py | Running with e10s: True
[task 2022-02-16T22:34:41.585Z] 22:34:41 INFO - runtests.py | Running with fission: False
[task 2022-02-16T22:34:41.585Z] 22:34:41 INFO - runtests.py | Running with cross-origin iframes: False
[task 2022-02-16T22:34:41.585Z] 22:34:41 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2022-02-16T22:34:41.585Z] 22:34:41 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2022-02-16T22:34:41.586Z] 22:34:41 INFO - runtests.py | Running tests: start.
[task 2022-02-16T22:34:41.586Z] 22:34:41 INFO -
[task 2022-02-16T22:34:41.607Z] 22:34:41 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpg9enf7s3.mozrunner
[task 2022-02-16T22:34:41.618Z] 22:34:41 INFO - runtests.py | Application pid: 5940
[task 2022-02-16T22:34:41.618Z] 22:34:41 INFO - TEST-INFO | started process GECKO(5940)
[task 2022-02-16T22:34:42.172Z] 22:34:42 INFO - GECKO(5940) | 1645050882171 Marionette INFO Marionette enabled
[task 2022-02-16T22:34:42.227Z] 22:34:42 INFO - GECKO(5940) | 1645050882225 Marionette TRACE Received observer notification toplevel-window-ready
[task 2022-02-16T22:34:45.694Z] 22:34:45 INFO - GECKO(5940) | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpg9enf7s3.mozrunner/search.json.mozlz4", (void 0)))
[task 2022-02-16T22:34:46.753Z] 22:34:46 INFO - GECKO(5940) | 1645050886751 Marionette TRACE Received observer notification marionette-startup-requested
[task 2022-02-16T22:34:46.754Z] 22:34:46 INFO - GECKO(5940) | 1645050886752 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
[task 2022-02-16T22:34:46.782Z] 22:34:46 INFO - GECKO(5940) | 1645050886781 Marionette TRACE All scripts recorded.
[task 2022-02-16T22:34:46.784Z] 22:34:46 INFO - GECKO(5940) | 1645050886783 Marionette INFO Listening on port 2828
[task 2022-02-16T22:34:46.786Z] 22:34:46 INFO - GECKO(5940) | 1645050886783 Marionette DEBUG Marionette is listening
[task 2022-02-16T22:34:46.850Z] 22:34:46 INFO - GECKO(5940) | 1645050886848 Marionette DEBUG Accepted connection 0 from 127.0.0.1:56984
[task 2022-02-16T22:34:46.856Z] 22:34:46 INFO - GECKO(5940) | 1645050886855 Marionette DEBUG Closed connection 0
[task 2022-02-16T22:34:46.857Z] 22:34:46 INFO - GECKO(5940) | 1645050886855 Marionette DEBUG Accepted connection 1 from 127.0.0.1:56986
[task 2022-02-16T22:34:46.863Z] 22:34:46 INFO - GECKO(5940) | 1645050886862 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-02-16T22:34:46.883Z] 22:34:46 INFO - GECKO(5940) | 1645050886882 RemoteAgent TRACE [19] Document already finished loading: about:blank
[task 2022-02-16T22:34:46.897Z] 22:34:46 INFO - GECKO(5940) | 1645050886895 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"c19a3fd6-0f3b-4518-b1e9-a16dc787ed57","capabilities":{"browserName":"firefox","browserVersion":"99.0a ... 3.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2022-02-16T22:34:46.914Z] 22:34:46 INFO - GECKO(5940) | 1645050886913 Marionette DEBUG 1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpeg09uqg_.zip","temporary":false}]
[task 2022-02-16T22:34:46.966Z] 22:34:46 INFO - GECKO(5940) | 1645050886965 Marionette DEBUG 1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2022-02-16T22:34:46.996Z] 22:34:46 INFO - GECKO(5940) | 1645050886995 Marionette DEBUG 1 -> [0,3,"Addon:Install",{"path":"/tmp/tmpne85j_6v.zip","temporary":false}]
[task 2022-02-16T22:34:47.018Z] 22:34:47 INFO - GECKO(5940) | 1645050887016 Marionette DEBUG 1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2022-02-16T22:34:47.020Z] 22:34:47 INFO - GECKO(5940) | 1645050887018 Marionette DEBUG 1 -> [0,4,"Marionette:GetContext",{}]
[task 2022-02-16T22:34:47.021Z] 22:34:47 INFO - GECKO(5940) | 1645050887018 Marionette DEBUG 1 <- [1,4,null,{"value":"content"}]
[task 2022-02-16T22:34:47.023Z] 22:34:47 INFO - GECKO(5940) | 1645050887022 Marionette DEBUG 1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-02-16T22:34:47.024Z] 22:34:47 INFO - GECKO(5940) | 1645050887022 Marionette DEBUG 1 <- [1,5,null,{"value":null}]
[task 2022-02-16T22:34:47.027Z] 22:34:47 INFO - GECKO(5940) | 1645050887025 Marionette DEBUG 1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... hrome","testUrl":"about:blank"}],"newSandbox":true,"sandbox":"default","line":1989,"filename":"tests/mochitest/runtests.py"}]
[task 2022-02-16T22:34:47.035Z] 22:34:47 INFO - GECKO(5940) | 1645050887032 Marionette TRACE [7] MarionetteCommands actor created for window id 2
[task 2022-02-16T22:34:47.055Z] 22:34:47 INFO - GECKO(5940) | 1645050887054 Marionette TRACE Received observer notification domwindowopened
[task 2022-02-16T22:34:47.063Z] 22:34:47 INFO - GECKO(5940) | 1645050887062 Marionette DEBUG 1 <- [1,6,null,{"value":null}]
[task 2022-02-16T22:34:47.079Z] 22:34:47 INFO - GECKO(5940) | 1645050887078 Marionette DEBUG 1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2022-02-16T22:34:47.081Z] 22:34:47 INFO - GECKO(5940) | 1645050887078 Marionette DEBUG 1 <- [1,7,null,{"value":null}]
[task 2022-02-16T22:34:47.129Z] 22:34:47 INFO - GECKO(5940) | 1645050887128 Marionette DEBUG 1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2022-02-16T22:34:47.151Z] 22:34:47 INFO - GECKO(5940) | 1645050887150 Marionette DEBUG 1 <- [1,8,null,{"value":null}]
[task 2022-02-16T22:34:47.193Z] 22:34:47 INFO - runtests.py | Waiting for browser...
[task 2022-02-16T22:34:47.198Z] 22:34:47 INFO - GECKO(5940) | 1645050887197 Marionette DEBUG Closed connection 1
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
|
Reporter | |
Comment 3•4 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=372099328&repo=autoland
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 6•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: REOPENED → RESOLVED
Closed: 4 years ago → 4 years ago
Resolution: --- → INCOMPLETE
|
|
Reporter | |
Comment 7•3 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=393312902&repo=mozilla-central
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 9•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: REOPENED → RESOLVED
Closed: 4 years ago → 3 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•