Closed Bug 2021526 Opened 4 months ago Closed 5 days ago

Perma browser/components/sidebar/tests/marionette/test_default_launcher_visible.py | AssertionError: False is not true : Sidebar button should be visible

Categories

(Firefox :: Sidebar, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Assigned: sfoster)

References

Details

(Keywords: intermittent-failure, intermittent-testcase, Whiteboard: [collect_confirm_failure][fidefe-sidebar])

Attachments

(2 files)

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


[task 2026-03-06T06:10:16.353+00:00] 06:10:16     INFO - TEST-START | browser/components/sidebar/tests/marionette/test_default_launcher_visible.py TestDefaultLauncherVisible.test_first_use_default_visible_pref_false
[task 2026-03-06T06:10:17.716+00:00] 06:10:17     INFO - Application command: /opt/worker/tasks/task_177277687192879/build/application/Firefox NightlyDebug.app/Contents/MacOS/firefox -marionette -remote-allow-system-access -foreground -profile /var/folders/mz/yz6g78bx1jlb3snvfzs98czh0000jb/T/tmp4gqk4dfo.mozrunner
[task 2026-03-06T06:10:18.088+00:00] 06:10:18     INFO -  [Parent 2542, Main Thread] ###!!! ASSERTION: /var/folders/mz/yz6g78bx1jlb3snvfzs98czh0000jb/T/tmp4gqk4dfo.mozrunner/prefs.js:187: prefs parse error: unknown keyword: 'Error', file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:2131
[task 2026-03-06T06:10:18.337+00:00] 06:10:18     INFO -  1772777418337	Marionette	INFO	Marionette enabled
[task 2026-03-06T06:10:18.515+00:00] 06:10:18     INFO -  2026-03-06 06:10:18.514 Firefox Nightly GPU Helper[2545:39635] Failure on line 686 in function id scheduleApplicationNotification(LSNotificationCode, NSWorkspaceNotificationCenter *): noErr == _LSModifyNotification(notificationID, 1, &code, 0, NULL, NULL, NULL)
[task 2026-03-06T06:10:18.528+00:00] 06:10:18     INFO -  1772777418527	Marionette	TRACE	Received observer notification final-ui-startup
[task 2026-03-06T06:10:18.618+00:00] 06:10:18     INFO -  2026-03-06 06:10:18.617 Firefox Nightly GPU Helper[2545:39635] Error received in message reply handler: Connection invalid
[task 2026-03-06T06:10:18.618+00:00] 06:10:18     INFO -  2026-03-06 06:10:18.617 Firefox Nightly GPU Helper[2545:39655] Connection Invalid error for service com.apple.hiservices-xpcservice.
[task 2026-03-06T06:10:18.625+00:00] 06:10:18     INFO -  1772777418624	Marionette	INFO	Listening on port 2828
[task 2026-03-06T06:10:18.625+00:00] 06:10:18     INFO -  1772777418625	Marionette	DEBUG	Marionette is listening
[task 2026-03-06T06:10:18.875+00:00] 06:10:18     INFO -  1772777418875	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:50251
[task 2026-03-06T06:10:18.913+00:00] 06:10:18     INFO -  GL_VENDOR: Intel Inc.
[task 2026-03-06T06:10:18.913+00:00] 06:10:18     INFO -  mVendor: Intel
[task 2026-03-06T06:10:18.913+00:00] 06:10:18     INFO -  GL_RENDERER: Intel(R) UHD Graphics 630
[task 2026-03-06T06:10:18.913+00:00] 06:10:18     INFO -  mRenderer: Unknown
[task 2026-03-06T06:10:18.913+00:00] 06:10:18     INFO -  mIsMesa: 0
[task 2026-03-06T06:10:18.969+00:00] 06:10:18     INFO -  1772777418968	Marionette	DEBUG	Closed connection 0
[task 2026-03-06T06:10:18.969+00:00] 06:10:18     INFO -  1772777418969	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:50252
[task 2026-03-06T06:10:18.975+00:00] 06:10:18     INFO -  [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2026-03-06T06:10:19.587+00:00] 06:10:19     INFO -  1772777419586	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2026-03-06T06:10:19.598+00:00] 06:10:19     INFO -  1772777419598	Marionette	DEBUG	Waiting for initial application window
[task 2026-03-06T06:10:19.605+00:00] 06:10:19     INFO -  [Parent 2542, Main Thread] WARNING: '!anonymousDivElement', file /builds/worker/checkouts/gecko/dom/html/TextControlState.cpp:1588
[task 2026-03-06T06:10:19.606+00:00] 06:10:19     INFO -  [Parent 2542, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(PrepareEditor()) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/html/TextControlState.cpp:1826
[task 2026-03-06T06:10:20.383+00:00] 06:10:20     INFO -  [WARN  webrender::device::gl] Missing optimized shader source for cs_linear_gradient_DITHERING
[task 2026-03-06T06:10:20.549+00:00] 06:10:20     INFO -  console.debug: SearchService: "init"
[task 2026-03-06T06:10:20.704+00:00] 06:10:20     INFO -  [Parent 2542, Main Thread] WARNING: NS_ENSURE_TRUE(she && she->mInfo->mSharedState.Get()) failed: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:1221
[task 2026-03-06T06:10:20.770+00:00] 06:10:20     INFO -  console.debug: SearchEngineSelector: "fetchEngineConfiguration en-US:unknown:default:::Firefox:150.0a1"
[task 2026-03-06T06:10:20.778+00:00] 06:10:20     INFO -  console.debug: SearchEngineSelector: "fetchEngineConfiguration: google,bing,ddg,perplexity,wikipedia"
[task 2026-03-06T06:10:20.778+00:00] 06:10:20     INFO -  console.debug: SearchService: "#loadEngines: start"
[task 2026-03-06T06:10:20.778+00:00] 06:10:20     INFO -  console.debug: SearchService: "#loadEnginesFromConfig"
[task 2026-03-06T06:10:20.784+00:00] 06:10:20     INFO -  console.debug: SearchService: "#addEngineToStore: Adding engine:" "Google"
[task 2026-03-06T06:10:20.786+00:00] 06:10:20     INFO -  console.debug: SearchService: "#addEngineToStore: Adding engine:" "Bing"
[task 2026-03-06T06:10:20.788+00:00] 06:10:20     INFO -  console.debug: SearchService: "#addEngineToStore: Adding engine:" "DuckDuckGo"
[task 2026-03-06T06:10:20.789+00:00] 06:10:20     INFO -  console.debug: SearchService: "#addEngineToStore: Adding engine:" "Perplexity"
[task 2026-03-06T06:10:20.789+00:00] 06:10:20     INFO -  console.debug: SearchService: "#addEngineToStore: Adding engine:" "Wikipedia (en)"
[task 2026-03-06T06:10:20.790+00:00] 06:10:20     INFO -  console.debug: SearchService: "#loadStartupEngines: loading" 0 "engines reported by AddonManager startup"
[task 2026-03-06T06:10:20.790+00:00] 06:10:20     INFO -  console.debug: SearchService: "#loadEnginesFromSettings: Loading" 5 "engines from settings"
[task 2026-03-06T06:10:20.790+00:00] 06:10:20     INFO -  console.debug: SearchService: "#loadEnginesFromSettings: skipped" 5 "built-in/policy engines."
[task 2026-03-06T06:10:20.790+00:00] 06:10:20     INFO -  console.debug: SearchService: "#loadEngines: done"
[task 2026-03-06T06:10:20.792+00:00] 06:10:20     INFO -  console.debug: SearchService: "Completed #init"
[task 2026-03-06T06:10:21.033+00:00] 06:10:21     INFO -  console.debug: SearchService: "#handleIgnoreListUpdated"
[task 2026-03-06T06:10:21.068+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "Initialize SERP categorizer."
[task 2026-03-06T06:10:21.068+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "Initializing domain-to-categories map."
[task 2026-03-06T06:10:21.068+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "Setting up domain-to-categories map."
[task 2026-03-06T06:10:21.068+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "Initializing domain-to-categories store."
[task 2026-03-06T06:10:21.071+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "Create store."
[task 2026-03-06T06:10:21.077+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "Initialized domain-to-categories store."
[task 2026-03-06T06:10:21.082+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "No records found for domain-to-categories map."
[task 2026-03-06T06:10:21.083+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "Initialized domain-to-categories map."
[task 2026-03-06T06:10:21.083+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "Initializing categorization event scheduler."
[task 2026-03-06T06:10:21.266+00:00] 06:10:21     INFO -  console.debug: SearchService: "getEngines: getting all engines"
[task 2026-03-06T06:10:21.266+00:00] 06:10:21     INFO -  console.debug: SearchService: "#buildSortedEngineList: using default orders"
[task 2026-03-06T06:10:21.427+00:00] 06:10:21     INFO -  [WARN  glean_core::upload] Recoverable upload failure while attempting to send ping efeff06f-2a2c-431b-b943-da1c4ab0df45, will retry. Error was RecoverableFailure { unused: 0 }
[task 2026-03-06T06:10:21.455+00:00] 06:10:21     INFO -  [WARN  glean_core::upload] Recoverable upload failure while attempting to send ping b5aac99e-1044-4285-891b-a49fd84e5fb8, will retry. Error was RecoverableFailure { unused: 0 }
[task 2026-03-06T06:10:21.470+00:00] 06:10:21     INFO -  [WARN  glean_core::upload] Recoverable upload failure while attempting to send ping a7f5346c-d076-4b2c-9cf4-01740eb7a079, will retry. Error was RecoverableFailure { unused: 0 }
[task 2026-03-06T06:10:21.470+00:00] 06:10:21     INFO -  [WARN  glean_core::upload] Reached maximum recoverable failures for the current uploading window. You are done.
[task 2026-03-06T06:10:21.557+00:00] 06:10:21     INFO -  [Child 2549, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/modules/libjar/nsJARChannel.cpp:379
[task 2026-03-06T06:10:21.615+00:00] 06:10:21     INFO -  [WARN  glean_core::upload] Recoverable upload failure while attempting to send ping ffdde37e-12b0-4edc-b49a-39fe33660f1e, will retry. Error was RecoverableFailure { unused: 0 }
[task 2026-03-06T06:10:21.619+00:00] 06:10:21     INFO -  [WARN  glean_core::upload] Recoverable upload failure while attempting to send ping 942bf50a-1322-48be-835a-288ad18e1e8a, will retry. Error was RecoverableFailure { unused: 0 }
[task 2026-03-06T06:10:21.622+00:00] 06:10:21     INFO -  [WARN  glean_core::upload] Recoverable upload failure while attempting to send ping fc286586-b9cd-4a0c-b348-c3e4f2618291, will retry. Error was RecoverableFailure { unused: 0 }
[task 2026-03-06T06:10:21.622+00:00] 06:10:21     INFO -  [WARN  glean_core::upload] Reached maximum recoverable failures for the current uploading window. You are done.
[task 2026-03-06T06:10:21.777+00:00] 06:10:21     INFO -  1772777421777	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2026-03-06T06:10:21.780+00:00] 06:10:21     INFO -  1772777421780	RemoteAgent	TRACE	[4] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=40000 waitForExplicitStart=false
[task 2026-03-06T06:10:21.780+00:00] 06:10:21     INFO -  1772777421780	RemoteAgent	TRACE	[4] ProgressListener Setting unload timer (40000ms)
[task 2026-03-06T06:10:21.781+00:00] 06:10:21     INFO -  1772777421781	RemoteAgent	TRACE	[4] Wait for initial navigation: isUncommittedInitial=false, isLoadingDocument=false
[task 2026-03-06T06:10:21.781+00:00] 06:10:21     INFO -  1772777421781	RemoteAgent	TRACE	[4] Document already finished loading: about:blank
[task 2026-03-06T06:10:21.781+00:00] 06:10:21     INFO -  1772777421781	RemoteAgent	TRACE	[4] ProgressListener Stop: has error=false url=about:blank
[task 2026-03-06T06:10:21.798+00:00] 06:10:21     INFO -  1772777421797	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"b671fdd9-1809-4cf1-bbd1-89d4482ed844","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"150.0a1","platformName":"mac","setWindowRect":true,"unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:150.0) Gecko/20100101 Firefox/150.0","moz:buildID":"20260306044542","moz:headless":false,"moz:platformVersion":"23.6.0","moz:processID":2542,"moz:profile":"/var/folders/mz/yz6g78bx1jlb3snvfzs98czh0000jb/T/tmp4gqk4dfo.mozrunner","moz:shutdownTimeout":60000,"pageLoadStrategy":"normal","timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":true,"moz:accessibilityChecks":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2026-03-06T06:10:21.802+00:00] 06:10:21     INFO -  console.debug: SearchSettings: "batchTask: Invalidating engine settings"
[task 2026-03-06T06:10:21.803+00:00] 06:10:21     INFO -  console.debug: SearchSettings: "_write: Settings unchanged. Did not write to disk."
[task 2026-03-06T06:10:21.804+00:00] 06:10:21     INFO -  1772777421804	Marionette	DEBUG	1 -> [0,2,"Marionette:SetContext",{"value":"chrome"}]
[task 2026-03-06T06:10:21.805+00:00] 06:10:21     INFO -  1772777421804	Marionette	DEBUG	1 <- [1,2,null,{"value":null}]
[task 2026-03-06T06:10:21.806+00:00] 06:10:21     INFO -  1772777421805	Marionette	DEBUG	1 -> [0,3,"Marionette:SetContext",{"value":"chrome"}]
[task 2026-03-06T06:10:21.806+00:00] 06:10:21     INFO -  1772777421806	Marionette	DEBUG	1 <- [1,3,null,{"value":null}]
[task 2026-03-06T06:10:21.808+00:00] 06:10:21     INFO -  1772777421808	Marionette	DEBUG	1 -> [0,4,"WebDriver:ExecuteAsyncScript",{"script":"let resolve = arguments[0];\n            let { BrowserInitState } = ChromeUtils.importESModule(\"resource:///modules/BrowserGlu ... WindowTracker.getTopWindow();\n                await win.SidebarController.promiseInitialized;\n            })().then(resolve);","args":[],"newSandbox":true,"sandbox":"default","scriptTimeout":null,"line":87,"filename":"tests/browser/components/sidebar/tests/marionette/test_default_launcher_visible.py"}]
[task 2026-03-06T06:10:21.812+00:00] 06:10:21     INFO -  1772777421811	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 2542
[task 2026-03-06T06:10:21.813+00:00] 06:10:21     INFO -  1772777421812	Marionette	TRACE	[2] MarionetteCommands actor created for window id 4
[task 2026-03-06T06:10:21.819+00:00] 06:10:21     INFO -  1772777421819	Marionette	DEBUG	1 <- [1,4,null,{"value":null}]
[task 2026-03-06T06:10:21.821+00:00] 06:10:21     INFO -  1772777421821	Marionette	DEBUG	1 -> [0,5,"WebDriver:ExecuteScript",{"script":"const window = BrowserWindowTracker.getTopWindow();\n            return window.SidebarController.sidebarContainer.hidden;","args":[],"newSandbox":true,"sandbox":"default","line":54,"filename":"tests/browser/components/sidebar/tests/marionette/test_default_launcher_visible.py"}]
[task 2026-03-06T06:10:21.825+00:00] 06:10:21     INFO -  1772777421825	Marionette	DEBUG	1 <- [1,5,null,{"value":true}]
[task 2026-03-06T06:10:21.827+00:00] 06:10:21     INFO -  1772777421826	Marionette	DEBUG	1 -> [0,6,"WebDriver:ExecuteScript",{"script":"const window = BrowserWindowTracker.getTopWindow();\n            const placement = window.CustomizableUI.getPlacementOfWidget( ...         }\n            const node = window.document.getElementById(\"sidebar-button\");\n            return node && !node.hidden;","args":[],"newSandbox":true,"sandbox":"default","line":63,"filename":"tests/browser/components/sidebar/tests/marionette/test_default_launcher_visible.py"}]
[task 2026-03-06T06:10:21.830+00:00] 06:10:21     INFO -  1772777421830	Marionette	DEBUG	1 <- [1,6,null,{"value":false}]
[task 2026-03-06T06:10:21.831+00:00] 06:10:21     INFO -  1772777421831	Marionette	DEBUG	1 -> [0,7,"Marionette:AcceptConnections",{"value":false}]
[task 2026-03-06T06:10:21.831+00:00] 06:10:21     INFO -  1772777421831	Marionette	INFO	Stopped listening on port 2828
[task 2026-03-06T06:10:21.831+00:00] 06:10:21     INFO -  1772777421831	Marionette	DEBUG	1 <- [1,7,null,{"value":null}]
[task 2026-03-06T06:10:21.832+00:00] 06:10:21     INFO -  1772777421832	Marionette	DEBUG	1 -> [0,8,"Marionette:Quit",{}]
[task 2026-03-06T06:10:21.840+00:00] 06:10:21     INFO -  1772777421840	Marionette	TRACE	[2] MarionetteCommands actor destroyed for window id 4
[task 2026-03-06T06:10:21.847+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "Uninit SERP categorizer."
[task 2026-03-06T06:10:21.847+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "Un-initializing domain-to-categories map."
[task 2026-03-06T06:10:21.847+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "Removing Remote Settings client."
[task 2026-03-06T06:10:21.848+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "Un-initializing domain-to-categories store."
[task 2026-03-06T06:10:21.848+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "Closing connection."
[task 2026-03-06T06:10:21.859+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "Un-initialized domain-to-categories store."
[task 2026-03-06T06:10:21.859+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "Un-initialized domain-to-categories map."
[task 2026-03-06T06:10:21.859+00:00] 06:10:21     INFO -  console.debug: SearchTelemetry: "Un-initializing categorization event scheduler."
[task 2026-03-06T06:10:21.886+00:00] 06:10:21     INFO -  1772777421885	Marionette	TRACE	Received observer notification quit-application
[task 2026-03-06T06:10:21.886+00:00] 06:10:21     INFO -  1772777421885	Marionette	TRACE	Application is shutting down with reason: "shutdown"
[task 2026-03-06T06:10:21.890+00:00] 06:10:21     INFO -  1772777421890	Marionette	DEBUG	1 <- [1,8,null,{"cause":"shutdown","forced":false,"in_app":true}]
[task 2026-03-06T06:10:21.917+00:00] 06:10:21     INFO -  1772777421917	Marionette	DEBUG	Marionette stopped listening
[task 2026-03-06T06:10:21.940+00:00] 06:10:21     INFO -  1772777421939	Marionette	DEBUG	Closed connection 1
[task 2026-03-06T06:10:22.278+00:00] 06:10:22     INFO -  [Parent 2542, Main Thread] WARNING: BlockShutdown: Init failed: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/toolkit/components/antitracking/bouncetrackingprotection/BounceTrackingProtectionStorage.cpp:538
[task 2026-03-06T06:10:22.304+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: quota manager shutdown step: '0.000003s: startCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.304+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: quota manager shutdown step: '0.000028s: initiateShutdownWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.304+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: idb: '0.000034s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.305+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: cache: '0.000050s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.305+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: sdb: '0.000062s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.305+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: fs: '0.000067s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.305+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: ls: '0.000075s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.305+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: quota manager shutdown step: '0.000081s: startKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.306+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: idb: '0.000550s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.306+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: idb: '0.000567s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.306+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: idb: '0.000699s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.306+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: idb: '0.000709s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.307+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: idb: '0.000812s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.307+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: idb: '0.000822s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.307+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: idb: '0.000888s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.307+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: idb: '0.000896s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.307+00:00] 06:10:22     INFO -  [Parent 2542, QuotaManager IO] WARNING: quota manager shutdown step: '0.000953s: ShutdownStorageOp::DoDirectoryWork -> ShutdownStorageInternal.', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.308+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: quota manager shutdown step: '0.001056s: stopKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.308+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: quota manager shutdown step: '0.001068s: shutdownAndJoinWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.308+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: idb: '0.001073s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.308+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: cache: '0.001323s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.309+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: sdb: '0.001332s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.309+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: fs: '0.001336s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.309+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: ls: '0.001339s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.309+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: quota manager shutdown step: '0.001342s: shutdownAndJoinIOThread', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.310+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: quota manager shutdown step: '0.001459s: invalidatePendingDirectoryLocks', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.310+00:00] 06:10:22     INFO -  [Parent 2542, IPDL Background] WARNING: quota manager shutdown step: '0.001468s: stopCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2412
[task 2026-03-06T06:10:22.374+00:00] 06:10:22     INFO -  [Utility 2550, Main Thread] WARNING: IPC message 'PUtilityProcess::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:511
[task 2026-03-06T06:10:22.382+00:00] 06:10:22     INFO -  1772777422381	Marionette	TRACE	Received observer notification xpcom-shutdown
[task 2026-03-06T06:10:22.382+00:00] 06:10:22     INFO -  [GPU 2545, Main Thread] WARNING: IPC message 'PGPU::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:511
[task 2026-03-06T06:10:22.384+00:00] 06:10:22     INFO -  [RDD 2547, Main Thread] WARNING: IPC message 'PRDD::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:511
[task 2026-03-06T06:10:22.398+00:00] 06:10:22     INFO -  1772777422397	Marionette	TRACE	Received observer notification xpcom-shutdown-threads
[task 2026-03-06T06:10:23.100+00:00] 06:10:23     INFO - Application command: /opt/worker/tasks/task_177277687192879/build/application/Firefox NightlyDebug.app/Contents/MacOS/firefox -marionette -remote-allow-system-access -foreground -profile /var/folders/mz/yz6g78bx1jlb3snvfzs98czh0000jb/T/tmp4gqk4dfo.mozrunner
[task 2026-03-06T06:10:23.727+00:00] 06:10:23     INFO -  1772777423727	Marionette	INFO	Marionette enabled
[task 2026-03-06T06:10:23.910+00:00] 06:10:23     INFO -  2026-03-06 06:10:23.909 Firefox Nightly GPU Helper[2554:39931] Failure on line 686 in function id scheduleApplicationNotification(LSNotificationCode, NSWorkspaceNotificationCenter *): noErr == _LSModifyNotification(notificationID, 1, &code, 0, NULL, NULL, NULL)
[task 2026-03-06T06:10:23.924+00:00] 06:10:23     INFO -  1772777423923	Marionette	TRACE	Received observer notification final-ui-startup
[task 2026-03-06T06:10:24.013+00:00] 06:10:24     INFO -  2026-03-06 06:10:24.012 Firefox Nightly GPU Helper[2554:39931] Error received in message reply handler: Connection invalid
[task 2026-03-06T06:10:24.013+00:00] 06:10:24     INFO -  2026-03-06 06:10:24.012 Firefox Nightly GPU Helper[2554:39945] Connection Invalid error for service com.apple.hiservices-xpcservice.
[task 2026-03-06T06:10:24.020+00:00] 06:10:24     INFO -  1772777424019	Marionette	INFO	Listening on port 2828
[task 2026-03-06T06:10:24.020+00:00] 06:10:24     INFO -  1772777424020	Marionette	DEBUG	Marionette is listening
[task 2026-03-06T06:10:24.261+00:00] 06:10:24     INFO -  1772777424261	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:50282
[task 2026-03-06T06:10:24.299+00:00] 06:10:24     INFO -  GL_VENDOR: Intel Inc.
[task 2026-03-06T06:10:24.299+00:00] 06:10:24     INFO -  mVendor: Intel
[task 2026-03-06T06:10:24.300+00:00] 06:10:24     INFO -  GL_RENDERER: Intel(R) UHD Graphics 630
[task 2026-03-06T06:10:24.300+00:00] 06:10:24     INFO -  mRenderer: Unknown
[task 2026-03-06T06:10:24.300+00:00] 06:10:24     INFO -  mIsMesa: 0
[task 2026-03-06T06:10:24.355+00:00] 06:10:24     INFO -  1772777424354	Marionette	DEBUG	Closed connection 0
[task 2026-03-06T06:10:24.356+00:00] 06:10:24     INFO -  1772777424355	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:50283
[task 2026-03-06T06:10:24.361+00:00] 06:10:24     INFO -  [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2026-03-06T06:10:24.970+00:00] 06:10:24     INFO -  1772777424970	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2026-03-06T06:10:24.982+00:00] 06:10:24     INFO -  1772777424981	Marionette	DEBUG	Waiting for initial application window
[task 2026-03-06T06:10:24.987+00:00] 06:10:24     INFO -  [Parent 2551, Main Thread] WARNING: '!anonymousDivElement', file /builds/worker/checkouts/gecko/dom/html/TextControlState.cpp:1588
[task 2026-03-06T06:10:24.988+00:00] 06:10:24     INFO -  [Parent 2551, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(PrepareEditor()) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/html/TextControlState.cpp:1826
[task 2026-03-06T06:10:25.769+00:00] 06:10:25     INFO -  [WARN  webrender::device::gl] Missing optimized shader source for cs_linear_gradient_DITHERING
[task 2026-03-06T06:10:25.939+00:00] 06:10:25     INFO -  console.debug: SearchService: "init"
[task 2026-03-06T06:10:26.091+00:00] 06:10:26     INFO -  [Parent 2551, Main Thread] WARNING: NS_ENSURE_TRUE(she && she->mInfo->mSharedState.Get()) failed: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:1221
[task 2026-03-06T06:10:26.155+00:00] 06:10:26     INFO -  console.debug: SearchEngineSelector: "fetchEngineConfiguration en-US:unknown:default:::Firefox:150.0a1"
[task 2026-03-06T06:10:26.163+00:00] 06:10:26     INFO -  console.debug: SearchEngineSelector: "fetchEngineConfiguration: google,bing,ddg,perplexity,wikipedia"
[task 2026-03-06T06:10:26.163+00:00] 06:10:26     INFO -  console.debug: SearchService: "#loadEngines: start"
[task 2026-03-06T06:10:26.163+00:00] 06:10:26     INFO -  console.debug: SearchService: "#loadEnginesFromConfig"
[task 2026-03-06T06:10:26.169+00:00] 06:10:26     INFO -  console.debug: SearchService: "#addEngineToStore: Adding engine:" "Google"
[task 2026-03-06T06:10:26.171+00:00] 06:10:26     INFO -  console.debug: SearchService: "#addEngineToStore: Adding engine:" "Bing"
[task 2026-03-06T06:10:26.172+00:00] 06:10:26     INFO -  console.debug: SearchService: "#addEngineToStore: Adding engine:" "DuckDuckGo"
[task 2026-03-06T06:10:26.173+00:00] 06:10:26     INFO -  console.debug: SearchService: "#addEngineToStore: Adding engine:" "Perplexity"
[task 2026-03-06T06:10:26.174+00:00] 06:10:26     INFO -  console.debug: SearchService: "#addEngineToStore: Adding engine:" "Wikipedia (en)"
[task 2026-03-06T06:10:26.174+00:00] 06:10:26     INFO -  console.debug: SearchService: "#loadStartupEngines: loading" 0 "engines reported by AddonManager startup"
[task 2026-03-06T06:10:26.174+00:00] 06:10:26     INFO -  console.debug: SearchService: "#loadEnginesFromSettings: Loading" 5 "engines from settings"
[task 2026-03-06T06:10:26.175+00:00] 06:10:26     INFO -  console.debug: SearchService: "#loadEnginesFromSettings: skipped" 5 "built-in/policy engines."
[task 2026-03-06T06:10:26.175+00:00] 06:10:26     INFO -  console.debug: SearchService: "#loadEngines: done"
[task 2026-03-06T06:10:26.177+00:00] 06:10:26     INFO -  console.debug: SearchService: "Completed #init"
[task 2026-03-06T06:10:26.416+00:00] 06:10:26     INFO -  console.debug: SearchService: "#handleIgnoreListUpdated"
[task 2026-03-06T06:10:26.446+00:00] 06:10:26     INFO -  console.debug: SearchTelemetry: "Initialize SERP categorizer."
[task 2026-03-06T06:10:26.446+00:00] 06:10:26     INFO -  console.debug: SearchTelemetry: "Initializing domain-to-categories map."
[task 2026-03-06T06:10:26.446+00:00] 06:10:26     INFO -  console.debug: SearchTelemetry: "Setting up domain-to-categories map."
[task 2026-03-06T06:10:26.446+00:00] 06:10:26     INFO -  console.debug: SearchTelemetry: "Initializing domain-to-categories store."
[task 2026-03-06T06:10:26.448+00:00] 06:10:26     INFO -  console.debug: SearchTelemetry: "Create store."
[task 2026-03-06T06:10:26.454+00:00] 06:10:26     INFO -  console.debug: SearchTelemetry: "Initialized domain-to-categories store."
[task 2026-03-06T06:10:26.457+00:00] 06:10:26     INFO -  console.debug: SearchTelemetry: "No records found for domain-to-categories map."
[task 2026-03-06T06:10:26.458+00:00] 06:10:26     INFO -  console.debug: SearchTelemetry: "Initialized domain-to-categories map."
[task 2026-03-06T06:10:26.458+00:00] 06:10:26     INFO -  console.debug: SearchTelemetry: "Initializing categorization event scheduler."
[task 2026-03-06T06:10:26.598+00:00] 06:10:26     INFO -  console.debug: SearchService: "getEngines: getting all engines"
[task 2026-03-06T06:10:26.598+00:00] 06:10:26     INFO -  console.debug: SearchService: "#buildSortedEngineList: using default orders"
[task 2026-03-06T06:10:26.901+00:00] 06:10:26     INFO -  [Child 2558, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/modules/libjar/nsJARChannel.cpp:379
[task 2026-03-06T06:10:27.158+00:00] 06:10:27     INFO -  [WARN  glean_core::upload] Recoverable upload failure while attempting to send ping 06b7528a-8aa7-44b2-8757-f5474e5c2166, will retry. Error was RecoverableFailure { unused: 0 }
[task 2026-03-06T06:10:27.169+00:00] 06:10:27     INFO -  [WARN  glean_core::upload] Recoverable upload failure while attempting to send ping b5aac99e-1044-4285-891b-a49fd84e5fb8, will retry. Error was RecoverableFailure { unused: 0 }
[task 2026-03-06T06:10:27.179+00:00] 06:10:27     INFO -  [WARN  glean_core::upload] Recoverable upload failure while attempting to send ping a7f5346c-d076-4b2c-9cf4-01740eb7a079, will retry. Error was RecoverableFailure { unused: 0 }
[task 2026-03-06T06:10:27.179+00:00] 06:10:27     INFO -  [WARN  glean_core::upload] Reached maximum recoverable failures for the current uploading window. You are done.
[task 2026-03-06T06:10:27.258+00:00] 06:10:27     INFO -  console.debug: SearchSettings: "batchTask: Invalidating engine settings"
[task 2026-03-06T06:10:27.259+00:00] 06:10:27     INFO -  console.debug: SearchSettings: "_write: Settings unchanged. Did not write to disk."
[task 2026-03-06T06:10:27.265+00:00] 06:10:27     INFO -  [WARN  glean_core::upload] Recoverable upload failure while attempting to send ping ffdde37e-12b0-4edc-b49a-39fe33660f1e, will retry. Error was RecoverableFailure { unused: 0 }
[task 2026-03-06T06:10:27.280+00:00] 06:10:27     INFO -  [WARN  glean_core::upload] Recoverable upload failure while attempting to send ping 942bf50a-1322-48be-835a-288ad18e1e8a, will retry. Error was RecoverableFailure { unused: 0 }
[task 2026-03-06T06:10:27.294+00:00] 06:10:27     INFO -  [WARN  glean_core::upload] Recoverable upload failure while attempting to send ping fc286586-b9cd-4a0c-b348-c3e4f2618291, will retry. Error was RecoverableFailure { unused: 0 }
[task 2026-03-06T06:10:27.294+00:00] 06:10:27     INFO -  [WARN  glean_core::upload] Reached maximum recoverable failures for the current uploading window. You are done.
[task 2026-03-06T06:10:27.300+00:00] 06:10:27     INFO -  [WARN  glean_core::upload] Recoverable upload failure while attempting to send ping 36a9021d-489b-4efa-8385-4442639c9917, will retry. Error was RecoverableFailure { unused: 0 }
[task 2026-03-06T06:10:27.302+00:00] 06:10:27     INFO -  [WARN  glean_core::upload] Recoverable upload failure while attempting to send ping 0c02236d-b4c1-4a77-abbd-5411cbaa3d9e, will retry. Error was RecoverableFailure { unused: 0 }
[task 2026-03-06T06:10:27.304+00:00] 06:10:27     INFO -  [WARN  glean_core::upload] Recoverable upload failure while attempting to send ping 2735e5f2-83e0-4f87-a067-ae7d8ece4fdd, will retry. Error was RecoverableFailure { unused: 0 }
[task 2026-03-06T06:10:27.304+00:00] 06:10:27     INFO -  [WARN  glean_core::upload] Reached maximum recoverable failures for the current uploading window. You are done.
[task 2026-03-06T06:10:27.464+00:00] 06:10:27     INFO -  1772777427464	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2026-03-06T06:10:27.467+00:00] 06:10:27     INFO -  1772777427467	RemoteAgent	TRACE	[4] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=40000 waitForExplicitStart=false
[task 2026-03-06T06:10:27.468+00:00] 06:10:27     INFO -  1772777427467	RemoteAgent	TRACE	[4] ProgressListener Setting unload timer (40000ms)
[task 2026-03-06T06:10:27.468+00:00] 06:10:27     INFO -  1772777427468	RemoteAgent	TRACE	[4] Wait for initial navigation: isUncommittedInitial=false, isLoadingDocument=false
[task 2026-03-06T06:10:27.468+00:00] 06:10:27     INFO -  1772777427468	RemoteAgent	TRACE	[4] Document already finished loading: about:blank
[task 2026-03-06T06:10:27.469+00:00] 06:10:27     INFO -  1772777427468	RemoteAgent	TRACE	[4] ProgressListener Stop: has error=false url=about:blank
[task 2026-03-06T06:10:27.486+00:00] 06:10:27     INFO -  1772777427485	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"b56e4061-0536-4008-8475-ba93fdca1755","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"150.0a1","platformName":"mac","setWindowRect":true,"unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:150.0) Gecko/20100101 Firefox/150.0","moz:buildID":"20260306044542","moz:headless":false,"moz:platformVersion":"23.6.0","moz:processID":2551,"moz:profile":"/var/folders/mz/yz6g78bx1jlb3snvfzs98czh0000jb/T/tmp4gqk4dfo.mozrunner","moz:shutdownTimeout":60000,"pageLoadStrategy":"normal","timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":true,"moz:accessibilityChecks":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2026-03-06T06:10:27.493+00:00] 06:10:27     INFO -  1772777427492	Marionette	DEBUG	1 -> [0,2,"Marionette:SetContext",{"value":"chrome"}]
[task 2026-03-06T06:10:27.493+00:00] 06:10:27     INFO -  1772777427493	Marionette	DEBUG	1 <- [1,2,null,{"value":null}]
[task 2026-03-06T06:10:27.494+00:00] 06:10:27     INFO -  1772777427494	Marionette	DEBUG	1 -> [0,3,"Marionette:SetContext",{"value":"chrome"}]
[task 2026-03-06T06:10:27.495+00:00] 06:10:27     INFO -  1772777427495	Marionette	DEBUG	1 <- [1,3,null,{"value":null}]
[task 2026-03-06T06:10:27.497+00:00] 06:10:27     INFO -  1772777427496	Marionette	DEBUG	1 -> [0,4,"WebDriver:ExecuteAsyncScript",{"script":"let resolve = arguments[0];\n            let { BrowserInitState } = ChromeUtils.importESModule(\"resource:///modules/BrowserGlu ... WindowTracker.getTopWindow();\n                await win.SidebarController.promiseInitialized;\n            })().then(resolve);","args":[],"newSandbox":true,"sandbox":"default","scriptTimeout":null,"line":87,"filename":"tests/browser/components/sidebar/tests/marionette/test_default_launcher_visible.py"}]
[task 2026-03-06T06:10:27.500+00:00] 06:10:27     INFO -  1772777427500	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 2551
[task 2026-03-06T06:10:27.501+00:00] 06:10:27     INFO -  1772777427501	Marionette	TRACE	[2] MarionetteCommands actor created for window id 4
[task 2026-03-06T06:10:27.506+00:00] 06:10:27     INFO -  1772777427506	Marionette	DEBUG	1 <- [1,4,null,{"value":null}]
[task 2026-03-06T06:10:27.508+00:00] 06:10:27     INFO -  1772777427507	Marionette	DEBUG	1 -> [0,5,"WebDriver:ExecuteScript",{"script":"const window = BrowserWindowTracker.getTopWindow();\n            const placement = window.CustomizableUI.getPlacementOfWidget( ...         }\n            const node = window.document.getElementById(\"sidebar-button\");\n            return node && !node.hidden;","args":[],"newSandbox":true,"sandbox":"default","line":63,"filename":"tests/browser/components/sidebar/tests/marionette/test_default_launcher_visible.py"}]
[task 2026-03-06T06:10:27.511+00:00] 06:10:27     INFO -  1772777427511	Marionette	DEBUG	1 <- [1,5,null,{"value":false}]
[task 2026-03-06T06:10:27.513+00:00] 06:10:27     INFO -  1772777427513	Marionette	DEBUG	1 -> [0,6,"Marionette:GetContext",{}]
[task 2026-03-06T06:10:27.513+00:00] 06:10:27     INFO -  1772777427513	Marionette	DEBUG	1 <- [1,6,null,{"value":"chrome"}]
[task 2026-03-06T06:10:27.514+00:00] 06:10:27     INFO -  1772777427514	Marionette	DEBUG	1 -> [0,7,"Marionette:SetContext",{"value":"chrome"}]
[task 2026-03-06T06:10:27.515+00:00] 06:10:27     INFO -  1772777427514	Marionette	DEBUG	1 <- [1,7,null,{"value":null}]
[task 2026-03-06T06:10:27.516+00:00] 06:10:27     INFO -  1772777427515	Marionette	DEBUG	1 -> [0,8,"WebDriver:TakeScreenshot",{"id":null,"full":true,"hash":false,"scroll":true}]
[task 2026-03-06T06:10:27.575+00:00] 06:10:27     INFO -  [GFX3-]: Creating null Skia image from null SourceSurface
[task 2026-03-06T06:10:27.575+00:00] 06:10:27     INFO -  [GFX3-]: Creating null Skia image from null SourceSurface
[task 2026-03-06T06:10:27.575+00:00] 06:10:27     INFO -  [GFX3-]: Creating null Skia image from null SourceSurface
[task 2026-03-06T06:10:27.577+00:00] 06:10:27     INFO -  [GFX3-]: Creating null Skia image from null SourceSurface
[task 2026-03-06T06:10:27.598+00:00] 06:10:27     INFO -  1772777427598	Marionette	DEBUG	1 <- [1,8,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAO1CAYAAAAxM0gLAAAgAElEQVR4XuzdB5hcZ3n47Ue9WtW23OUiG1uukhvYBlMNMQ4tQBIIHWNsE2oCwXQCgUACphmCKYEPC ... AMQEIAAAAAGMCEAAAAADGBCAAAAAAjAlAAAAAABgTgAAAAAAwJgABAAAAYEwAAgAAAMCYAAQAAACAMQEIAAAAAGMCEAAAAADGAnx1lu5GffYoAAAAAElFTkSuQmCC"}]
[task 2026-03-06T06:10:27.601+00:00] 06:10:27     INFO -  1772777427601	Marionette	DEBUG	1 -> [0,9,"Marionette:SetContext",{"value":"chrome"}]
[task 2026-03-06T06:10:27.601+00:00] 06:10:27     INFO -  1772777427601	Marionette	DEBUG	1 <- [1,9,null,{"value":null}]
[task 2026-03-06T06:10:27.602+00:00] 06:10:27     INFO -  1772777427602	Marionette	DEBUG	1 -> [0,10,"Marionette:GetContext",{}]
[task 2026-03-06T06:10:27.603+00:00] 06:10:27     INFO -  1772777427602	Marionette	DEBUG	1 <- [1,10,null,{"value":"chrome"}]
[task 2026-03-06T06:10:27.604+00:00] 06:10:27     INFO -  1772777427604	Marionette	DEBUG	1 -> [0,11,"Marionette:SetContext",{"value":"content"}]
[task 2026-03-06T06:10:27.604+00:00] 06:10:27     INFO -  1772777427604	Marionette	DEBUG	1 <- [1,11,null,{"value":null}]
[task 2026-03-06T06:10:27.605+00:00] 06:10:27     INFO -  1772777427605	Marionette	DEBUG	1 -> [0,12,"WebDriver:GetPageSource",{}]
[task 2026-03-06T06:10:27.610+00:00] 06:10:27     INFO -  1772777427610	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 2555
[task 2026-03-06T06:10:27.613+00:00] 06:10:27     INFO -  1772777427612	Marionette	TRACE	[4] MarionetteCommands actor created for window id 4294967297
[task 2026-03-06T06:10:27.617+00:00] 06:10:27     INFO -  1772777427616	Marionette	DEBUG	1 <- [1,12,null,{"value":"<html><head></head><body></body></html>"}]
[task 2026-03-06T06:10:27.617+00:00] 06:10:27     INFO -  1772777427617	Marionette	DEBUG	1 -> [0,13,"Marionette:SetContext",{"value":"chrome"}]
[task 2026-03-06T06:10:27.617+00:00] 06:10:27     INFO -  1772777427617	Marionette	DEBUG	1 <- [1,13,null,{"value":null}]
[task 2026-03-06T06:10:27.652+00:00] 06:10:27     INFO - TEST-UNEXPECTED-FAIL | browser/components/sidebar/tests/marionette/test_default_launcher_visible.py TestDefaultLauncherVisible.test_first_use_default_visible_pref_false | AssertionError: False is not true : Sidebar button should be visible
[task 2026-03-06T06:10:27.652+00:00] 06:10:27     INFO - Traceback (most recent call last):
[task 2026-03-06T06:10:27.652+00:00] 06:10:27     INFO -   File "/opt/worker/tasks/task_177277687192879/build/venv/lib/python3.11/site-packages/marionette_harness/marionette_test/testcases.py", line 192, in run
[task 2026-03-06T06:10:27.652+00:00] 06:10:27     INFO -     testMethod()
[task 2026-03-06T06:10:27.652+00:00] 06:10:27     INFO -   File "/opt/worker/tasks/task_177277687192879/build/tests/marionette/tests/browser/components/sidebar/tests/marionette/test_default_launcher_visible.py", line 128, in test_first_use_default_visible_pref_false
[task 2026-03-06T06:10:27.652+00:00] 06:10:27     INFO -     self.assertTrue(
[task 2026-03-06T06:10:27.652+00:00] 06:10:27     INFO - TEST-INFO took 11265ms
[task 2026-03-06T06:10:27.653+00:00] 06:10:27     INFO -  1772777427619	Marionette	DEBUG	1 -> [0,14,"Marionette:GetContext",{}]
[task 2026-03-06T06:10:27.653+00:00] 06:10:27     INFO -  1772777427619	Marionette	DEBUG	1 <- [1,14,null,{"value":"chrome"}]
[task 2026-03-06T06:10:27.653+00:00] 06:10:27     INFO -  1772777427619	Marionette	DEBUG	1 -> [0,15,"WebDriver:DeleteSession",{}]
[task 2026-03-06T06:10:27.653+00:00] 06:10:27     INFO -  1772777427620	Marionette	TRACE	[2] MarionetteCommands actor destroyed for window id 4
[task 2026-03-06T06:10:27.653+00:00] 06:10:27     INFO -  1772777427620	Marionette	TRACE	[4] MarionetteCommands actor destroyed for window id 4294967297
[task 2026-03-06T06:10:27.654+00:00] 06:10:27     INFO -  1772777427622	Marionette	DEBUG	1 <- [1,15,null,{"value":null}]
[task 2026-03-06T06:10:27.654+00:00] 06:10:27     INFO -  1772777427622	Marionette	DEBUG	Closed connection 1
[task 2026-03-06T06:10:27.681+00:00] 06:10:27     INFO - Application command: /opt/worker/tasks/task_177277687192879/build/application/Firefox NightlyDebug.app/Contents/MacOS/firefox -marionette -remote-allow-system-access -foreground -profile /var/folders/mz/yz6g78bx1jlb3snvfzs98czh0000jb/T/tmpy17fsv54.mozrunner
[task 2026-03-06T06:10:28.579+00:00] 06:10:28     INFO -  1772777428579	Marionette	INFO	Marionette enabled
[task 2026-03-06T06:10:28.743+00:00] 06:10:28     INFO -  2026-03-06 06:10:28.742 Firefox Nightly GPU Helper[2571:40342] Failure on line 686 in function id scheduleApplicationNotification(LSNotificationCode, NSWorkspaceNotificationCenter *): noErr == _LSModifyNotification(notificationID, 1, &code, 0, NULL, NULL, NULL)
[task 2026-03-06T06:10:28.755+00:00] 06:10:28     INFO -  1772777428755	Marionette	TRACE	Received observer notification final-ui-startup
[task 2026-03-06T06:10:28.839+00:00] 06:10:28     INFO -  2026-03-06 06:10:28.838 Firefox Nightly GPU Helper[2571:40356] Connection Invalid error for service com.apple.hiservices-xpcservice.
[task 2026-03-06T06:10:28.839+00:00] 06:10:28     INFO -  2026-03-06 06:10:28.838 Firefox Nightly GPU Helper[2571:40342] Error received in message reply handler: Connection invalid
[task 2026-03-06T06:10:28.992+00:00] 06:10:28     INFO -  1772777428992	Marionette	INFO	Listening on port 2828
[task 2026-03-06T06:10:28.998+00:00] 06:10:28     INFO -  1772777428997	Marionette	DEBUG	Marionette is listening
[task 2026-03-06T06:10:29.249+00:00] 06:10:29     INFO -  1772777429249	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:50325
[task 2026-03-06T06:10:29.273+00:00] 06:10:29     INFO -  GL_VENDOR: Intel Inc.
[task 2026-03-06T06:10:29.273+00:00] 06:10:29     INFO -  mVendor: Intel
[task 2026-03-06T06:10:29.273+00:00] 06:10:29     INFO -  GL_RENDERER: Intel(R) UHD Graphics 630
[task 2026-03-06T06:10:29.273+00:00] 06:10:29     INFO -  mRenderer: Unknown
[task 2026-03-06T06:10:29.273+00:00] 06:10:29     INFO -  mIsMesa: 0
[task 2026-03-06T06:10:29.313+00:00] 06:10:29     INFO -  [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2026-03-06T06:10:29.313+00:00] 06:10:29     INFO -  1772777429313	Marionette	DEBUG	Closed connection 0
[task 2026-03-06T06:10:29.314+00:00] 06:10:29     INFO -  1772777429314	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:50326
[task 2026-03-06T06:10:29.577+00:00] 06:10:29     INFO -  1772777429576	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2026-03-06T06:10:29.600+00:00] 06:10:29     INFO -  1772777429599	Marionette	DEBUG	Waiting for initial application window
[task 2026-03-06T06:10:30.052+00:00] 06:10:30     INFO -  [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2026-03-06T06:10:30.478+00:00] 06:10:30     INFO -  [Parent 2568, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp:1879
[task 2026-03-06T06:10:30.528+00:00] 06:10:30     INFO -  [Parent 2568, Main Thread] WARNING: '!anonymousDivElement', file /builds/worker/checkouts/gecko/dom/html/TextControlState.cpp:1588
[task 2026-03-06T06:10:30.529+00:00] 06:10:30     INFO -  [Parent 2568, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(PrepareEditor()) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/html/TextControlState.cpp:1826
[task 2026-03-06T06:10:31.241+00:00] 06:10:31     INFO -  [WARN  webrender::device::gl] Missing optimized shader source for cs_linear_gradient_DITHERING
[task 2026-03-06T06:10:31.614+00:00] 06:10:31     INFO -  [Parent 2568, Main Thread] WARNING: NS_ENSURE_TRUE(she && she->mInfo->mSharedState.Get()) failed: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:1221
[task 2026-03-06T06:10:32.005+00:00] 06:10:32     INFO -  [Parent 2568, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1227
[task 2026-03-06T06:10:32.108+00:00] 06:10:32     INFO -  [Parent 2568, Main Thread] WARNING: NS_ENSURE_TRUE(mNameHashtable.Get(aName, &index)) failed: file /builds/worker/checkouts/gecko/storage/mozStorageRow.cpp:91
[task 2026-03-06T06:10:32.447+00:00] 06:10:32     INFO -  [Child 2573, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/modules/libjar/nsJARChannel.cpp:379
[task 2026-03-06T06:10:32.779+00:00] 06:10:32     INFO -  Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2026-03-06T06:10:32.780+00:00] 06:10:32     INFO -  [Child 2578, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:569
[task 2026-03-06T06:10:32.794+00:00] 06:10:32     INFO -  Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2026-03-06T06:10:32.794+00:00] 06:10:32     INFO -  [Child 2579, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:569
[task 2026-03-06T06:10:32.880+00:00] 06:10:32     INFO -  1772777432879	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2026-03-06T06:10:33.256+00:00] 06:10:33     INFO -  1772777433256	RemoteAgent	TRACE	[11] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=40000 waitForExplicitStart=false
[task 2026-03-06T06:10:33.257+00:00] 06:10:33     INFO -  1772777433256	RemoteAgent	TRACE	[11] ProgressListener Setting unload timer (40000ms)
[task 2026-03-06T06:10:33.258+00:00] 06:10:33     INFO -  1772777433257	RemoteAgent	TRACE	[11] Wait for initial navigation: isUncommittedInitial=false, isLoadingDocument=false
[task 2026-03-06T06:10:33.258+00:00] 06:10:33     INFO -  1772777433257	RemoteAgent	TRACE	[11] Document already finished loading: about:blank
[task 2026-03-06T06:10:33.258+00:00] 06:10:33     INFO -  1772777433257	RemoteAgent	TRACE	[11] ProgressListener Stop: has error=false url=about:blank
[task 2026-03-06T06:10:33.276+00:00] 06:10:33     INFO -  1772777433275	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"6c3e1ef3-99c0-4ad7-a8cf-e9bf10c2e41f","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"150.0a1","platformName":"mac","setWindowRect":true,"unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:150.0) Gecko/20100101 Firefox/150.0","moz:buildID":"20260306044542","moz:headless":false,"moz:platformVersion":"23.6.0","moz:processID":2568,"moz:profile":"/var/folders/mz/yz6g78bx1jlb3snvfzs98czh0000jb/T/tmpy17fsv54.mozrunner","moz:shutdownTimeout":60000,"pageLoadStrategy":"normal","timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":true,"moz:accessibilityChecks":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2026-03-06T06:10:33.284+00:00] 06:10:33     INFO -  1772777433284	Marionette	DEBUG	1 -> [0,2,"Marionette:SetContext",{"value":"chrome"}]
[task 2026-03-06T06:10:33.285+00:00] 06:10:33     INFO -  1772777433284	Marionette	DEBUG	1 <- [1,2,null,{"value":null}]
[task 2026-03-06T06:10:33.287+00:00] 06:10:33     INFO -  1772777433286	Marionette	DEBUG	1 -> [0,3,"WebDriver:DeleteSession",{}]
[task 2026-03-06T06:10:33.289+00:00] 06:10:33     INFO -  1772777433289	Marionette	DEBUG	1 <- [1,3,null,{"value":null}]
[task 2026-03-06T06:10:33.290+00:00] 06:10:33     INFO -  1772777433290	Marionette	DEBUG	Closed connection 1
[task 2026-03-06T06:10:33.291+00:00] 06:10:33     INFO - TEST-START | browser/components/sidebar/tests/marionette/test_default_launcher_visible.py 

Hi @aryx
We are not sure what caused this, we noticed this is a new job that doesn't run normally in autoland or central and we found this revision, that is making changes in that manifest but it looks like the timing doesn't fit since that got merged to central in the previous push and the job did not run.
Could you please take a look? Thank you.

Flags: needinfo?(aryx.bugmail)
Assignee: nobody → smolnar
Status: NEW → ASSIGNED
Assignee: smolnar → nobody
Status: ASSIGNED → NEW
Keywords: leave-open
Whiteboard: [collect_confirm_failure] → [collect_confirm_failure][stockwell disabled]
Pushed by smolnar@mozilla.com: https://github.com/mozilla-firefox/firefox/commit/cf520968d96c https://hg.mozilla.org/integration/autoland/rev/62cc2c06e134 Disable test_default_launcher_visible.py on win & mac for frequent falures r=intermittent-reviewers,sfoster,aryx DONTBUILD
Whiteboard: [collect_confirm_failure][stockwell disabled] → [collect_confirm_failure][stockwell disabled][fidefe-sidebar]
Assignee: nobody → sfoster
Pushed by agoloman@mozilla.com: https://github.com/mozilla-firefox/firefox/commit/6087999d87e8 https://hg.mozilla.org/integration/autoland/rev/85efeb4c050a Revert "Bug 2021526 - Wait for CUI to render the sidebar-button before asserting on it. r=nsharpley" for causing marionette failures @test_default_launcher_visible.py.

Backed out for causing failures @test_default_launcher_visible.py.

Flags: needinfo?(sfoster)

Alas, looks like this won't also fix bug 2010115. Thanks.

Flags: needinfo?(sfoster)

The patch that landed un-disabled this test (removed the skip-ifs). So I think we can remove the leave-open keyword and at least the [stockwell disabled] whiteboard tag now, but I'm not sure how you normally handle this?

Flags: needinfo?(smolnar)
Flags: needinfo?(smolnar)
Keywords: leave-open
Whiteboard: [collect_confirm_failure][stockwell disabled][fidefe-sidebar] → [collect_confirm_failure][fidefe-sidebar]
Status: NEW → RESOLVED
Closed: 5 days ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: