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
Description
•