Open
Bug 1968578
Opened 5 months ago
Updated 5 months ago
perma fail ubuntu 24.04 opt: browser/components/sessionstore/test/marionette/test_restore_manually.py
Categories
(Firefox :: Session Restore, defect)
Firefox
Session Restore
Tracking
()
NEW
People
(Reporter: jmaher, Unassigned, NeedInfo)
References
(Blocks 1 open bug)
Details
when running tests in CI on a newer version of linux (18.04 -> 24.04) we run into some new perma failures.
in this opt log, you can see this failure:
[task 2025-05-24T20:10:55.294Z] 20:10:55 INFO - 1748117455292 RemoteAgent TRACE Received DOM event focus for [object HTMLDocument]
[task 2025-05-24T20:10:55.294Z] 20:10:55 INFO - 1748117455293 Marionette DEBUG 1 <- [1,64,null,{"value":null}]
[task 2025-05-24T20:10:55.299Z] 20:10:55 INFO - 1748117455298 Marionette DEBUG 1 -> [0,65,"WebDriver:ExecuteScript",{"script":"return gBrowser.tabs.map(tab => {\n return tab.linkedBrowser.currentURI.spec;\n });","args":[],"newSandbox":true,"sandbox":"default","line":232,"filename":"tests/browser/components/sessionstore/test/marionette/session_store_test_case.py"}]
[task 2025-05-24T20:10:55.306Z] 20:10:55 INFO - 1748117455305 Marionette DEBUG 1 <- [1,65,null,{"value":["data:text/html;charset=utf-8,<html><head><title>lorem ipsom</title></head><body></body></html>","data:text/html;charset=utf-8,<html><head><title>dolor</title></head><body></body></html>"]}]
[task 2025-05-24T20:10:55.307Z] 20:10:55 INFO - 1748117455306 Marionette DEBUG 1 -> [0,66,"Marionette:SetContext",{"value":"chrome"}]
[task 2025-05-24T20:10:55.307Z] 20:10:55 INFO - 1748117455306 Marionette DEBUG 1 <- [1,66,null,{"value":null}]
[task 2025-05-24T20:10:55.309Z] 20:10:55 INFO - 1748117455308 Marionette DEBUG 1 -> [0,67,"WebDriver:SwitchToWindow",{"handle":"95688d30-08ba-4d4f-809d-b3347851dc21","focus":true}]
[task 2025-05-24T20:10:55.322Z] 20:10:55 INFO - 1748117455317 RemoteAgent TRACE Received DOM event activate for [object Window]
[task 2025-05-24T20:10:55.324Z] 20:10:55 INFO - 1748117455317 RemoteAgent TRACE Received DOM event focus for [object HTMLDocument]
[task 2025-05-24T20:10:55.326Z] 20:10:55 INFO - 1748117455324 Marionette DEBUG 1 <- [1,67,null,{"value":null}]
[task 2025-05-24T20:10:55.327Z] 20:10:55 INFO - 1748117455326 Marionette DEBUG 1 -> [0,68,"Marionette:GetContext",{}]
[task 2025-05-24T20:10:55.329Z] 20:10:55 INFO - 1748117455326 Marionette DEBUG 1 <- [1,68,null,{"value":"chrome"}]
[task 2025-05-24T20:10:55.331Z] 20:10:55 INFO - 1748117455328 Marionette DEBUG 1 -> [0,69,"Marionette:SetContext",{"value":"chrome"}]
[task 2025-05-24T20:10:55.331Z] 20:10:55 INFO - 1748117455328 Marionette DEBUG 1 <- [1,69,null,{"value":null}]
[task 2025-05-24T20:10:55.331Z] 20:10:55 INFO - 1748117455329 Marionette DEBUG 1 -> [0,70,"WebDriver:GetWindowHandle",{}]
[task 2025-05-24T20:10:55.331Z] 20:10:55 INFO - 1748117455329 Marionette DEBUG 1 <- [1,70,null,{"value":"95688d30-08ba-4d4f-809d-b3347851dc21"}]
[task 2025-05-24T20:10:55.331Z] 20:10:55 INFO - 1748117455330 Marionette DEBUG 1 -> [0,71,"Marionette:SetContext",{"value":"chrome"}]
[task 2025-05-24T20:10:55.332Z] 20:10:55 INFO - 1748117455330 Marionette DEBUG 1 <- [1,71,null,{"value":null}]
[task 2025-05-24T20:10:55.332Z] 20:10:55 INFO - 1748117455331 Marionette DEBUG 1 -> [0,72,"Marionette:GetContext",{}]
[task 2025-05-24T20:10:55.332Z] 20:10:55 INFO - 1748117455331 Marionette DEBUG 1 <- [1,72,null,{"value":"chrome"}]
[task 2025-05-24T20:10:55.334Z] 20:10:55 INFO - 1748117455334 Marionette DEBUG 1 -> [0,73,"Marionette:SetContext",{"value":"chrome"}]
[task 2025-05-24T20:10:55.334Z] 20:10:55 INFO - 1748117455334 Marionette DEBUG 1 <- [1,73,null,{"value":null}]
[task 2025-05-24T20:10:55.335Z] 20:10:55 INFO - 1748117455334 Marionette DEBUG 1 -> [0,74,"WebDriver:SwitchToWindow",{"handle":"95688d30-08ba-4d4f-809d-b3347851dc21","focus":true}]
[task 2025-05-24T20:10:55.336Z] 20:10:55 INFO - 1748117455335 Marionette DEBUG 1 <- [1,74,null,{"value":null}]
[task 2025-05-24T20:10:55.340Z] 20:10:55 INFO - 1748117455339 Marionette DEBUG 1 -> [0,75,"WebDriver:ExecuteScript",{"script":"return gBrowser.tabs.map(tab => {\n return tab.linkedBrowser.currentURI.spec;\n });","args":[],"newSandbox":true,"sandbox":"default","line":232,"filename":"tests/browser/components/sessionstore/test/marionette/session_store_test_case.py"}]
[task 2025-05-24T20:10:55.349Z] 20:10:55 INFO - 1748117455345 Marionette DEBUG 1 <- [1,75,null,{"value":["data:text/html;charset=utf-8,<html><head><title>sit</title></head><body></body></html>"]}]
[task 2025-05-24T20:10:55.351Z] 20:10:55 INFO - 1748117455350 Marionette DEBUG 1 -> [0,76,"Marionette:SetContext",{"value":"chrome"}]
[task 2025-05-24T20:10:55.351Z] 20:10:55 INFO - 1748117455350 Marionette DEBUG 1 <- [1,76,null,{"value":null}]
[task 2025-05-24T20:10:55.353Z] 20:10:55 INFO - 1748117455351 Marionette DEBUG 1 -> [0,77,"WebDriver:SwitchToWindow",{"handle":"95688d30-08ba-4d4f-809d-b3347851dc21","focus":true}]
[task 2025-05-24T20:10:55.355Z] 20:10:55 INFO - 1748117455352 Marionette DEBUG 1 <- [1,77,null,{"value":null}]
[task 2025-05-24T20:10:55.355Z] 20:10:55 INFO - 1748117455353 Marionette DEBUG 1 -> [0,78,"Marionette:GetContext",{}]
[task 2025-05-24T20:10:55.356Z] 20:10:55 INFO - 1748117455353 Marionette DEBUG 1 <- [1,78,null,{"value":"chrome"}]
[task 2025-05-24T20:10:55.356Z] 20:10:55 INFO - 1748117455354 Marionette DEBUG 1 -> [0,79,"Marionette:SetContext",{"value":"chrome"}]
[task 2025-05-24T20:10:55.356Z] 20:10:55 INFO - 1748117455354 Marionette DEBUG 1 <- [1,79,null,{"value":null}]
[task 2025-05-24T20:10:55.357Z] 20:10:55 INFO - 1748117455355 Marionette DEBUG 1 -> [0,80,"WebDriver:GetWindowHandles",{}]
[task 2025-05-24T20:10:55.357Z] 20:10:55 INFO - 1748117455355 Marionette DEBUG 1 <- [1,80,null,["b1d30d6d-6d7f-4b6e-b953-ff0f9541aee3","95688d30-08ba-4d4f-809d-b3347851dc21"]]
[task 2025-05-24T20:10:55.357Z] 20:10:55 INFO - 1748117455356 Marionette DEBUG 1 -> [0,81,"Marionette:SetContext",{"value":"chrome"}]
[task 2025-05-24T20:10:55.357Z] 20:10:55 INFO - 1748117455356 Marionette DEBUG 1 <- [1,81,null,{"value":null}]
[task 2025-05-24T20:10:55.358Z] 20:10:55 INFO - 1748117455357 Marionette DEBUG 1 -> [0,82,"WebDriver:ExecuteAsyncScript",{"script":"function getAllBrowserWindows() {\n return Array.from(Services.wm.getEnumerator(\"navigator:browser\"));\n ... et windows = getAllBrowserWindows();\n let value = 500;\n promiseResize(value, windows[1]).then(resolve);","args":[],"newSandbox":true,"sandbox":"default","scriptTimeout":null,"line":58,"filename":"tests/browser/components/sessionstore/test/marionette/test_restore_manually.py"}]
[task 2025-05-24T20:10:55.592Z] 20:10:55 INFO - 1748117455589 Marionette DEBUG 1 <- [1,82,null,{"value":null}]
[task 2025-05-24T20:10:55.592Z] 20:10:55 INFO - 1748117455590 Marionette DEBUG 1 -> [0,83,"Marionette:AcceptConnections",{"value":false}]
[task 2025-05-24T20:10:55.592Z] 20:10:55 INFO - 1748117455590 Marionette INFO Stopped listening on port 2828
[task 2025-05-24T20:10:55.592Z] 20:10:55 INFO - 1748117455590 Marionette DEBUG 1 <- [1,83,null,{"value":null}]
[task 2025-05-24T20:10:55.592Z] 20:10:55 INFO - 1748117455591 Marionette DEBUG 1 -> [0,84,"Marionette:Quit",{}]
[task 2025-05-24T20:10:56.030Z] 20:10:56 INFO - 1748117456030 Marionette TRACE [12] MarionetteCommands actor destroyed for window id 25
[task 2025-05-24T20:10:56.031Z] 20:10:56 INFO - 1748117456030 Marionette TRACE [1] MarionetteCommands actor destroyed for window id 2
[task 2025-05-24T20:10:56.098Z] 20:10:56 INFO - 1748117456096 Marionette TRACE Received observer notification quit-application
[task 2025-05-24T20:10:56.103Z] 20:10:56 INFO - 1748117456102 Marionette TRACE Received observer notification quit-application
[task 2025-05-24T20:10:56.106Z] 20:10:56 INFO - 1748117456106 Marionette DEBUG 1 <- [1,84,null,{"cause":"shutdown","forced":false,"in_app":true}]
[task 2025-05-24T20:10:56.126Z] 20:10:56 INFO - 1748117456125 Marionette DEBUG Marionette stopped listening
[task 2025-05-24T20:10:56.132Z] 20:10:56 INFO - 1748117456131 Marionette DEBUG Closed connection 1
[task 2025-05-24T20:10:56.139Z] 20:10:56 INFO - console.error: AboutHomeStartupCache: "A privileged about content process shut down while cache streams were still en route."
[task 2025-05-24T20:10:56.340Z] 20:10:56 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -remote-allow-system-access -profile /tmp/tmpzwkq350_.mozrunner
[task 2025-05-24T20:10:56.640Z] 20:10:56 INFO - libEGL warning: DRI3: Screen seems not DRI3 capable
[task 2025-05-24T20:10:56.640Z] 20:10:56 INFO - libEGL warning: DRI3: Screen seems not DRI3 capable
[task 2025-05-24T20:10:56.656Z] 20:10:56 INFO - MESA: error: ZINK: failed to choose pdev
[task 2025-05-24T20:10:56.657Z] 20:10:56 INFO - libEGL warning: egl: failed to create dri2 screen
[task 2025-05-24T20:10:56.743Z] 20:10:56 INFO - 1748117456742 Marionette INFO Marionette enabled
[task 2025-05-24T20:10:56.743Z] 20:10:56 INFO - 1748117456743 Marionette TRACE Received observer notification final-ui-startup
[task 2025-05-24T20:10:56.779Z] 20:10:56 INFO - 1748117456779 Marionette INFO Listening on port 2828
[task 2025-05-24T20:10:56.780Z] 20:10:56 INFO - 1748117456779 Marionette DEBUG Marionette is listening
[task 2025-05-24T20:10:56.851Z] 20:10:56 INFO - 1748117456851 Marionette DEBUG Accepted connection 0 from 127.0.0.1:48356
[task 2025-05-24T20:10:56.884Z] 20:10:56 INFO - 1748117456883 Marionette DEBUG Closed connection 0
[task 2025-05-24T20:10:56.885Z] 20:10:56 INFO - 1748117456884 Marionette DEBUG Accepted connection 1 from 127.0.0.1:48372
[task 2025-05-24T20:10:56.985Z] 20:10:56 INFO - 1748117456983 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2025-05-24T20:10:56.990Z] 20:10:56 INFO - 1748117456985 Marionette DEBUG Waiting for initial application window
[task 2025-05-24T20:10:58.044Z] 20:10:58 INFO - 1748117458043 Marionette TRACE Received observer notification browser-idle-startup-tasks-finished
[task 2025-05-24T20:10:58.047Z] 20:10:58 INFO - 1748117458046 RemoteAgent TRACE [3] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=5000 waitForExplicitStart=false
[task 2025-05-24T20:10:58.047Z] 20:10:58 INFO - 1748117458046 RemoteAgent TRACE [3] ProgressListener Setting unload timer (5000ms)
[task 2025-05-24T20:10:58.048Z] 20:10:58 INFO - 1748117458047 RemoteAgent TRACE [3] Wait for initial navigation: isInitial=false, isLoadingDocument=false
[task 2025-05-24T20:10:58.048Z] 20:10:58 INFO - 1748117458047 RemoteAgent TRACE [3] Document already finished loading: about:home
[task 2025-05-24T20:10:58.048Z] 20:10:58 INFO - 1748117458047 RemoteAgent TRACE [3] ProgressListener Stop: has error=false url=about:home
[task 2025-05-24T20:10:58.053Z] 20:10:58 INFO - 1748117458052 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"b380d6d6-e396-4906-9ac4-3949805317e2","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"140.0a1","platformName":"linux","unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (X11; Linux x86_64; rv:140.0) Gecko/20100101 Firefox/140.0","moz:buildID":"20250524193623","moz:headless":false,"moz:platformVersion":"6.11.0-1015-gcp","moz:processID":8442,"moz:profile":"/tmp/tmpzwkq350_.mozrunner","moz:shutdownTimeout":60000,"pageLoadStrategy":"normal","timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"setWindowRect":true,"strictFileInteractability":true,"moz:accessibilityChecks":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2025-05-24T20:10:58.055Z] 20:10:58 INFO - 1748117458055 Marionette DEBUG 1 -> [0,2,"Marionette:SetContext",{"value":"chrome"}]
[task 2025-05-24T20:10:58.056Z] 20:10:58 INFO - 1748117458055 Marionette DEBUG 1 <- [1,2,null,{"value":null}]
[task 2025-05-24T20:10:58.061Z] 20:10:58 INFO - 1748117458060 Marionette DEBUG 1 -> [0,3,"WebDriver:ExecuteScript",{"script":"const lazy = {};\n ChromeUtils.defineESModuleGetters(lazy, {\n SessionStore: \"resource:///modules/ses ... ionStore.restoreLastSession();\n await closedWindowsObserver;\n }\n checkForWindowHeight();","args":[],"newSandbox":true,"sandbox":"default","line":96,"filename":"tests/browser/components/sessionstore/test/marionette/test_restore_manually.py"}]
[task 2025-05-24T20:10:58.062Z] 20:10:58 INFO - 1748117458062 RemoteAgent TRACE WebDriverProcessData actor created for PID 8442
[task 2025-05-24T20:10:58.063Z] 20:10:58 INFO - 1748117458063 Marionette TRACE [1] MarionetteCommands actor created for window id 2
[task 2025-05-24T20:10:58.069Z] 20:10:58 INFO - 1748117458069 RemoteAgent TRACE Received observer notification domwindowopened
[task 2025-05-24T20:10:58.074Z] 20:10:58 INFO - 1748117458074 Marionette DEBUG 1 <- [1,3,null,{"value":null}]
[task 2025-05-24T20:10:58.172Z] 20:10:58 INFO - 1748117458172 Marionette DEBUG 1 -> [0,4,"Marionette:GetContext",{}]
[task 2025-05-24T20:10:58.173Z] 20:10:58 INFO - 1748117458172 Marionette DEBUG 1 <- [1,4,null,{"value":"chrome"}]
[task 2025-05-24T20:10:58.286Z] 20:10:58 INFO - 1748117458285 Marionette TRACE Received observer notification browser-delayed-startup-finished
[task 2025-05-24T20:10:58.319Z] 20:10:58 INFO - 1748117458317 Marionette DEBUG 1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2025-05-24T20:10:58.319Z] 20:10:58 INFO - 1748117458317 Marionette DEBUG 1 <- [1,5,null,{"value":null}]
[task 2025-05-24T20:10:58.359Z] 20:10:58 INFO - 1748117458353 Marionette DEBUG 1 -> [0,6,"WebDriver:GetWindowHandles",{}]
[task 2025-05-24T20:10:58.359Z] 20:10:58 INFO - 1748117458353 Marionette DEBUG 1 <- [1,6,null,["11fbe58a-1281-48ae-986a-8137f75408e3","79dbaaa2-484a-4ccc-abaa-56d706810e32"]]
[task 2025-05-24T20:10:58.359Z] 20:10:58 INFO - 1748117458358 Marionette TRACE Remoteness change detected. Set new top-level browsing context to 3
[task 2025-05-24T20:10:58.378Z] 20:10:58 INFO - 1748117458377 Marionette DEBUG 1 -> [0,7,"Marionette:SetContext",{"value":"chrome"}]
[task 2025-05-24T20:10:58.379Z] 20:10:58 INFO - 1748117458377 Marionette DEBUG 1 <- [1,7,null,{"value":null}]
[task 2025-05-24T20:10:58.381Z] 20:10:58 INFO - 1748117458380 Marionette DEBUG 1 -> [0,8,"WebDriver:ExecuteScript",{"script":"const lazy = {};\n ChromeUtils.defineESModuleGetters(lazy, {\n SessionStore: \"resource:///mod ... });\n let state = SessionStore.getCurrentState()\n return state.windows[1][\"height\"]","args":[],"newSandbox":true,"sandbox":"default","line":130,"filename":"tests/browser/components/sessionstore/test/marionette/test_restore_manually.py"}]
[task 2025-05-24T20:10:58.639Z] 20:10:58 INFO - 1748117458635 Marionette DEBUG 1 <- [1,8,null,{"value":537}]
[task 2025-05-24T20:10:58.662Z] 20:10:58 INFO - 1748117458658 Marionette DEBUG 1 -> [0,9,"Marionette:GetContext",{}]
[task 2025-05-24T20:10:58.662Z] 20:10:58 INFO - 1748117458658 Marionette DEBUG 1 <- [1,9,null,{"value":"chrome"}]
[task 2025-05-24T20:10:58.662Z] 20:10:58 INFO - 1748117458661 Marionette DEBUG 1 -> [0,10,"Marionette:SetContext",{"value":"chrome"}]
[task 2025-05-24T20:10:58.663Z] 20:10:58 INFO - 1748117458661 Marionette DEBUG 1 <- [1,10,null,{"value":null}]
[task 2025-05-24T20:10:58.665Z] 20:10:58 INFO - 1748117458665 Marionette DEBUG 1 -> [0,11,"WebDriver:TakeScreenshot",{"id":null,"full":true,"hash":false,"scroll":true}]
[task 2025-05-24T20:10:58.718Z] 20:10:58 INFO - 1748117458716 Marionette DEBUG 1 <- [1,11,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAQQCAYAAAC9RfbYAAAgAElEQVR4XuzdCbQtVXkv+nk4Hc0RUDqNLWBvVOwIik3QZ//sfTxsE2OUoF6NUWwSxcRmePXZcUUd0 ... YE4AAAAAAMCYAAQAAAGBMAAIAAADAmAAEAAAAgDEBCAAAAABjAhAAAAAAxgQgAAAAAIwJQAAAAAAYE4AAAAAAMCYAAQAAAGAsYr3aR0i+CxYAAAAASUVORK5CYII="}]
[task 2025-05-24T20:10:58.723Z] 20:10:58 INFO - 1748117458723 Marionette DEBUG 1 -> [0,12,"Marionette:SetContext",{"value":"chrome"}]
[task 2025-05-24T20:10:58.724Z] 20:10:58 INFO - 1748117458723 Marionette DEBUG 1 <- [1,12,null,{"value":null}]
[task 2025-05-24T20:10:58.727Z] 20:10:58 INFO - 1748117458726 Marionette DEBUG 1 -> [0,13,"Marionette:GetContext",{}]
[task 2025-05-24T20:10:58.727Z] 20:10:58 INFO - 1748117458726 Marionette DEBUG 1 <- [1,13,null,{"value":"chrome"}]
[task 2025-05-24T20:10:58.727Z] 20:10:58 INFO - 1748117458727 Marionette DEBUG 1 -> [0,14,"Marionette:SetContext",{"value":"content"}]
[task 2025-05-24T20:10:58.728Z] 20:10:58 INFO - 1748117458727 Marionette DEBUG 1 <- [1,14,null,{"value":null}]
[task 2025-05-24T20:10:58.729Z] 20:10:58 INFO - 1748117458729 Marionette DEBUG 1 -> [0,15,"WebDriver:GetPageSource",{}]
[task 2025-05-24T20:10:58.733Z] 20:10:58 INFO - 1748117458733 RemoteAgent TRACE WebDriverProcessData actor created for PID 8658
[task 2025-05-24T20:10:58.735Z] 20:10:58 INFO - 1748117458735 Marionette TRACE [3] MarionetteCommands actor created for window id 15032385537
[task 2025-05-24T20:10:58.737Z] 20:10:58 INFO - 1748117458737 Marionette DEBUG 1 <- [1,15,null,{"value":"<html><head><title>dolor</title></head><body></body></html>"}]
[task 2025-05-24T20:10:58.738Z] 20:10:58 INFO - 1748117458738 Marionette DEBUG 1 -> [0,16,"Marionette:SetContext",{"value":"chrome"}]
[task 2025-05-24T20:10:58.739Z] 20:10:58 INFO - 1748117458738 Marionette DEBUG 1 <- [1,16,null,{"value":null}]
[task 2025-05-24T20:10:58.765Z] 20:10:58 INFO - TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/marionette/test_restore_manually.py TestSessionRestoreManually.test_restore | AssertionError: 537 != 500 : Second window has been restored to the correct height.
[task 2025-05-24T20:10:58.765Z] 20:10:58 INFO - Traceback (most recent call last):
[task 2025-05-24T20:10:58.765Z] 20:10:58 INFO - File "/builds/worker/workspace/build/venv/lib/python3.12/site-packages/marionette_harness/marionette_test/testcases.py", line 196, in run
[task 2025-05-24T20:10:58.765Z] 20:10:58 INFO - testMethod()
[task 2025-05-24T20:10:58.765Z] 20:10:58 INFO - File "/builds/worker/workspace/build/tests/marionette/tests/browser/components/sessionstore/test/marionette/test_restore_manually.py", line 129, in test_restore
[task 2025-05-24T20:10:58.765Z] 20:10:58 INFO - self.assertEqual(
[task 2025-05-24T20:10:58.765Z] 20:10:58 INFO - TEST-INFO took 7031ms
this bug will be for tracking investigations, I will be skipping these tests in a manifest in a larger patch to green stuff up.
| Reporter | ||
Updated•5 months ago
|
Comment 1•5 months ago
|
||
The severity field is not set for this bug.
:dwalker, could you have a look please?
For more information, please visit BugBot documentation.
Flags: needinfo?(dwalker)
You need to log in
before you can comment on or make changes to this bug.
Description
•