Closed Bug 1727871 Opened 4 years ago Closed 4 years ago

Intermittent toolkit/components/extensions/test/browser/browser_ext_themes_arrowpanels.js | application terminated with exit code 1

Categories

(WebExtensions :: General, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=349737521&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/BvxaIBKkTiSeE-Xn-k3XiQ/runs/0/artifacts/public/logs/live_backing.log


[task 2021-08-27T01:56:22.032Z] 01:56:22     INFO - TEST-START | toolkit/components/extensions/test/browser/browser_ext_themes_arrowpanels.js
[task 2021-08-27T01:56:22.447Z] 01:56:22     INFO - GECKO(15498) | Gdk-Message: 01:56:22.442: firefox: Fatal IO error 11 (Resource temporarily unavailable) on X server :0.
[task 2021-08-27T01:56:22.465Z] 01:56:22     INFO - GECKO(15498) | Exiting due to channel error.
[task 2021-08-27T01:56:22.470Z] 01:56:22     INFO - GECKO(15498) | Exiting due to channel error.
[task 2021-08-27T01:56:22.476Z] 01:56:22     INFO - GECKO(15498) | Exiting due to channel error.
[task 2021-08-27T01:56:22.485Z] 01:56:22     INFO - GECKO(15498) | Exiting due to channel error.
[task 2021-08-27T01:56:22.485Z] 01:56:22     INFO - GECKO(15498) | Exiting due to channel error.
[task 2021-08-27T01:56:22.531Z] 01:56:22     INFO - TEST-INFO | Main app process: exit 1
[task 2021-08-27T01:56:22.532Z] 01:56:22     INFO - Buffered messages logged at 01:56:22
[task 2021-08-27T01:56:22.533Z] 01:56:22     INFO - Entering test bound test_popup_styling
[task 2021-08-27T01:56:22.533Z] 01:56:22     INFO - Extension loaded
[task 2021-08-27T01:56:22.533Z] 01:56:22     INFO - TEST-PASS | toolkit/components/extensions/test/browser/browser_ext_themes_arrowpanels.js | Popup background color should have been themed - "rgb(255, 0, 0)" == "rgb(255, 0, 0)" - 
[task 2021-08-27T01:56:22.533Z] 01:56:22     INFO - TEST-PASS | toolkit/components/extensions/test/browser/browser_ext_themes_arrowpanels.js | Popup text color should have been themed - "rgb(0, 128, 0)" == "rgb(0, 128, 0)" - 
[task 2021-08-27T01:56:22.533Z] 01:56:22     INFO - TEST-PASS | toolkit/components/extensions/test/browser/browser_ext_themes_arrowpanels.js | Popup text description color should have been themed - "rgba(0, 128, 0, 0.7)" == "rgba(0, 128, 0, 0.7)" - 
[task 2021-08-27T01:56:22.533Z] 01:56:22     INFO - TEST-PASS | toolkit/components/extensions/test/browser/browser_ext_themes_arrowpanels.js | Element left border color should be set. - "rgb(0, 0, 255)" == "rgb(0, 0, 255)" - 
[task 2021-08-27T01:56:22.533Z] 01:56:22     INFO - TEST-PASS | toolkit/components/extensions/test/browser/browser_ext_themes_arrowpanels.js | Element right border color should be set. - "rgb(0, 0, 255)" == "rgb(0, 0, 255)" - 
[task 2021-08-27T01:56:22.533Z] 01:56:22     INFO - TEST-PASS | toolkit/components/extensions/test/browser/browser_ext_themes_arrowpanels.js | Element top border color should be set. - "rgb(0, 0, 255)" == "rgb(0, 0, 255)" - 
[task 2021-08-27T01:56:22.533Z] 01:56:22     INFO - TEST-PASS | toolkit/components/extensions/test/browser/browser_ext_themes_arrowpanels.js | Element bottom border color should be set. - "rgb(0, 0, 255)" == "rgb(0, 0, 255)" - 
[task 2021-08-27T01:56:22.533Z] 01:56:22     INFO - Buffered messages finished
[task 2021-08-27T01:56:22.533Z] 01:56:22    ERROR - TEST-UNEXPECTED-FAIL | toolkit/components/extensions/test/browser/browser_ext_themes_arrowpanels.js | application terminated with exit code 1
[task 2021-08-27T01:56:22.536Z] 01:56:22     INFO - runtests.py | Application ran for: 0:00:06.831475
[task 2021-08-27T01:56:22.537Z] 01:56:22     INFO - zombiecheck | Reading PID log: /tmp/tmp7dypnbq3pidlog
[task 2021-08-27T01:56:22.538Z] 01:56:22     INFO - ==> process 15498 launched child process 15518
[task 2021-08-27T01:56:22.538Z] 01:56:22     INFO - ==> process 15498 launched child process 15571
[task 2021-08-27T01:56:22.539Z] 01:56:22     INFO - ==> process 15498 launched child process 15592
[task 2021-08-27T01:56:22.540Z] 01:56:22     INFO - ==> process 15498 launched child process 15643
[task 2021-08-27T01:56:22.540Z] 01:56:22     INFO - ==> process 15498 launched child process 15675
[task 2021-08-27T01:56:22.541Z] 01:56:22     INFO - ==> process 15498 launched child process 15712
[task 2021-08-27T01:56:22.541Z] 01:56:22     INFO - zombiecheck | Checking for orphan process with PID: 15712
[task 2021-08-27T01:56:22.542Z] 01:56:22     INFO - zombiecheck | Checking for orphan process with PID: 15592
[task 2021-08-27T01:56:22.543Z] 01:56:22     INFO - zombiecheck | Checking for orphan process with PID: 15675
[task 2021-08-27T01:56:22.544Z] 01:56:22     INFO - zombiecheck | Checking for orphan process with PID: 15571
[task 2021-08-27T01:56:22.544Z] 01:56:22     INFO - zombiecheck | Checking for orphan process with PID: 15643
[task 2021-08-27T01:56:22.545Z] 01:56:22     INFO - zombiecheck | Checking for orphan process with PID: 15518
[task 2021-08-27T01:56:22.545Z] 01:56:22     INFO - Stopping web server
[task 2021-08-27T01:56:22.546Z] 01:56:22     INFO - Server shut down.
[task 2021-08-27T01:56:22.567Z] 01:56:22     INFO - Web server killed.
[task 2021-08-27T01:56:22.568Z] 01:56:22     INFO - Stopping web socket server
[task 2021-08-27T01:56:22.588Z] 01:56:22     INFO - Stopping ssltunnel
[task 2021-08-27T01:56:22.608Z] 01:56:22  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2021-08-27T01:56:22.609Z] 01:56:22     INFO - runtests.py | Running tests: end.
[task 2021-08-27T01:56:22.626Z] 01:56:22     INFO - Buffered messages finished
[task 2021-08-27T01:56:22.627Z] 01:56:22     INFO - Running manifest: toolkit/components/normandy/test/browser/browser.ini
[task 2021-08-27T01:56:22.647Z] 01:56:22     INFO -  Setting pipeline to PAUSED ...
[task 2021-08-27T01:56:22.648Z] 01:56:22     INFO -  Pipeline is PREROLLING ...
[task 2021-08-27T01:56:22.650Z] 01:56:22     INFO -  Pipeline is PREROLLED ...
[task 2021-08-27T01:56:22.650Z] 01:56:22     INFO -  Setting pipeline to PLAYING ...
[task 2021-08-27T01:56:22.651Z] 01:56:22     INFO -  New clock: GstSystemClock
[task 2021-08-27T01:56:22.685Z] 01:56:22     INFO -  Got EOS from element "pipeline0".
[task 2021-08-27T01:56:22.685Z] 01:56:22     INFO -  Execution ended after 0:00:00.033340440
[task 2021-08-27T01:56:22.686Z] 01:56:22     INFO -  Setting pipeline to PAUSED ...
[task 2021-08-27T01:56:22.687Z] 01:56:22     INFO -  Setting pipeline to READY ...
[task 2021-08-27T01:56:22.688Z] 01:56:22     INFO -  (gst-launch-1.0:15756): GStreamer-CRITICAL **: 01:56:22.684: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2021-08-27T01:56:22.690Z] 01:56:22     INFO -  Setting pipeline to NULL ...
[task 2021-08-27T01:56:22.691Z] 01:56:22     INFO -  Freeing pipeline ...
[task 2021-08-27T01:56:22.893Z] 01:56:22     INFO - PID 15766 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2021-08-27T01:56:23.011Z] 01:56:23     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/tmpwc6qqlz1.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 2021-08-27T01:56:23.012Z] 01:56:23     INFO - runtests.py | Server pid: 15775
[task 2021-08-27T01:56:23.030Z] 01:56:23     INFO - runtests.py | Websocket server pid: 15778
[task 2021-08-27T01:56:23.040Z] 01:56:23     INFO - runtests.py | SSL tunnel pid: 15783
[task 2021-08-27T01:56:23.094Z] 01:56:23     INFO - runtests.py | Running with scheme: http
[task 2021-08-27T01:56:23.094Z] 01:56:23     INFO - runtests.py | Running with e10s: True
[task 2021-08-27T01:56:23.094Z] 01:56:23     INFO - runtests.py | Running with fission: False
[task 2021-08-27T01:56:23.094Z] 01:56:23     INFO - runtests.py | Running with cross-origin iframes: False
[task 2021-08-27T01:56:23.094Z] 01:56:23     INFO - runtests.py | Running with serviceworker_e10s: True
[task 2021-08-27T01:56:23.094Z] 01:56:23     INFO - runtests.py | Running with socketprocess_e10s: False
[task 2021-08-27T01:56:23.094Z] 01:56:23     INFO - runtests.py | Running tests: start.
[task 2021-08-27T01:56:23.094Z] 01:56:23     INFO - 
[task 2021-08-27T01:56:23.139Z] 01:56:23     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpwc6qqlz1.mozrunner
[task 2021-08-27T01:56:23.151Z] 01:56:23     INFO - runtests.py | Application pid: 15801
[task 2021-08-27T01:56:23.152Z] 01:56:23     INFO - TEST-INFO | started process GECKO(15801)
[task 2021-08-27T01:56:23.721Z] 01:56:23     INFO - GECKO(15801) | 1630029383719	Marionette	INFO	Marionette enabled
[task 2021-08-27T01:56:23.772Z] 01:56:23     INFO - GECKO(15801) | 1630029383771	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2021-08-27T01:56:25.500Z] 01:56:25     INFO - GECKO(15801) | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpwc6qqlz1.mozrunner/search.json.mozlz4", (void 0)))
[task 2021-08-27T01:56:26.198Z] 01:56:26     INFO - GECKO(15801) | 1630029386196	Marionette	TRACE	Received observer notification marionette-startup-requested
[task 2021-08-27T01:56:26.202Z] 01:56:26     INFO - GECKO(15801) | 1630029386196	Marionette	TRACE	Waiting until startup recorder finished recording startup scripts...
[task 2021-08-27T01:56:26.211Z] 01:56:26     INFO - GECKO(15801) | 1630029386210	Marionette	TRACE	All scripts recorded.
[task 2021-08-27T01:56:26.213Z] 01:56:26     INFO - GECKO(15801) | 1630029386212	Marionette	INFO	Listening on port 2828
[task 2021-08-27T01:56:26.214Z] 01:56:26     INFO - GECKO(15801) | 1630029386212	Marionette	DEBUG	Marionette is listening
[task 2021-08-27T01:56:26.272Z] 01:56:26     INFO - GECKO(15801) | 1630029386271	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:58644
[task 2021-08-27T01:56:26.275Z] 01:56:26     INFO - GECKO(15801) | 1630029386273	Marionette	DEBUG	Closed connection 0
[task 2021-08-27T01:56:26.275Z] 01:56:26     INFO - GECKO(15801) | 1630029386274	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:58646
[task 2021-08-27T01:56:26.280Z] 01:56:26     INFO - GECKO(15801) | 1630029386279	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-08-27T01:56:26.289Z] 01:56:26     INFO - GECKO(15801) | 1630029386288	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"ed3b95bd-1cf4-499c-b1be-671bec3ce150","capabilities":{"browserName":"firefox","browserVersion":"93.0a ... 1.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2021-08-27T01:56:26.304Z] 01:56:26     INFO - GECKO(15801) | 1630029386302	Marionette	DEBUG	1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpeekhz7f2.zip","temporary":false}]
[task 2021-08-27T01:56:26.362Z] 01:56:26     INFO - GECKO(15801) | 1630029386361	Marionette	DEBUG	1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2021-08-27T01:56:26.394Z] 01:56:26     INFO - GECKO(15801) | 1630029386391	Marionette	DEBUG	1 -> [0,3,"Addon:Install",{"path":"/tmp/tmpg13cyje9.zip","temporary":false}]
[task 2021-08-27T01:56:26.424Z] 01:56:26     INFO - GECKO(15801) | 1630029386423	Marionette	DEBUG	1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2021-08-27T01:56:26.426Z] 01:56:26     INFO - GECKO(15801) | 1630029386425	Marionette	DEBUG	1 -> [0,4,"Marionette:GetContext",{}]
[task 2021-08-27T01:56:26.427Z] 01:56:26     INFO - GECKO(15801) | 1630029386425	Marionette	DEBUG	1 <- [1,4,null,{"value":"content"}]
[task 2021-08-27T01:56:26.429Z] 01:56:26     INFO - GECKO(15801) | 1630029386428	Marionette	DEBUG	1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-08-27T01:56:26.430Z] 01:56:26     INFO - GECKO(15801) | 1630029386429	Marionette	DEBUG	1 <- [1,5,null,{"value":null}]
[task 2021-08-27T01:56:26.432Z] 01:56:26     INFO - GECKO(15801) | 1630029386431	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":1937,"filename":"tests/mochitest/runtests.py"}]
[task 2021-08-27T01:56:26.436Z] 01:56:26     INFO - GECKO(15801) | 1630029386436	Marionette	TRACE	[7] MarionetteCommands actor created for window id 2
[task 2021-08-27T01:56:26.468Z] 01:56:26     INFO - GECKO(15801) | 1630029386468	Marionette	TRACE	Received observer notification domwindowopened
[task 2021-08-27T01:56:26.473Z] 01:56:26     INFO - GECKO(15801) | 1630029386472	Marionette	DEBUG	1 <- [1,6,null,{"value":null}]
[task 2021-08-27T01:56:26.486Z] 01:56:26     INFO - GECKO(15801) | 1630029386485	Marionette	DEBUG	1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2021-08-27T01:56:26.487Z] 01:56:26     INFO - GECKO(15801) | 1630029386485	Marionette	DEBUG	1 <- [1,7,null,{"value":null}]
[task 2021-08-27T01:56:26.500Z] 01:56:26     INFO - GECKO(15801) | 1630029386499	Marionette	DEBUG	1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2021-08-27T01:56:26.503Z] 01:56:26     INFO - runtests.py | Waiting for browser...
[task 2021-08-27T01:56:26.503Z] 01:56:26     INFO - GECKO(15801) | 1630029386500	Marionette	DEBUG	1 <- [1,8,null,{"value":null}]
[task 2021-08-27T01:56:26.505Z] 01:56:26     INFO - GECKO(15801) | 1630029386502	Marionette	DEBUG	Closed connection 1
[task 2021-08-27T01:56:26.743Z] 01:56:26     INFO - TEST-START | toolkit/components/normandy/test/browser/browser_ActionsManager.js
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.