1775461 - Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_quit_restart.py TestQuitRestart.test_in_app_restart_with_callback_but_process_quit | AssertionError: "Process unexpectedly quit without restarting" does not match "Process has

Status: RESOLVED INCOMPLETE

(Testing :: Marionette Client and Harness, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-06-22T11:05:41.904Z] 11:05:41     INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_quit_restart.py TestQuitRestart.test_in_app_restart_with_callback_but_process_quit
[task 2022-06-22T11:05:41.905Z] 11:05:41     INFO -  1655895941904	Marionette	DEBUG	Accepted connection 2 from 127.0.0.1:40590
[task 2022-06-22T11:05:41.911Z] 11:05:41     INFO -  1655895941910	Marionette	DEBUG	2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-06-22T11:05:41.917Z] 11:05:41     INFO -  1655895941912	Marionette	DEBUG	Waiting for initial application window
[task 2022-06-22T11:05:41.921Z] 11:05:41     INFO -  1655895941914	RemoteAgent	TRACE	[30] Document already finished loading: about:blank
[task 2022-06-22T11:05:41.925Z] 11:05:41     INFO -  1655895941918	Marionette	DEBUG	2 <- [1,1,null,{"sessionId":"9bc67cbe-5e59-4ea9-96e6-a3d8082304b1","capabilities":{"browserName":"firefox","browserVersion":"103.0 ... ownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2022-06-22T11:05:41.931Z] 11:05:41     INFO -  1655895941930	Marionette	DEBUG	2 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2022-06-22T11:05:41.937Z] 11:05:41     INFO -  1655895941936	Marionette	DEBUG	2 <- [1,2,null,{"value":null}]
[task 2022-06-22T11:05:41.942Z] 11:05:41     INFO -  1655895941938	Marionette	DEBUG	2 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2022-06-22T11:05:41.946Z] 11:05:41     INFO -  1655895941939	Marionette	DEBUG	2 <- [1,3,null,{"value":null}]
[task 2022-06-22T11:05:41.952Z] 11:05:41     INFO -  1655895941947	Marionette	DEBUG	2 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2022-06-22T11:05:41.956Z] 11:05:41     INFO -  1655895941948	Marionette	DEBUG	2 <- [1,4,null,{"value":null}]
[task 2022-06-22T11:05:41.960Z] 11:05:41     INFO -  1655895941952	Marionette	DEBUG	2 -> [0,5,"Marionette:GetContext",{}]
[task 2022-06-22T11:05:41.964Z] 11:05:41     INFO -  1655895941953	Marionette	DEBUG	2 <- [1,5,null,{"value":"content"}]
[task 2022-06-22T11:05:41.970Z] 11:05:41     INFO -  1655895941960	Marionette	DEBUG	2 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-06-22T11:05:41.974Z] 11:05:41     INFO -  1655895941961	Marionette	DEBUG	2 <- [1,6,null,{"value":null}]
[task 2022-06-22T11:05:41.980Z] 11:05:41     INFO -  1655895941970	Marionette	DEBUG	2 -> [0,7,"WebDriver:ExecuteScript",{"script":"const { Preferences } = ChromeUtils.import(\n                  \"resource://gre/mod ... ox":true,"sandbox":"default","line":810,"filename":"../../venv/lib/python3.6/site-packages/marionette_driver/marionette.py"}]
[task 2022-06-22T11:05:41.984Z] 11:05:41     INFO -  1655895941973	Marionette	TRACE	[1] MarionetteCommands actor created for window id 2
[task 2022-06-22T11:05:41.989Z] 11:05:41     INFO -  1655895941979	Marionette	DEBUG	2 <- [1,7,null,{"value":"https://www.mozilla.org/projects/firefox/%VERSION%/firstrun/"}]
[task 2022-06-22T11:05:41.994Z] 11:05:41     INFO -  1655895941986	Marionette	DEBUG	2 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2022-06-22T11:05:41.998Z] 11:05:41     INFO -  1655895941987	Marionette	DEBUG	2 <- [1,8,null,{"value":null}]
[task 2022-06-22T11:05:42.002Z] 11:05:41     INFO -  1655895941988	Marionette	DEBUG	2 -> [0,9,"Marionette:GetContext",{}]
[task 2022-06-22T11:05:42.006Z] 11:05:41     INFO -  1655895941989	Marionette	DEBUG	2 <- [1,9,null,{"value":"content"}]
[task 2022-06-22T11:05:42.010Z] 11:05:41     INFO -  1655895941991	Marionette	DEBUG	2 -> [0,10,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-06-22T11:05:42.015Z] 11:05:41     INFO -  1655895941992	Marionette	DEBUG	2 <- [1,10,null,{"value":null}]
[task 2022-06-22T11:05:42.020Z] 11:05:42     INFO -  1655895941996	Marionette	DEBUG	2 -> [0,11,"WebDriver:ExecuteScript",{"script":"const { Preferences } = ChromeUtils.import(\n                  \"resource://gre/mo ... ox":true,"sandbox":"default","line":849,"filename":"../../venv/lib/python3.6/site-packages/marionette_driver/marionette.py"}]
[task 2022-06-22T11:05:42.032Z] 11:05:42     INFO -  1655895942032	Marionette	DEBUG	2 <- [1,11,null,{"value":null}]
[task 2022-06-22T11:05:42.038Z] 11:05:42     INFO -  1655895942034	Marionette	DEBUG	2 -> [0,12,"Marionette:SetContext",{"value":"content"}]
[task 2022-06-22T11:05:42.043Z] 11:05:42     INFO -  1655895942034	Marionette	DEBUG	2 <- [1,12,null,{"value":null}]
[task 2022-06-22T11:05:42.047Z] 11:05:42     INFO -  1655895942037	Marionette	DEBUG	2 -> [0,13,"Marionette:GetContext",{}]
[task 2022-06-22T11:05:42.052Z] 11:05:42     INFO -  1655895942038	Marionette	DEBUG	2 <- [1,13,null,{"value":"content"}]
[task 2022-06-22T11:05:42.058Z] 11:05:42     INFO -  1655895942040	Marionette	DEBUG	2 -> [0,14,"Marionette:AcceptConnections",{"value":false}]
[task 2022-06-22T11:05:42.062Z] 11:05:42     INFO -  1655895942042	Marionette	INFO	Stopped listening on port 2828
[task 2022-06-22T11:05:42.067Z] 11:05:42     INFO -  1655895942043	Marionette	DEBUG	2 <- [1,14,null,{"value":null}]
[task 2022-06-22T11:05:42.072Z] 11:05:42     INFO -  1655895942071	Marionette	DEBUG	2 -> [0,15,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-06-22T11:05:42.078Z] 11:05:42     INFO -  1655895942077	Marionette	DEBUG	2 <- [1,15,null,{"value":null}]
[task 2022-06-22T11:05:42.085Z] 11:05:42     INFO -  1655895942084	Marionette	DEBUG	2 -> [0,16,"WebDriver:ExecuteScript",{"script":"const { Services } = ChromeUtils.import(\n              \"resource://gre/modules/S ... dbox":"default","line":137,"filename":"tests/testing/marionette/harness/marionette_harness/tests/unit/test_quit_restart.py"}]
[task 2022-06-22T11:05:42.090Z] 11:05:42     INFO -  [CodeCoverage] Setting handlers for process 16660.
[task 2022-06-22T11:05:42.106Z] 11:05:42     INFO -  DEBUG: Adding blocker SessionStore: flushing all windows for phase quit-application-granted
[task 2022-06-22T11:05:42.110Z] 11:05:42     INFO -  DEBUG: Starting phase quit-application-granted
[task 2022-06-22T11:05:42.122Z] 11:05:42     INFO -  DEBUG: Spinning the event loop
[task 2022-06-22T11:05:42.124Z] 11:05:42     INFO -  DEBUG: Adding blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.126Z] 11:05:42     INFO -  DEBUG: Adding blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.128Z] 11:05:42     INFO -  DEBUG: Adding blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.129Z] 11:05:42     INFO -  DEBUG: Adding blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.130Z] 11:05:42     INFO -  DEBUG: Adding blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.133Z] 11:05:42     INFO -  DEBUG: Adding blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.137Z] 11:05:42     INFO -  DEBUG: Adding blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.138Z] 11:05:42     INFO -  DEBUG: Adding blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.139Z] 11:05:42     INFO -  DEBUG: Adding blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.140Z] 11:05:42     INFO -  DEBUG: Adding blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.142Z] 11:05:42     INFO -  DEBUG: Adding blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.143Z] 11:05:42     INFO -  DEBUG: Adding blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.144Z] 11:05:42     INFO -  DEBUG: Adding blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.193Z] 11:05:42     INFO -  DEBUG: Completed blocker XPIProvider shutdown for phase quit-application-granted
[task 2022-06-22T11:05:42.252Z] 11:05:42     INFO -  DEBUG: Completed blocker SessionStore: flushing all windows for phase quit-application-granted
[task 2022-06-22T11:05:42.257Z] 11:05:42     INFO -  DEBUG: Finished phase quit-application-granted
[task 2022-06-22T11:05:42.269Z] 11:05:42     INFO -  DEBUG: Adding blocker ShieldRecipeClient: Cleaning up for phase profile-before-change
[task 2022-06-22T11:05:42.296Z] 11:05:42     INFO -  DEBUG: Completed blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.300Z] 11:05:42     INFO -  DEBUG: Completed blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.303Z] 11:05:42     INFO -  DEBUG: Completed blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.305Z] 11:05:42     INFO -  DEBUG: Completed blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.307Z] 11:05:42     INFO -  DEBUG: Completed blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.310Z] 11:05:42     INFO -  DEBUG: Completed blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.316Z] 11:05:42     INFO -  DEBUG: Completed blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.318Z] 11:05:42     INFO -  DEBUG: Completed blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.319Z] 11:05:42     INFO -  DEBUG: Completed blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.321Z] 11:05:42     INFO -  DEBUG: Completed blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.322Z] 11:05:42     INFO -  DEBUG: Completed blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.323Z] 11:05:42     INFO -  DEBUG: Completed blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.324Z] 11:05:42     INFO -  DEBUG: Completed blocker Extension shutdown: [e-mail] for phase profile-change-teardown
[task 2022-06-22T11:05:42.379Z] 11:05:42     INFO -  DEBUG: Adding blocker SessionFile: Finish writing Session Restore data for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2022-06-22T11:05:42.381Z] 11:05:42     INFO -  1655895942379	Marionette	TRACE	Received observer notification quit-application
[task 2022-06-22T11:05:42.387Z] 11:05:42     INFO -  1655895942379	Marionette	DEBUG	Marionette stopped listening
[task 2022-06-22T11:05:42.391Z] 11:05:42     INFO -  DEBUG: Completed blocker ShieldRecipeClient: Cleaning up for phase profile-before-change
[task 2022-06-22T11:05:42.394Z] 11:05:42     INFO -  1655895942393	Marionette	TRACE	[1] Querying "executeScript" failed with AbortError, returning "null" as fallback
[task 2022-06-22T11:05:42.398Z] 11:05:42     INFO -  1655895942393	Marionette	DEBUG	2 <- [1,16,null,{"value":null}]
[task 2022-06-22T11:05:42.420Z] 11:05:42     INFO -  DEBUG: Completed blocker SessionFile: Finish writing Session Restore data for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2022-06-22T11:05:42.423Z] 11:05:42     INFO -  DEBUG: Adding blocker ContentParent: id=7fd235f89600 for phase xpcom-will-shutdown
[task 2022-06-22T11:05:42.425Z] 11:05:42     INFO -  DEBUG: Adding blocker ContentParent: id=7fd235f89600 for phase profile-before-change
[task 2022-06-22T11:05:42.445Z] 11:05:42     INFO -  DEBUG: Adding blocker ContentParent: id=7fd235f8af00 for phase xpcom-will-shutdown
[task 2022-06-22T11:05:42.457Z] 11:05:42     INFO -  DEBUG: Adding blocker ContentParent: id=7fd235f8af00 for phase profile-before-change
[task 2022-06-22T11:05:42.495Z] 11:05:42     INFO -  DEBUG: Adding blocker ContentParent: id=7fd235f89b00 for phase xpcom-will-shutdown
[task 2022-06-22T11:05:42.497Z] 11:05:42     INFO -  DEBUG: Adding blocker ContentParent: id=7fd235f89b00 for phase profile-before-change
[task 2022-06-22T11:05:42.584Z] 11:05:42     INFO -  JavaScript error: chrome://remote/content/marionette/cert.js, line 57: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsICertOverrideService.setDisableAllSecurityChecksAndLetAttackersInterceptMyData]
[task 2022-06-22T11:05:42.586Z] 11:05:42     INFO -  DEBUG: Starting phase profile-change-teardown
[task 2022-06-22T11:05:42.683Z] 11:05:42     INFO -  DEBUG: Spinning the event loop
[task 2022-06-22T11:05:42.686Z] 11:05:42     INFO -  DEBUG: Completed blocker UrlbarProviderQuickSuggest: Record impression counters reset telemetry for phase profile-change-teardown
[task 2022-06-22T11:05:42.688Z] 11:05:42     INFO -  DEBUG: Completed blocker sanitize.js: Sanitize on shutdown for phase Places Clients shutdown
[task 2022-06-22T11:05:42.708Z] 11:05:42     INFO -  DEBUG: Finished phase profile-change-teardown
[task 2022-06-22T11:05:42.713Z] 11:05:42     INFO -  DEBUG: Starting phase profile-before-change
[task 2022-06-22T11:05:42.720Z] 11:05:42     INFO -  [Parent 16568, Main Thread] WARNING: ContentParent: id=7fd23deac100 - BlockShutdown: CanSend.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3607
[task 2022-06-22T11:05:42.722Z] 11:05:42     INFO -  [Parent 16568, Main Thread] WARNING: ContentParent: id=7fd23deac100 - ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1808
[task 2022-06-22T11:05:42.726Z] 11:05:42     INFO -  [Parent 16568, Main Thread] WARNING: ContentParent: id=7fd235f89600 - BlockShutdown: CanSend.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3607
[task 2022-06-22T11:05:42.727Z] 11:05:42     INFO -  [Parent 16568, Main Thread] WARNING: ContentParent: id=7fd235f89600 - ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1808
[task 2022-06-22T11:05:42.728Z] 11:05:42     INFO -  [Parent 16568, Main Thread] WARNING: ContentParent: id=7fd235f8af00 - BlockShutdown: CanSend.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3607
[task 2022-06-22T11:05:42.729Z] 11:05:42     INFO -  [Parent 16568, Main Thread] WARNING: ContentParent: id=7fd235f8af00 - ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1808
[task 2022-06-22T11:05:42.732Z] 11:05:42     INFO -  [Parent 16568, Main Thread] WARNING: ContentParent: id=7fd235f89b00 - BlockShutdown: CanSend.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3607
[task 2022-06-22T11:05:42.733Z] 11:05:42     INFO -  [Parent 16568, Main Thread] WARNING: ContentParent: id=7fd235f89b00 - ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1808
[task 2022-06-22T11:05:42.733Z] 11:05:42     INFO -  DEBUG: Spinning the event loop
[task 2022-06-22T11:05:42.734Z] 11:05:42     INFO -  DEBUG: Completed blocker EnvironmentAddonBuilder for phase AddonManager: Waiting to start provider shutdown.
[task 2022-06-22T11:05:42.735Z] 11:05:42     INFO -  DEBUG: Completed blocker PageActions: purging unregistered actions from cache for phase profile-before-change
[task 2022-06-22T11:05:42.736Z] 11:05:42     INFO -  DEBUG: Completed blocker Places Expiration: shutdown for phase Places Connection shutdown
[task 2022-06-22T11:05:42.739Z] 11:05:42     INFO -  DEBUG: Completed blocker JSON store: writing data for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2022-06-22T11:05:42.740Z] 11:05:42     INFO -  DEBUG: Completed blocker JSON store: writing data for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2022-06-22T11:05:42.741Z] 11:05:42     INFO -  DEBUG: Completed blocker JSON store: writing data for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2022-06-22T11:05:42.742Z] 11:05:42     INFO -  DEBUG: Completed blocker JSON store: writing data for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2022-06-22T11:05:42.743Z] 11:05:42     INFO -  DEBUG: Completed blocker RemoteSettingsClient - finish IDB access. for phase profile-before-change
[task 2022-06-22T11:05:42.744Z] 11:05:42     INFO -  DEBUG: Completed blocker Remote Settings profile-before-change for phase profile-before-change
[task 2022-06-22T11:05:42.745Z] 11:05:42     INFO -  DEBUG: Completed blocker DownloadAutoSaveView: writing data for phase profile-before-change
[task 2022-06-22T11:05:42.746Z] 11:05:42     INFO -  DEBUG: Completed blocker Search service: shutting down for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2022-06-22T11:05:42.747Z] 11:05:42     INFO -  DEBUG: Completed blocker places.sqlite#1: waiting for shutdown for phase Sqlite.jsm: wait until all connections are closed
[task 2022-06-22T11:05:42.748Z] 11:05:42     INFO -  DEBUG: Completed blocker OS.File: flush I/O queued before profileBeforeChange for phase profile-before-change
[task 2022-06-22T11:05:42.827Z] 11:05:42     INFO -  DEBUG: Completed blocker PlacesUtils wrapped connection closing as part of Places shutdown for phase Places Connection shutdown
[task 2022-06-22T11:05:42.830Z] 11:05:42     INFO -  DEBUG: Completed blocker PlacesUtils wrapped connection must be closed before Sqlite.jsm for phase Sqlite.jsm: wait until all clients have completed their task
[task 2022-06-22T11:05:42.833Z] 11:05:42     INFO -  DEBUG: Completed blocker GMPProvider for phase AddonManager: Waiting for providers to shut down.
[task 2022-06-22T11:05:42.834Z] 11:05:42     INFO -  DEBUG: Completed blocker XPIProvider for phase AddonManager: Waiting for providers to shut down.
[task 2022-06-22T11:05:42.836Z] 11:05:42     INFO -  DEBUG: Completed blocker AddonManager: shutting down. for phase profile-before-change
[task 2022-06-22T11:05:42.844Z] 11:05:42     INFO -  DEBUG: Completed blocker CrashMonitor: Writing notifications to file after receiving profile-before-change and awaiting all checkpoints written for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2022-06-22T11:05:42.850Z] 11:05:42     INFO -  [Parent 16568, Main Thread] WARNING: ContentParent: id=7fd23deac100 - ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1843
[task 2022-06-22T11:05:42.851Z] 11:05:42     INFO -  [Parent 16568, Main Thread] WARNING: ContentParent: id=7fd23deac100 - RemoveShutdownBlockers: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3709
[task 2022-06-22T11:05:42.874Z] 11:05:42     INFO -  DEBUG: Completed blocker places.sqlite#0: waiting for shutdown for phase Sqlite.jsm: wait until all connections are closed
[task 2022-06-22T11:05:42.876Z] 11:05:42     INFO -  DEBUG: Completed blocker PlacesUtils read-only connection closing as part of Places shutdown for phase Places Connection shutdown
[task 2022-06-22T11:05:42.877Z] 11:05:42     INFO -  DEBUG: Completed blocker PlacesUtils read-only connection must be closed before Sqlite.jsm for phase Sqlite.jsm: wait until all clients have completed their task
[task 2022-06-22T11:05:42.888Z] 11:05:42     INFO -  DEBUG: Completed blocker content-prefs.sqlite#0: waiting for shutdown for phase Sqlite.jsm: wait until all connections are closed
[task 2022-06-22T11:05:42.890Z] 11:05:42     INFO -  DEBUG: Completed blocker Closing ContentPrefService2 connection. for phase Sqlite.jsm: wait until all clients have completed their task
[task 2022-06-22T11:05:42.892Z] 11:05:42     INFO -  DEBUG: Completed blocker Sqlite.jsm shutdown blocker for phase profile-before-change
[task 2022-06-22T11:05:45.153Z] 11:05:45     INFO -  [CodeCoverage] Setting handlers for process 16707.
[task 2022-06-22T11:05:45.242Z] 11:05:45     INFO -  [Parent 16568, Main Thread] WARNING: ContentParent: id=7fd235f8af00 - ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1843
[task 2022-06-22T11:05:45.243Z] 11:05:45     INFO -  [Parent 16568, Main Thread] WARNING: ContentParent: id=7fd235f8af00 - RemoveShutdownBlockers: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3709
[task 2022-06-22T11:05:45.247Z] 11:05:45     INFO -  [CodeCoverage] Setting handlers for process 16706.
[task 2022-06-22T11:05:45.274Z] 11:05:45     INFO -  [CodeCoverage] Setting handlers for process 16708.
[task 2022-06-22T11:05:45.295Z] 11:05:45     INFO -  [Parent 16568, Main Thread] WARNING: ContentParent: id=7fd235f89600 - ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1843
[task 2022-06-22T11:05:45.295Z] 11:05:45     INFO -  [Parent 16568, Main Thread] WARNING: ContentParent: id=7fd235f89600 - RemoveShutdownBlockers: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3709
[task 2022-06-22T11:05:45.366Z] 11:05:45     INFO -  [Parent 16568, Main Thread] WARNING: ContentParent: id=7fd235f89b00 - ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1843
[task 2022-06-22T11:05:45.367Z] 11:05:45     INFO -  [Parent 16568, Main Thread] WARNING: ContentParent: id=7fd235f89b00 - RemoveShutdownBlockers: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3709
[task 2022-06-22T11:05:45.370Z] 11:05:45     INFO -  DEBUG: Finished phase profile-before-change
[task 2022-06-22T11:05:45.374Z] 11:05:45     INFO -  DEBUG: Starting phase profile-before-change-telemetry
[task 2022-06-22T11:05:45.376Z] 11:05:45     INFO -  DEBUG: Spinning the event loop
[task 2022-06-22T11:05:45.772Z] 11:05:45     INFO -  DEBUG: Adding blocker Waiting for ping task for phase TelemetryController: Waiting for pending ping activity
[task 2022-06-22T11:05:45.796Z] 11:05:45     INFO -  DEBUG: Adding blocker Waiting for ping task for phase TelemetryController: Waiting for pending ping activity
[task 2022-06-22T11:05:45.830Z] 11:05:45     INFO -  DEBUG: Completed blocker Waiting for ping task for phase TelemetryController: Waiting for pending ping activity
[task 2022-06-22T11:05:45.832Z] 11:05:45     INFO -  DEBUG: Completed blocker Waiting for ping task for phase TelemetryController: Waiting for pending ping activity
[task 2022-06-22T11:05:45.866Z] 11:05:45     INFO -  DEBUG: Completed blocker TelemetryController: shutting down for phase profile-before-change-telemetry
[task 2022-06-22T11:05:45.867Z] 11:05:45     INFO -  DEBUG: Finished phase profile-before-change-telemetry
[task 2022-06-22T11:05:45.868Z] 11:05:45     INFO -  DEBUG: Starting phase xpcom-will-shutdown
[task 2022-06-22T11:05:45.873Z] 11:05:45     INFO -  DEBUG: Spinning the event loop
[task 2022-06-22T11:05:45.876Z] 11:05:45     INFO -  DEBUG: Completed blocker OS.File: flush pending requests, warn about unclosed files, shut down service. for phase xpcom-will-shutdown
[task 2022-06-22T11:05:45.877Z] 11:05:45     INFO -  DEBUG: Finished phase xpcom-will-shutdown
[task 2022-06-22T11:05:45.885Z] 11:05:45     INFO -  DEBUG: Starting phase web-workers-shutdown
[task 2022-06-22T11:05:45.888Z] 11:05:45     INFO -  DEBUG: Spinning the event loop
[task 2022-06-22T11:05:45.889Z] 11:05:45     INFO -  DEBUG: Finished phase web-workers-shutdown
[task 2022-06-22T11:05:47.664Z] 11:05:47     INFO - TEST-UNEXPECTED-FAIL | testing/marionette/harness/marionette_harness/tests/unit/test_quit_restart.py TestQuitRestart.test_in_app_restart_with_callback_but_process_quit | AssertionError: "Process unexpectedly quit without restarting" does not match "Process has been unexpectedly closed (Exit code: 0) (Reason: No data received over socket)"
[task 2022-06-22T11:05:47.665Z] 11:05:47     INFO - Traceback (most recent call last):
[task 2022-06-22T11:05:47.665Z] 11:05:47     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_harness/marionette_test/testcases.py", line 202, in run
[task 2022-06-22T11:05:47.665Z] 11:05:47     INFO -     testMethod()
[task 2022-06-22T11:05:47.666Z] 11:05:47     INFO -   File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_quit_restart.py", line 276, in test_in_app_restart_with_callback_but_process_quit
[task 2022-06-22T11:05:47.668Z] 11:05:47     INFO -     in_app=True, callback=lambda: self.shutdown(restart=False)
[task 2022-06-22T11:05:47.668Z] 11:05:47     INFO - TEST-INFO took 5760ms
[task 2022-06-22T11:05:47.670Z] 11:05:47     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -no-remote -marionette -profile /tmp/tmpxendomq_.mozrunner
[task 2022-06-22T11:05:47.732Z] 11:05:47     INFO -  [CodeCoverage] Setting handlers for process 16764.
[task 2022-06-22T11:05:48.344Z] 11:05:48     INFO -  DEBUG: Adding blocker PermissionManager: Flushing data for phase xpcom-will-shutdown
[task 2022-06-22T11:05:49.402Z] 11:05:49     INFO -  DEBUG: Adding blocker AddonManager: shutting down. for phase profile-before-change
[task 2022-06-22T11:05:49.427Z] 11:05:49     INFO -  DEBUG: Adding blocker IOUtils Blocker (profile-before-change) for phase profile-before-change
[task 2022-06-22T11:05:49.428Z] 11:05:49     INFO -  DEBUG: Adding blocker IOUtils Blocker (xpcom-will-shutdown) for phase xpcom-will-shutdown
[task 2022-06-22T11:05:49.440Z] 11:05:49     INFO -  DEBUG: Adding blocker XPIProvider shutdown for phase quit-application-granted
[task 2022-06-22T11:05:49.442Z] 11:05:49     INFO -  DEBUG: Adding blocker XPIProvider for phase AddonManager: Waiting for providers to shut down.
[task 2022-06-22T11:05:49.443Z] 11:05:49     INFO -  DEBUG: Adding blocker ServiceWorkerRegistrar: Flushing data for phase profile-before-change
[task 2022-06-22T11:05:49.445Z] 11:05:49     INFO -  DEBUG: Adding blocker CrashMonitor: Writing notifications to file after receiving profile-before-change and awaiting all checkpoints written for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2022-06-22T11:05:49.456Z] 11:05:49     INFO -  DEBUG: Adding blocker TelemetryController: shutting down for phase profile-before-change-telemetry
[task 2022-06-22T11:05:49.468Z] 11:05:49     INFO -  DEBUG: Adding blocker EnvironmentAddonBuilder for phase AddonManager: Waiting to start provider shutdown.
[task 2022-06-22T11:05:49.472Z] 11:05:49     INFO -  DEBUG: Adding blocker RemoteSettingsClient - finish IDB access. for phase profile-before-change
[task 2022-06-22T11:05:49.477Z] 11:05:49     INFO -  1655895949476	Marionette	INFO	Marionette enabled
[task 2022-06-22T11:05:49.479Z] 11:05:49     INFO -  1655895949478	Marionette	TRACE	Received observer notification final-ui-startup
[task 2022-06-22T11:05:49.480Z] 11:05:49     INFO -  1655895949480	Marionette	INFO	Listening on port 2828
[task 2022-06-22T11:05:49.481Z] 11:05:49     INFO -  1655895949480	Marionette	DEBUG	Marionette is listening
[task 2022-06-22T11:05:49.592Z] 11:05:49     INFO -  DEBUG: Adding blocker ClientManagerService: start destroying IPC actors early for phase xpcom-will-shutdown
[task 2022-06-22T11:05:49.607Z] 11:05:49     INFO -  1655895949606	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:40728
[task 2022-06-22T11:05:49.630Z] 11:05:49     INFO -  1655895949630	Marionette	DEBUG	Closed connection 0
[task 2022-06-22T11:05:49.632Z] 11:05:49     INFO -  1655895949630	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:40730
[task 2022-06-22T11:05:49.685Z] 11:05:49     INFO -  DEBUG: Adding blocker JSON store: writing data for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2022-06-22T11:05:49.735Z] 11:05:49     INFO -  DEBUG: Adding blocker MediaShutdownManager: shutdown for phase profile-before-change
[task 2022-06-22T11:05:49.765Z] 11:05:49     INFO -  DEBUG: Adding blocker JSON store: writing data for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2022-06-22T11:05:49.800Z] 11:05:49     INFO -  1655895949799	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-06-22T11:05:49.803Z] 11:05:49     INFO -  1655895949802	Marionette	DEBUG	Waiting for initial application window
[task 2022-06-22T11:05:49.985Z] 11:05:49     INFO -  DEBUG: Adding blocker ContentParent: id=7f99885a5400 for phase xpcom-will-shutdown
[task 2022-06-22T11:05:49.987Z] 11:05:49     INFO -  DEBUG: Adding blocker ContentParent: id=7f99885a5400 for phase profile-before-change
[task 2022-06-22T11:05:50.024Z] 11:05:50     INFO -  DEBUG: Adding blocker GMPProvider for phase AddonManager: Waiting for providers to shut down.
[task 2022-06-22T11:05:50.084Z] 11:05:50     INFO -  DEBUG: Adding blocker UrlbarProviderQuickSuggest: Record impression counters reset telemetry for phase profile-change-teardown
[task 2022-06-22T11:05:50.107Z] 11:05:50     INFO -  DEBUG: Adding blocker ServiceWorkerShutdownBlocker: shutting down Service Workers for phase profile-change-teardown
[task 2022-06-22T11:05:51.008Z] 11:05:51     INFO -  [CodeCoverage] Setting handlers for process 16826.
[task 2022-06-22T11:05:51.797Z] 11:05:51     INFO -  DEBUG: Adding blocker PageActions: purging unregistered actions from cache for phase profile-before-change
[task 2022-06-22T11:05:51.805Z] 11:05:51     INFO -  DEBUG: Adding blocker Places Clients shutdown for phase profile-change-teardown
[task 2022-06-22T11:05:51.805Z] 11:05:51     INFO -  DEBUG: Adding blocker Places Connection shutdown for phase profile-before-change
[task 2022-06-22T11:05:51.843Z] 11:05:51     INFO -  DEBUG: Adding blocker sanitize.js: Sanitize on shutdown for phase Places Clients shutdown
[task 2022-06-22T11:05:51.923Z] 11:05:51     INFO -  DEBUG: Adding blocker Remote Settings profile-before-change for phase profile-before-change
[task 2022-06-22T11:05:51.937Z] 11:05:51     INFO -  DEBUG: Adding blocker ContentParent: id=7f9980789b00 for phase xpcom-will-shutdown
[task 2022-06-22T11:05:51.938Z] 11:05:51     INFO -  DEBUG: Adding blocker ContentParent: id=7f9980789b00 for phase profile-before-change
[task 2022-06-22T11:05:52.078Z] 11:05:52     INFO -  DEBUG: Adding blocker Sqlite.jsm shutdown blocker for phase profile-before-change
[task 2022-06-22T11:05:52.079Z] 11:05:52     INFO -  DEBUG: Adding blocker content-prefs.sqlite#0: waiting for shutdown for phase Sqlite.jsm: wait until all connections are closed
[task 2022-06-22T11:05:52.081Z] 11:05:52     INFO -  DEBUG: Adding blocker Closing ContentPrefService2 connection. for phase Sqlite.jsm: wait until all clients have completed their task
[task 2022-06-22T11:05:52.200Z] 11:05:52     INFO -  DEBUG: Adding blocker Transaction (0) for phase content-prefs.sqlite#0: waiting for clients
[task 2022-06-22T11:05:52.306Z] 11:05:52     INFO -  DEBUG: Adding blocker ContentParent: id=7f9982ff8c00 for phase xpcom-will-shutdown
[task 2022-06-22T11:05:52.313Z] 11:05:52     INFO -  DEBUG: Adding blocker ContentParent: id=7f9982ff8c00 for phase profile-before-change
[task 2022-06-22T11:05:52.490Z] 11:05:52     INFO -  DEBUG: Completed blocker Transaction (0) for phase content-prefs.sqlite#0: waiting for clients
[task 2022-06-22T11:05:52.512Z] 11:05:52     INFO -  DEBUG: Adding blocker Search service: shutting down for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2022-06-22T11:05:52.559Z] 11:05:52     INFO -  DEBUG: Adding blocker places.sqlite#1: waiting for shutdown for phase Sqlite.jsm: wait until all connections are closed
[task 2022-06-22T11:05:52.560Z] 11:05:52     INFO -  DEBUG: Adding blocker Places Expiration: shutdown for phase Places Connection shutdown
[task 2022-06-22T11:05:52.578Z] 11:05:52     INFO -  DEBUG: Adding blocker PlacesUtils wrapped connection closing as part of Places shutdown for phase Places Connection shutdown
[task 2022-06-22T11:05:52.580Z] 11:05:52     INFO -  DEBUG: Adding blocker PlacesUtils wrapped connection must be closed before Sqlite.jsm for phase Sqlite.jsm: wait until all clients have completed their task
[task 2022-06-22T11:05:52.589Z] 11:05:52     INFO -  DEBUG: Adding blocker places.sqlite#1: PlacesExpiration.jsm: setup (0) for phase places.sqlite#1: waiting for clients
[task 2022-06-22T11:05:52.594Z] 11:05:52     INFO -  DEBUG: Adding blocker places.sqlite#0: waiting for shutdown for phase Sqlite.jsm: wait until all connections are closed
[task 2022-06-22T11:05:52.595Z] 11:05:52     INFO -  DEBUG: Adding blocker PlacesUtils read-only connection closing as part of Places shutdown for phase Places Connection shutdown
[task 2022-06-22T11:05:52.595Z] 11:05:52     INFO -  DEBUG: Adding blocker PlacesUtils read-only connection must be closed before Sqlite.jsm for phase Sqlite.jsm: wait until all clients have completed their task
[task 2022-06-22T11:05:52.597Z] 11:05:52     INFO -  DEBUG: Completed blocker places.sqlite#1: PlacesExpiration.jsm: setup (0) for phase places.sqlite#1: waiting for clients
[task 2022-06-22T11:05:52.730Z] 11:05:52     INFO -  DEBUG: Adding blocker JSON store: writing data for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2022-06-22T11:05:52.776Z] 11:05:52     INFO -  DEBUG: Adding blocker JSON store: writing data for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2022-06-22T11:05:53.055Z] 11:05:53     INFO -  DEBUG: Adding blocker OS.File: flush pending requests, warn about unclosed files, shut down service. for phase xpcom-will-shutdown
[task 2022-06-22T11:05:53.066Z] 11:05:53     INFO -  DEBUG: Adding blocker OS.File: flush I/O queued before profileBeforeChange for phase profile-before-change
[task 2022-06-22T11:05:53.155Z] 11:05:53     INFO -  DEBUG: Adding blocker DownloadAutoSaveView: writing data for phase profile-before-change
[task 2022-06-22T11:05:53.180Z] 11:05:53     INFO -  1655895953180	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2022-06-22T11:05:53.181Z] 11:05:53     INFO -  DEBUG: Adding blocker ScriptPreloader: Saving bytecode cache for phase xpcom-will-shutdown
[task 2022-06-22T11:05:53.189Z] 11:05:53     INFO -  1655895953189	RemoteAgent	TRACE	[30] ProgressListener state=start: about:blank
[task 2022-06-22T11:05:53.566Z] 11:05:53     INFO -  [CodeCoverage] Setting handlers for process 16852.
[task 2022-06-22T11:05:53.738Z] 11:05:53     INFO -  1655895953738	RemoteAgent	TRACE	[30] ProgressListener state=stop: about:blank
[task 2022-06-22T11:05:53.762Z] 11:05:53     INFO -  1655895953761	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"869f09a7-eddc-4802-a4bd-4e68c381234f","capabilities":{"browserName":"firefox","browserVersion":"103.0 ... ownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2022-06-22T11:05:53.794Z] 11:05:53     INFO -  1655895953794	Marionette	DEBUG	1 -> [0,2,"Marionette:GetContext",{}]
[task 2022-06-22T11:05:53.796Z] 11:05:53     INFO -  1655895953794	Marionette	DEBUG	1 <- [1,2,null,{"value":"content"}]
[task 2022-06-22T11:05:53.797Z] 11:05:53     INFO -  1655895953797	Marionette	DEBUG	1 -> [0,3,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-06-22T11:05:53.798Z] 11:05:53     INFO -  1655895953798	Marionette	DEBUG	1 <- [1,3,null,{"value":null}]
[task 2022-06-22T11:05:53.802Z] 11:05:53     INFO -  1655895953801	Marionette	DEBUG	1 -> [0,4,"WebDriver:ExecuteScript",{"script":"const { Preferences } = ChromeUtils.import(\n                 \"resource://gre/modu ... ox":true,"sandbox":"default","line":777,"filename":"../../venv/lib/python3.6/site-packages/marionette_driver/marionette.py"}]
[task 2022-06-22T11:05:53.806Z] 11:05:53     INFO -  1655895953806	Marionette	TRACE	[1] MarionetteCommands actor created for window id 2
[task 2022-06-22T11:05:53.818Z] 11:05:53     INFO -  1655895953817	Marionette	DEBUG	1 <- [1,4,null,{"value":null}]
[task 2022-06-22T11:05:53.820Z] 11:05:53     INFO -  1655895953820	Marionette	DEBUG	1 -> [0,5,"Marionette:SetContext",{"value":"content"}]
[task 2022-06-22T11:05:53.821Z] 11:05:53     INFO -  1655895953820	Marionette	DEBUG	1 <- [1,5,null,{"value":null}]
[task 2022-06-22T11:05:53.823Z] 11:05:53     INFO -  1655895953823	Marionette	DEBUG	1 -> [0,6,"WebDriver:DeleteSession",{}]
[task 2022-06-22T11:05:53.831Z] 11:05:53     INFO -  1655895953826	Marionette	DEBUG	1 <- [1,6,null,{"value":null}]
[task 2022-06-22T11:05:53.835Z] 11:05:53     INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_quit_restart.py TestQuitRestart.test_in_app_restart_with_callback_missing_shutdown

Comment 1

[Henrik Skupin [:whimboo][⌚️UTC+2]]

I wonder why we don't hit this code path:
https://searchfox.org/mozilla-central/rev/44d99b6b8d84abed2d20386cc2e62cd90da41cc3/testing/marionette/client/marionette_driver/marionette.py#1137-1161

Instead we fallback to the do_process_check decorator of the next Marionette command.

Note that this is a CCOV builds only failure. Marco, has something changed recently?

Comment 2

[Intermittent Failures Robot]

19 failures in 684 pushes (0.028 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 19

Platform and build breakdown:

• linux1804-64-ccov-qr: 19
  • opt: 19

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1775461&startday=2022-06-22&endday=2022-06-22&tree=all

Comment 3

[Henrik Skupin [:whimboo][⌚️UTC+2]]

As it looks like some commit in this autoland to mozilla-central merge should have caused it.

https://treeherder.mozilla.org/jobs?repo=mozilla-central&searchStr=ccov%2Cmn&tochange=7982881720ffb2ed2397e39f0e563feb7c883dd8&fromchange=fa042bbf4c143ee21fb05b382706cf3a2219a4e2

Comment 4

[Marco Castelluccio [:marco]]

Nothing specific to ccov has been changed recently. Given the range, I imagine bug 1774977 could be the culprit. Moving from AWS to GCP might have affected the runtime characteristics.

Comment 5

[Henrik Skupin [:whimboo][⌚️UTC+2]]

Ah, this indeed could explain it. Interesting that we haven't seen any other failures for CCOV today. Maybe some other tweaks improved it? Michelle do you have an idea?

https://treeherder.mozilla.org/jobs?repo=mozilla-central&searchStr=ccov%2Cmn&fromchange=7982881720ffb2ed2397e39f0e563feb7c883dd8&tochange=c05cfc52283cce26816b49934cd3890e4b61b0b5

Comment 6

[Michelle Goossens [:masterwayz]]

Could very well be me, but no clue what exactly could cause it, it's a different cloud but it should have the same resources per worker.

Comment 7

[Henrik Skupin [:whimboo][⌚️UTC+2]]

Good to hear. So I would propose to leave the failure for now and check daily if more failures are classified. If not we could close the bug next week.

Comment 8

[Intermittent Failures Robot]

22 failures in 3075 pushes (0.007 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 22

Platform and build breakdown:

• linux1804-64-ccov-qr: 22
  • opt: 22

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1775461&startday=2022-06-20&endday=2022-06-26&tree=all

Comment 9

[Henrik Skupin [:whimboo][⌚️UTC+2]]

There haven't been more failing tests and those formerly failing oness are passing now:
https://treeherder.mozilla.org/jobs?repo=mozilla-central&searchStr=ccov%2Cmn&fromchange=d26536dbf462dd37726927090126ae96eebed500&tochange=f6c16b5b69425e0149db5741577fd0295c3ca0fe

I think that we can close the bug as WFM. Lets reopen if it starts happening again.

Comment 10

[Noemi Erli[:noemi_erli]]

3 out of 4 failures on the latest merge: https://treeherder.mozilla.org/jobs?repo=mozilla-central&selectedTaskRun=TuyJKsStQ5mesdqbNVGvbQ.0&resultStatus=testfailed%2Cbusted%2Cexception%2Cretry%2Cusercancel&searchStr=ccov%2Cmn&revision=401c2d7e80fba6483348d1d0514c768f7180e734

Comment 11

[Intermittent Failures Robot]

6 failures in 3312 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 6

Platform and build breakdown:

• linux1804-64-ccov-qr: 6
  • opt: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1775461&startday=2022-06-27&endday=2022-07-03&tree=all

Comment 12

[Intermittent Failures Robot]

17 failures in 3072 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 17

Platform and build breakdown:

• linux1804-64-ccov-qr: 17
  • opt: 17

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1775461&startday=2022-07-04&endday=2022-07-10&tree=all

Comment 13

[Henrik Skupin [:whimboo][⌚️UTC+2]]

As just noticed the actual callback to shutdown Firefox gets aborted:

[task 2022-07-09T10:58:08.243Z] 10:58:08     INFO -  1657364288242	Marionette	TRACE	[1] Querying "executeScript" failed with AbortError, returning "null" as fallback
[task 2022-07-09T10:58:08.243Z] 10:58:08     INFO -  1657364288242	Marionette	DEBUG	2 <- [1,16,null,{"value":null}]

Maybe that's the problem here, but that required bug 1761634 to get fixed.

Comment 14

[Intermittent Failures Robot]

8 failures in 3341 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 8

Platform and build breakdown:

• linux1804-64-ccov-qr: 8
  • opt: 8

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1775461&startday=2022-07-11&endday=2022-07-17&tree=all

Comment 15

[Intermittent Failures Robot]

7 failures in 3497 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 7

Platform and build breakdown:

• linux1804-64-ccov-qr: 7
  • opt: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1775461&startday=2022-07-18&endday=2022-07-24&tree=all

Comment 16

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 17

[BMO Automation]

Moving bug to Testing::Marionette Client and Harness component per bug 1815831.

Comment 19

[BugBot [:suhaib / :marco/ :calixte]]

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