Closed Bug 1827015 Opened 2 years ago Closed 1 year ago

Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_switch_window_chrome.py TestSwitchToWindowContent.test_switch_to_new_private_browsing_tab | marionette_driver.errors.NoSuchElementException: Failed to trigger opening a new tab: Un

Categories

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

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Depends on 1 open bug)

Details

(Keywords: intermittent-failure)

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


[task 2023-04-07T19:11:22.157Z] 19:11:22     INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_switch_window_chrome.py TestSwitchToWindowContent.test_switch_to_new_private_browsing_tab
[task 2023-04-07T19:11:22.159Z] 19:11:22     INFO -  1680894682159	Marionette	DEBUG	Accepted connection 74 from 127.0.0.1:51984
[task 2023-04-07T19:11:22.161Z] 19:11:22     INFO -  1680894682160	Marionette	DEBUG	Closed connection 73
[task 2023-04-07T19:11:22.162Z] 19:11:22     INFO -  1680894682162	Marionette	DEBUG	74 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2023-04-07T19:11:22.164Z] 19:11:22     INFO -  1680894682164	Marionette	DEBUG	Waiting for initial application window
[task 2023-04-07T19:11:22.166Z] 19:11:22     INFO -  1680894682166	RemoteAgent	TRACE	[68] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=200 waitForExplicitStart=false
[task 2023-04-07T19:11:22.168Z] 19:11:22     INFO -  1680894682167	RemoteAgent	TRACE	[68] ProgressListener Setting unload timer (200ms)
[task 2023-04-07T19:11:22.169Z] 19:11:22     INFO -  1680894682169	RemoteAgent	TRACE	[68] Document already finished loading: http://127.0.0.1:36056/test_iframe.html
[task 2023-04-07T19:11:22.170Z] 19:11:22     INFO -  1680894682170	RemoteAgent	TRACE	[68] ProgressListener Stop: has error=false
[task 2023-04-07T19:11:22.175Z] 19:11:22     INFO -  1680894682174	Marionette	DEBUG	74 <- [1,1,null,{"sessionId":"3a513570-2405-4814-9644-3f5139539837","capabilities":{"browserName":"firefox","browserVersion":"113.0a1","platformName":"linux","acceptInsecureCerts":false,"pageLoadStrategy":"normal","setWindowRect":true,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":true,"unhandledPromptBehavior":"dismiss and notify","moz:accessibilityChecks":false,"moz:buildID":"20230407182530","moz:headless":false,"moz:platformVersion":"4.4.0-1014-aws","moz:processID":28539,"moz:profile":"/tmp/tmpl28ps_yc.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2023-04-07T19:11:22.178Z] 19:11:22     INFO -  1680894682177	Marionette	DEBUG	74 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2023-04-07T19:11:22.179Z] 19:11:22     INFO -  1680894682179	Marionette	DEBUG	74 <- [1,2,null,{"value":null}]
[task 2023-04-07T19:11:22.181Z] 19:11:22     INFO -  1680894682180	Marionette	DEBUG	74 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2023-04-07T19:11:22.182Z] 19:11:22     INFO -  1680894682181	Marionette	DEBUG	74 <- [1,3,null,{"value":null}]
[task 2023-04-07T19:11:22.183Z] 19:11:22     INFO -  1680894682183	Marionette	DEBUG	74 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2023-04-07T19:11:22.184Z] 19:11:22     INFO -  1680894682184	Marionette	DEBUG	74 <- [1,4,null,{"value":null}]
[task 2023-04-07T19:11:22.186Z] 19:11:22     INFO -  1680894682186	Marionette	DEBUG	74 -> [0,5,"Marionette:GetContext",{}]
[task 2023-04-07T19:11:22.187Z] 19:11:22     INFO -  1680894682187	Marionette	DEBUG	74 <- [1,5,null,{"value":"content"}]
[task 2023-04-07T19:11:22.189Z] 19:11:22     INFO -  1680894682189	Marionette	DEBUG	74 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2023-04-07T19:11:22.190Z] 19:11:22     INFO -  1680894682190	Marionette	DEBUG	74 <- [1,6,null,{"value":null}]
[task 2023-04-07T19:11:22.192Z] 19:11:22     INFO -  1680894682192	Marionette	DEBUG	74 -> [0,7,"WebDriver:GetWindowHandle",{}]
[task 2023-04-07T19:11:22.193Z] 19:11:22     INFO -  1680894682193	Marionette	DEBUG	74 <- [1,7,null,{"value":"3b750902-8f16-4959-8239-3fd974205f0e"}]
[task 2023-04-07T19:11:22.195Z] 19:11:22     INFO -  1680894682194	Marionette	DEBUG	74 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.196Z] 19:11:22     INFO -  1680894682195	Marionette	DEBUG	74 <- [1,8,null,{"value":null}]
[task 2023-04-07T19:11:22.198Z] 19:11:22     INFO -  1680894682197	Marionette	DEBUG	74 -> [0,9,"Marionette:GetContext",{}]
[task 2023-04-07T19:11:22.199Z] 19:11:22     INFO -  1680894682198	Marionette	DEBUG	74 <- [1,9,null,{"value":"content"}]
[task 2023-04-07T19:11:22.200Z] 19:11:22     INFO -  1680894682200	Marionette	DEBUG	74 -> [0,10,"Marionette:SetContext",{"value":"chrome"}]
[task 2023-04-07T19:11:22.201Z] 19:11:22     INFO -  1680894682201	Marionette	DEBUG	74 <- [1,10,null,{"value":null}]
[task 2023-04-07T19:11:22.202Z] 19:11:22     INFO -  1680894682202	Marionette	DEBUG	74 -> [0,11,"WebDriver:GetWindowHandles",{}]
[task 2023-04-07T19:11:22.203Z] 19:11:22     INFO -  1680894682202	Marionette	DEBUG	74 <- [1,11,null,["3b750902-8f16-4959-8239-3fd974205f0e"]]
[task 2023-04-07T19:11:22.204Z] 19:11:22     INFO -  1680894682204	Marionette	DEBUG	74 -> [0,12,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.205Z] 19:11:22     INFO -  1680894682205	Marionette	DEBUG	74 <- [1,12,null,{"value":null}]
[task 2023-04-07T19:11:22.207Z] 19:11:22     INFO -  1680894682206	Marionette	DEBUG	74 -> [0,13,"Marionette:GetContext",{}]
[task 2023-04-07T19:11:22.208Z] 19:11:22     INFO -  1680894682207	Marionette	DEBUG	74 <- [1,13,null,{"value":"content"}]
[task 2023-04-07T19:11:22.209Z] 19:11:22     INFO -  1680894682209	Marionette	DEBUG	74 -> [0,14,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.210Z] 19:11:22     INFO -  1680894682210	Marionette	DEBUG	74 <- [1,14,null,{"value":null}]
[task 2023-04-07T19:11:22.211Z] 19:11:22     INFO -  1680894682211	Marionette	DEBUG	74 -> [0,15,"WebDriver:GetWindowHandle",{}]
[task 2023-04-07T19:11:22.212Z] 19:11:22     INFO -  1680894682212	Marionette	DEBUG	74 <- [1,15,null,{"value":"149b8090-e715-47dd-a0d1-9d4215cfc6db"}]
[task 2023-04-07T19:11:22.214Z] 19:11:22     INFO -  1680894682213	Marionette	DEBUG	74 -> [0,16,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.215Z] 19:11:22     INFO -  1680894682214	Marionette	DEBUG	74 <- [1,16,null,{"value":null}]
[task 2023-04-07T19:11:22.216Z] 19:11:22     INFO -  1680894682216	Marionette	DEBUG	74 -> [0,17,"Marionette:GetContext",{}]
[task 2023-04-07T19:11:22.217Z] 19:11:22     INFO -  1680894682217	Marionette	DEBUG	74 <- [1,17,null,{"value":"content"}]
[task 2023-04-07T19:11:22.218Z] 19:11:22     INFO -  1680894682218	Marionette	DEBUG	74 -> [0,18,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.219Z] 19:11:22     INFO -  1680894682219	Marionette	DEBUG	74 <- [1,18,null,{"value":null}]
[task 2023-04-07T19:11:22.221Z] 19:11:22     INFO -  1680894682220	Marionette	DEBUG	74 -> [0,19,"WebDriver:GetWindowHandles",{}]
[task 2023-04-07T19:11:22.222Z] 19:11:22     INFO -  1680894682221	Marionette	DEBUG	74 <- [1,19,null,["149b8090-e715-47dd-a0d1-9d4215cfc6db"]]
[task 2023-04-07T19:11:22.223Z] 19:11:22     INFO -  1680894682223	Marionette	DEBUG	74 -> [0,20,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.224Z] 19:11:22     INFO -  1680894682224	Marionette	DEBUG	74 <- [1,20,null,{"value":null}]
[task 2023-04-07T19:11:22.225Z] 19:11:22     INFO -  1680894682225	Marionette	DEBUG	74 -> [0,21,"Marionette:GetContext",{}]
[task 2023-04-07T19:11:22.226Z] 19:11:22     INFO -  1680894682226	Marionette	DEBUG	74 <- [1,21,null,{"value":"content"}]
[task 2023-04-07T19:11:22.228Z] 19:11:22     INFO -  1680894682227	Marionette	DEBUG	74 -> [0,22,"Marionette:SetContext",{"value":"chrome"}]
[task 2023-04-07T19:11:22.229Z] 19:11:22     INFO -  1680894682229	Marionette	DEBUG	74 <- [1,22,null,{"value":null}]
[task 2023-04-07T19:11:22.240Z] 19:11:22     INFO -  1680894682239	Marionette	DEBUG	74 -> [0,23,"WebDriver:ExecuteScript",{"script":"const { AppConstants } = ChromeUtils.importESModule(\n                  \"resource://gre/modules/AppConstants.sys.mjs\"\n         ...       if (tabBrowser.tabs[i] == tabBrowser.selectedTab) {\n                    return i;\n                  }\n                }","args":[],"newSandbox":true,"sandbox":"default","line":77,"filename":"tests/testing/marionette/harness/marionette_harness/tests/unit/test_switch_window_content.py"}]
[task 2023-04-07T19:11:22.241Z] 19:11:22     INFO -  1680894682241	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 28539
[task 2023-04-07T19:11:22.245Z] 19:11:22     INFO -  1680894682245	Marionette	TRACE	[1] MarionetteCommands actor created for window id 2
[task 2023-04-07T19:11:22.248Z] 19:11:22     INFO -  1680894682248	Marionette	DEBUG	74 <- [1,23,null,{"value":0}]
[task 2023-04-07T19:11:22.250Z] 19:11:22     INFO -  1680894682249	Marionette	DEBUG	74 -> [0,24,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.254Z] 19:11:22     INFO -  1680894682253	Marionette	DEBUG	74 <- [1,24,null,{"value":null}]
[task 2023-04-07T19:11:22.257Z] 19:11:22     INFO -  1680894682257	Marionette	DEBUG	74 -> [0,25,"Marionette:GetContext",{}]
[task 2023-04-07T19:11:22.258Z] 19:11:22     INFO -  1680894682257	Marionette	DEBUG	74 <- [1,25,null,{"value":"content"}]
[task 2023-04-07T19:11:22.259Z] 19:11:22     INFO -  1680894682258	Marionette	DEBUG	74 -> [0,26,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.259Z] 19:11:22     INFO -  1680894682259	Marionette	DEBUG	74 <- [1,26,null,{"value":null}]
[task 2023-04-07T19:11:22.260Z] 19:11:22     INFO -  1680894682260	Marionette	DEBUG	74 -> [0,27,"WebDriver:GetWindowHandles",{}]
[task 2023-04-07T19:11:22.261Z] 19:11:22     INFO -  1680894682260	Marionette	DEBUG	74 <- [1,27,null,["149b8090-e715-47dd-a0d1-9d4215cfc6db"]]
[task 2023-04-07T19:11:22.262Z] 19:11:22     INFO -  1680894682262	Marionette	DEBUG	74 -> [0,28,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.262Z] 19:11:22     INFO -  1680894682262	Marionette	DEBUG	74 <- [1,28,null,{"value":null}]
[task 2023-04-07T19:11:22.265Z] 19:11:22     INFO -  1680894682263	Marionette	DEBUG	74 -> [0,29,"WebDriver:NewWindow",{"type":"tab","focus":true,"private":false}]
[task 2023-04-07T19:11:22.275Z] 19:11:22     INFO -  1680894682274	RemoteAgent	TRACE	Received DOM event TabOpen for [object XULElement]
[task 2023-04-07T19:11:22.304Z] 19:11:22     INFO -  1680894682303	RemoteAgent	TRACE	[80] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=200 waitForExplicitStart=false
[task 2023-04-07T19:11:22.309Z] 19:11:22     INFO -  1680894682308	RemoteAgent	TRACE	[80] ProgressListener Setting unload timer (200ms)
[task 2023-04-07T19:11:22.318Z] 19:11:22     INFO -  DEBUG: Adding blocker ContentParent: id=7f6d535c5c00 for phase xpcom-will-shutdown
[task 2023-04-07T19:11:22.319Z] 19:11:22     INFO -  DEBUG: Adding blocker ContentParent: id=7f6d535c5c00 for phase profile-before-change
[task 2023-04-07T19:11:22.320Z] 19:11:22     INFO -  DEBUG: Adding blocker ContentParent: id=7f6d535c5c00 for phase quit-application-granted
[task 2023-04-07T19:11:22.342Z] 19:11:22     INFO -  DEBUG: Adding blocker ContentParent: id=7f6d625c1200 for phase xpcom-will-shutdown
[task 2023-04-07T19:11:22.345Z] 19:11:22     INFO -  DEBUG: Adding blocker ContentParent: id=7f6d625c1200 for phase profile-before-change
[task 2023-04-07T19:11:22.347Z] 19:11:22     INFO -  DEBUG: Adding blocker ContentParent: id=7f6d625c1200 for phase quit-application-granted
[task 2023-04-07T19:11:22.377Z] 19:11:22     INFO -  DEBUG: Adding blocker ContentParent: id=7f6d625bdb00 for phase xpcom-will-shutdown
[task 2023-04-07T19:11:22.379Z] 19:11:22     INFO -  DEBUG: Adding blocker ContentParent: id=7f6d625bdb00 for phase profile-before-change
[task 2023-04-07T19:11:22.381Z] 19:11:22     INFO -  DEBUG: Adding blocker ContentParent: id=7f6d625bdb00 for phase quit-application-granted
[task 2023-04-07T19:11:22.514Z] 19:11:22     INFO -  1680894682512	RemoteAgent	TRACE	[80] ProgressListener No navigation detected: about:blank
[task 2023-04-07T19:11:22.515Z] 19:11:22     INFO -  1680894682512	RemoteAgent	TRACE	[80] ProgressListener Stop: has error=false
[task 2023-04-07T19:11:22.517Z] 19:11:22     INFO -  1680894682516	Marionette	DEBUG	74 <- [1,29,null,{"handle":"8fc5c3e5-3e4f-4a5e-8d9e-2ff2af2a6923","type":"tab"}]
[task 2023-04-07T19:11:22.520Z] 19:11:22     INFO -  1680894682520	Marionette	DEBUG	74 -> [0,30,"Marionette:GetContext",{}]
[task 2023-04-07T19:11:22.522Z] 19:11:22     INFO -  1680894682521	Marionette	DEBUG	74 <- [1,30,null,{"value":"content"}]
[task 2023-04-07T19:11:22.524Z] 19:11:22     INFO -  1680894682523	Marionette	DEBUG	74 -> [0,31,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.525Z] 19:11:22     INFO -  1680894682524	Marionette	DEBUG	74 <- [1,31,null,{"value":null}]
[task 2023-04-07T19:11:22.526Z] 19:11:22     INFO -  1680894682526	Marionette	DEBUG	74 -> [0,32,"WebDriver:GetWindowHandles",{}]
[task 2023-04-07T19:11:22.528Z] 19:11:22     INFO -  1680894682527	Marionette	DEBUG	74 <- [1,32,null,["149b8090-e715-47dd-a0d1-9d4215cfc6db","8fc5c3e5-3e4f-4a5e-8d9e-2ff2af2a6923"]]
[task 2023-04-07T19:11:22.532Z] 19:11:22     INFO -  1680894682531	Marionette	DEBUG	74 -> [0,33,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.533Z] 19:11:22     INFO -  1680894682532	Marionette	DEBUG	74 <- [1,33,null,{"value":null}]
[task 2023-04-07T19:11:22.537Z] 19:11:22     INFO -  1680894682537	Marionette	DEBUG	74 -> [0,34,"Marionette:GetContext",{}]
[task 2023-04-07T19:11:22.539Z] 19:11:22     INFO -  1680894682538	Marionette	DEBUG	74 <- [1,34,null,{"value":"content"}]
[task 2023-04-07T19:11:22.541Z] 19:11:22     INFO -  1680894682541	Marionette	DEBUG	74 -> [0,35,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.542Z] 19:11:22     INFO -  1680894682542	Marionette	DEBUG	74 <- [1,35,null,{"value":null}]
[task 2023-04-07T19:11:22.544Z] 19:11:22     INFO -  1680894682544	Marionette	DEBUG	74 -> [0,36,"WebDriver:GetWindowHandles",{}]
[task 2023-04-07T19:11:22.545Z] 19:11:22     INFO -  1680894682545	Marionette	DEBUG	74 <- [1,36,null,["149b8090-e715-47dd-a0d1-9d4215cfc6db","8fc5c3e5-3e4f-4a5e-8d9e-2ff2af2a6923"]]
[task 2023-04-07T19:11:22.558Z] 19:11:22     INFO -  1680894682558	Marionette	DEBUG	74 -> [0,37,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.562Z] 19:11:22     INFO -  1680894682561	Marionette	DEBUG	74 <- [1,37,null,{"value":null}]
[task 2023-04-07T19:11:22.564Z] 19:11:22     INFO -  1680894682563	Marionette	DEBUG	74 -> [0,38,"WebDriver:SwitchToWindow",{"handle":"8fc5c3e5-3e4f-4a5e-8d9e-2ff2af2a6923","focus":true}]
[task 2023-04-07T19:11:22.588Z] 19:11:22     INFO -  1680894682587	Marionette	DEBUG	74 <- [1,38,null,{"value":null}]
[task 2023-04-07T19:11:22.603Z] 19:11:22     INFO -  1680894682603	Marionette	DEBUG	74 -> [0,39,"Marionette:GetContext",{}]
[task 2023-04-07T19:11:22.607Z] 19:11:22     INFO -  1680894682606	Marionette	DEBUG	74 <- [1,39,null,{"value":"content"}]
[task 2023-04-07T19:11:22.612Z] 19:11:22     INFO -  1680894682611	Marionette	DEBUG	74 -> [0,40,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.613Z] 19:11:22     INFO -  1680894682613	Marionette	DEBUG	74 <- [1,40,null,{"value":null}]
[task 2023-04-07T19:11:22.673Z] 19:11:22     INFO -  1680894682672	Marionette	DEBUG	74 -> [0,41,"WebDriver:Navigate",{"url":"about:privatebrowsing"}]
[task 2023-04-07T19:11:22.686Z] 19:11:22     INFO -  1680894682684	Marionette	TRACE	[80] Received event pagehide for about:blank
[task 2023-04-07T19:11:22.743Z] 19:11:22     INFO -  1680894682743	Marionette	TRACE	[80] Received event DOMContentLoaded for about:blank
[task 2023-04-07T19:11:22.746Z] 19:11:22     INFO -  1680894682745	Marionette	TRACE	[80] Received event pageshow for about:blank
[task 2023-04-07T19:11:22.748Z] 19:11:22     INFO -  1680894682748	Marionette	DEBUG	74 <- [1,41,null,{"value":null}]
[task 2023-04-07T19:11:22.769Z] 19:11:22     INFO -  1680894682768	Marionette	DEBUG	74 -> [0,42,"Marionette:GetContext",{}]
[task 2023-04-07T19:11:22.771Z] 19:11:22     INFO -  1680894682770	Marionette	DEBUG	74 <- [1,42,null,{"value":"content"}]
[task 2023-04-07T19:11:22.792Z] 19:11:22     INFO -  1680894682791	Marionette	TRACE	Remoteness change detected. Set new top-level browsing context to 81
[task 2023-04-07T19:11:22.818Z] 19:11:22     INFO -  1680894682818	Marionette	DEBUG	74 -> [0,43,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.820Z] 19:11:22     INFO -  1680894682819	Marionette	DEBUG	74 <- [1,43,null,{"value":null}]
[task 2023-04-07T19:11:22.833Z] 19:11:22     INFO -  DEBUG: Adding blocker Transaction (21) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-04-07T19:11:22.853Z] 19:11:22     INFO -  1680894682852	Marionette	DEBUG	74 -> [0,44,"WebDriver:GetWindowHandles",{}]
[task 2023-04-07T19:11:22.855Z] 19:11:22     INFO -  1680894682854	Marionette	DEBUG	74 <- [1,44,null,["149b8090-e715-47dd-a0d1-9d4215cfc6db","8fc5c3e5-3e4f-4a5e-8d9e-2ff2af2a6923"]]
[task 2023-04-07T19:11:22.858Z] 19:11:22     INFO -  DEBUG: Completed blocker Transaction (21) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-04-07T19:11:22.860Z] 19:11:22     INFO -  1680894682860	Marionette	DEBUG	74 -> [0,45,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.863Z] 19:11:22     INFO -  1680894682862	Marionette	DEBUG	74 <- [1,45,null,{"value":null}]
[task 2023-04-07T19:11:22.865Z] 19:11:22     INFO -  1680894682864	Marionette	DEBUG	74 -> [0,46,"Marionette:GetContext",{}]
[task 2023-04-07T19:11:22.866Z] 19:11:22     INFO -  1680894682865	Marionette	DEBUG	74 <- [1,46,null,{"value":"content"}]
[task 2023-04-07T19:11:22.868Z] 19:11:22     INFO -  1680894682868	Marionette	DEBUG	74 -> [0,47,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.870Z] 19:11:22     INFO -  1680894682870	Marionette	DEBUG	74 <- [1,47,null,{"value":null}]
[task 2023-04-07T19:11:22.874Z] 19:11:22     INFO -  1680894682873	Marionette	DEBUG	74 -> [0,48,"WebDriver:FindElement",{"value":"startPrivateBrowsing","using":"id"}]
[task 2023-04-07T19:11:22.880Z] 19:11:22     INFO -  1680894682879	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 28610
[task 2023-04-07T19:11:22.883Z] 19:11:22     INFO -  1680894682883	Marionette	TRACE	[81] MarionetteCommands actor created for window id 2147483649
[task 2023-04-07T19:11:22.899Z] 19:11:22     INFO -  1680894682899	Marionette	DEBUG	74 <- [1,48,{"error":"no such element","message":"Unable to locate element: startPrivateBrowsing","stacktrace":"RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8\nWebDriverError@chrome://remote/content/shared/webdriver/Er ... ote/content/shared/webdriver/Errors.sys.mjs:395:5\nelement.find/</<@chrome://remote/content/marionette/element.sys.mjs:134:16\n"},null]
[task 2023-04-07T19:11:22.902Z] 19:11:22     INFO -  1680894682901	Marionette	DEBUG	74 -> [0,49,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.903Z] 19:11:22     INFO -  1680894682903	Marionette	DEBUG	74 <- [1,49,null,{"value":null}]
[task 2023-04-07T19:11:22.905Z] 19:11:22     INFO -  1680894682905	Marionette	DEBUG	74 -> [0,50,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:22.906Z] 19:11:22     INFO -  1680894682906	Marionette	DEBUG	74 <- [1,50,null,{"value":null}]
[task 2023-04-07T19:11:22.910Z] 19:11:22     INFO -  1680894682910	Marionette	DEBUG	74 -> [0,51,"Marionette:GetContext",{}]
[task 2023-04-07T19:11:22.912Z] 19:11:22     INFO -  1680894682911	Marionette	DEBUG	74 <- [1,51,null,{"value":"content"}]
[task 2023-04-07T19:11:22.913Z] 19:11:22     INFO -  1680894682913	Marionette	DEBUG	74 -> [0,52,"Marionette:SetContext",{"value":"chrome"}]
[task 2023-04-07T19:11:22.914Z] 19:11:22     INFO -  1680894682914	Marionette	DEBUG	74 <- [1,52,null,{"value":null}]
[task 2023-04-07T19:11:22.916Z] 19:11:22     INFO -  1680894682916	Marionette	DEBUG	74 -> [0,53,"WebDriver:TakeScreenshot",{"id":null,"full":true,"hash":false,"scroll":true}]
[task 2023-04-07T19:11:23.010Z] 19:11:23     INFO -  1680894683009	Marionette	DEBUG	74 <- [1,53,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAQQCAYAAAC9RfbYAAAgAElEQVR4XuzdCbQldX0n8P97vdALyI6CJiAKRo0mZtBkzChKxoyQqJmYk1ESMWMSEVziAEk0izMnm ... QIECAAAECBAgQIECAAAECBAgEBRyAwdFUJkCAAAECBAgQIECAAAECBAgQIPAKOABfKTkCBAgQIECAAAECBAgQIECAAAECQYEBpZ1ZAjUI+JcAAAAASUVORK5CYII="}]
[task 2023-04-07T19:11:23.026Z] 19:11:23     INFO -  1680894683025	Marionette	DEBUG	74 -> [0,54,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:23.028Z] 19:11:23     INFO -  1680894683027	Marionette	DEBUG	74 <- [1,54,null,{"value":null}]
[task 2023-04-07T19:11:23.031Z] 19:11:23     INFO -  1680894683030	Marionette	DEBUG	74 -> [0,55,"Marionette:GetContext",{}]
[task 2023-04-07T19:11:23.032Z] 19:11:23     INFO -  1680894683032	Marionette	DEBUG	74 <- [1,55,null,{"value":"content"}]
[task 2023-04-07T19:11:23.042Z] 19:11:23     INFO -  1680894683041	Marionette	DEBUG	74 -> [0,56,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:23.043Z] 19:11:23     INFO -  1680894683043	Marionette	DEBUG	74 <- [1,56,null,{"value":null}]
[task 2023-04-07T19:11:23.045Z] 19:11:23     INFO -  1680894683045	Marionette	DEBUG	74 -> [0,57,"WebDriver:GetPageSource",{}]
[task 2023-04-07T19:11:23.048Z] 19:11:23     INFO -  1680894683047	Marionette	DEBUG	74 <- [1,57,null,{"value":"<html xmlns=\"http://www.w3.org/1999/xhtml\" class=\"normal\" dir=\"ltr\" lang=\"en-US\"><head>\n    <meta charset=\"utf-8\">\n    <meta  ... ate-browsing-promo-close-button\" id=\"dismiss-btn\" class=\"promo-dismiss\" title=\"Close\"></button>\n    </div>\n  \n\n</body></html>"}]
[task 2023-04-07T19:11:23.050Z] 19:11:23     INFO -  1680894683049	Marionette	DEBUG	74 -> [0,58,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:23.051Z] 19:11:23     INFO -  1680894683051	Marionette	DEBUG	74 <- [1,58,null,{"value":null}]
[task 2023-04-07T19:11:23.171Z] 19:11:23     INFO - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_switch_window_chrome.py TestSwitchToWindowContent.test_switch_to_new_private_browsing_tab | marionette_driver.errors.NoSuchElementException: Failed to trigger opening a new tab: Unable to locate element: startPrivateBrowsing
[task 2023-04-07T19:11:23.171Z] 19:11:23     INFO - stacktrace:
[task 2023-04-07T19:11:23.171Z] 19:11:23     INFO - 	RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8
[task 2023-04-07T19:11:23.171Z] 19:11:23     INFO - 	WebDriverError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:183:5
[task 2023-04-07T19:11:23.173Z] 19:11:23     INFO - 	NoSuchElementError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:395:5
[task 2023-04-07T19:11:23.174Z] 19:11:23     INFO - 	element.find/</<@chrome://remote/content/marionette/element.sys.mjs:134:16
[task 2023-04-07T19:11:23.176Z] 19:11:23     INFO - Traceback (most recent call last):
[task 2023-04-07T19:11:23.176Z] 19:11:23     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_harness/marionette_test/testcases.py", line 198, in run
[task 2023-04-07T19:11:23.177Z] 19:11:23     INFO -     testMethod()
[task 2023-04-07T19:11:23.178Z] 19:11:23     INFO -   File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_switch_window_content.py", line 234, in test_switch_to_new_private_browsing_tab
[task 2023-04-07T19:11:23.178Z] 19:11:23     INFO -     new_pb_tab = self.open_tab(open_private_browsing_window_firefox)
[task 2023-04-07T19:11:23.179Z] 19:11:23     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_harness/runner/mixins/window_manager.py", line 77, in open_tab
[task 2023-04-07T19:11:23.180Z] 19:11:23     INFO -     tb,
[task 2023-04-07T19:11:23.180Z] 19:11:23     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/six.py", line 702, in reraise
[task 2023-04-07T19:11:23.181Z] 19:11:23     INFO -     raise value.with_traceback(tb)
[task 2023-04-07T19:11:23.182Z] 19:11:23     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_harness/runner/mixins/window_manager.py", line 63, in open_tab
[task 2023-04-07T19:11:23.182Z] 19:11:23     INFO -     callback()
[task 2023-04-07T19:11:23.183Z] 19:11:23     INFO -   File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_switch_window_content.py", line 223, in open_private_browsing_window_firefox
[task 2023-04-07T19:11:23.183Z] 19:11:23     INFO -     self.marionette.find_element(By.ID, "startPrivateBrowsing").click()
[task 2023-04-07T19:11:23.184Z] 19:11:23     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 1883, in find_element
[task 2023-04-07T19:11:23.184Z] 19:11:23     INFO -     return self._send_message("WebDriver:FindElement", body, key="value")
[task 2023-04-07T19:11:23.185Z] 19:11:23     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/decorators.py", line 24, in _
[task 2023-04-07T19:11:23.185Z] 19:11:23     INFO -     return func(*args, **kwargs)
[task 2023-04-07T19:11:23.186Z] 19:11:23     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 703, in _send_message
[task 2023-04-07T19:11:23.186Z] 19:11:23     INFO -     self._handle_error(err)
[task 2023-04-07T19:11:23.186Z] 19:11:23     INFO -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 715, in _handle_error
[task 2023-04-07T19:11:23.187Z] 19:11:23     INFO -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2023-04-07T19:11:23.187Z] 19:11:23     INFO - TEST-INFO took 896ms
[task 2023-04-07T19:11:23.189Z] 19:11:23     INFO -  1680894683063	Marionette	DEBUG	74 -> [0,59,"Marionette:GetContext",{}]
[task 2023-04-07T19:11:23.192Z] 19:11:23     INFO -  1680894683063	Marionette	DEBUG	74 <- [1,59,null,{"value":"content"}]
[task 2023-04-07T19:11:23.195Z] 19:11:23     INFO -  1680894683070	Marionette	DEBUG	74 -> [0,60,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:23.196Z] 19:11:23     INFO -  1680894683070	Marionette	DEBUG	74 <- [1,60,null,{"value":null}]
[task 2023-04-07T19:11:23.197Z] 19:11:23     INFO -  1680894683080	Marionette	DEBUG	74 -> [0,61,"WebDriver:GetWindowHandles",{}]
[task 2023-04-07T19:11:23.198Z] 19:11:23     INFO -  1680894683080	Marionette	DEBUG	74 <- [1,61,null,["149b8090-e715-47dd-a0d1-9d4215cfc6db","8fc5c3e5-3e4f-4a5e-8d9e-2ff2af2a6923"]]
[task 2023-04-07T19:11:23.199Z] 19:11:23     INFO -  1680894683081	Marionette	DEBUG	74 -> [0,62,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:23.201Z] 19:11:23     INFO -  1680894683081	Marionette	DEBUG	74 <- [1,62,null,{"value":null}]
[task 2023-04-07T19:11:23.202Z] 19:11:23     INFO -  1680894683082	Marionette	DEBUG	74 -> [0,63,"WebDriver:SwitchToWindow",{"handle":"8fc5c3e5-3e4f-4a5e-8d9e-2ff2af2a6923","focus":true}]
[task 2023-04-07T19:11:23.203Z] 19:11:23     INFO -  1680894683082	Marionette	DEBUG	74 <- [1,63,null,{"value":null}]
[task 2023-04-07T19:11:23.204Z] 19:11:23     INFO -  1680894683083	Marionette	DEBUG	74 -> [0,64,"WebDriver:CloseWindow",{}]
[task 2023-04-07T19:11:23.206Z] 19:11:23     INFO -  1680894683102	RemoteAgent	TRACE	Received DOM event TabClose for [object XULElement]
[task 2023-04-07T19:11:23.207Z] 19:11:23     INFO -  1680894683118	Marionette	TRACE	[81] MarionetteCommands actor destroyed for window id 2147483649
[task 2023-04-07T19:11:23.208Z] 19:11:23     INFO -  1680894683147	Marionette	TRACE	Received observer notification message-manager-disconnect
[task 2023-04-07T19:11:23.209Z] 19:11:23     INFO -  1680894683148	Marionette	TRACE	Received observer notification message-manager-disconnect
[task 2023-04-07T19:11:23.210Z] 19:11:23     INFO -  1680894683148	Marionette	DEBUG	74 <- [1,64,null,["149b8090-e715-47dd-a0d1-9d4215cfc6db"]]
[task 2023-04-07T19:11:23.212Z] 19:11:23     INFO -  1680894683161	Marionette	DEBUG	74 -> [0,65,"WebDriver:SwitchToWindow",{"handle":"149b8090-e715-47dd-a0d1-9d4215cfc6db","focus":true}]
[task 2023-04-07T19:11:23.214Z] 19:11:23     INFO -  1680894683164	Marionette	DEBUG	74 <- [1,65,null,{"value":null}]
[task 2023-04-07T19:11:23.217Z] 19:11:23     INFO -  1680894683167	Marionette	DEBUG	74 -> [0,66,"Marionette:GetContext",{}]
[task 2023-04-07T19:11:23.218Z] 19:11:23     INFO -  1680894683167	Marionette	DEBUG	74 <- [1,66,null,{"value":"content"}]
[task 2023-04-07T19:11:23.220Z] 19:11:23     INFO -  1680894683169	Marionette	DEBUG	74 -> [0,67,"Marionette:SetContext",{"value":"chrome"}]
[task 2023-04-07T19:11:23.222Z] 19:11:23     INFO -  1680894683169	Marionette	DEBUG	74 <- [1,67,null,{"value":null}]
[task 2023-04-07T19:11:23.224Z] 19:11:23     INFO -  1680894683200	Marionette	DEBUG	74 -> [0,68,"WebDriver:GetWindowHandles",{}]
[task 2023-04-07T19:11:23.225Z] 19:11:23     INFO -  1680894683203	Marionette	DEBUG	74 <- [1,68,null,["3b750902-8f16-4959-8239-3fd974205f0e"]]
[task 2023-04-07T19:11:23.227Z] 19:11:23     INFO -  1680894683206	Marionette	DEBUG	74 -> [0,69,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:23.228Z] 19:11:23     INFO -  1680894683209	Marionette	DEBUG	74 <- [1,69,null,{"value":null}]
[task 2023-04-07T19:11:23.230Z] 19:11:23     INFO -  1680894683212	Marionette	DEBUG	74 -> [0,70,"Marionette:GetContext",{}]
[task 2023-04-07T19:11:23.232Z] 19:11:23     INFO -  1680894683216	Marionette	DEBUG	74 <- [1,70,null,{"value":"content"}]
[task 2023-04-07T19:11:23.233Z] 19:11:23     INFO -  1680894683222	Marionette	DEBUG	74 -> [0,71,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:23.235Z] 19:11:23     INFO -  1680894683225	Marionette	DEBUG	74 <- [1,71,null,{"value":null}]
[task 2023-04-07T19:11:23.236Z] 19:11:23     INFO -  1680894683230	Marionette	DEBUG	74 -> [0,72,"WebDriver:GetWindowHandles",{}]
[task 2023-04-07T19:11:23.238Z] 19:11:23     INFO -  1680894683237	Marionette	DEBUG	74 <- [1,72,null,["149b8090-e715-47dd-a0d1-9d4215cfc6db"]]
[task 2023-04-07T19:11:23.240Z] 19:11:23     INFO -  1680894683240	Marionette	DEBUG	74 -> [0,73,"Marionette:SetContext",{"value":"content"}]
[task 2023-04-07T19:11:23.242Z] 19:11:23     INFO -  1680894683241	Marionette	DEBUG	74 <- [1,73,null,{"value":null}]
[task 2023-04-07T19:11:23.244Z] 19:11:23     INFO -  1680894683244	Marionette	DEBUG	74 -> [0,74,"WebDriver:DeleteSession",{}]
[task 2023-04-07T19:11:23.246Z] 19:11:23     INFO -  1680894683246	Marionette	TRACE	[1] MarionetteCommands actor destroyed for window id 2
[task 2023-04-07T19:11:23.252Z] 19:11:23     INFO -  1680894683251	Marionette	DEBUG	74 <- [1,74,null,{"value":null}]
[task 2023-04-07T19:11:23.269Z] 19:11:23     INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_switch_window_chrome.py TestSwitchToWindowContent.test_switch_to_unloaded_tab

The problem here is that about:blank has been loaded instead of about:privatebrowsing and as such we cannot find the Open a Private Window button:

https://treeherder.mozilla.org/logviewer?job_id=411718310&repo=autoland&lineNumber=80072-80075

[task 2023-04-07T19:11:22.673Z] 19:11:22     INFO -  1680894682672	Marionette	DEBUG	74 -> [0,41,"WebDriver:Navigate",{"url":"about:privatebrowsing"}]
[task 2023-04-07T19:11:22.686Z] 19:11:22     INFO -  1680894682684	Marionette	TRACE	[80] Received event pagehide for about:blank
[task 2023-04-07T19:11:22.743Z] 19:11:22     INFO -  1680894682743	Marionette	TRACE	[80] Received event DOMContentLoaded for about:blank
[task 2023-04-07T19:11:22.746Z] 19:11:22     INFO -  1680894682745	Marionette	TRACE	[80] Received event pageshow for about:blank

Bug 1664165 should help here.

Depends on: 1664165
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.