Closed Bug 1762505 Opened 2 years ago Closed 1 year ago

Intermittent [tier 2] testing/firefox-ui/tests/functional/security/test_ssl_status_after_restart.py TestSSLStatusAfterRestart.test_ssl_status_after_restart | marionette_driver.errors.UnknownException: Reached error page: about:neterror?e

Categories

(Testing :: Firefox UI Tests, defect, P5)

defect

Tracking

(firefox-esr91 disabled, firefox98 wontfix, firefox99 disabled, firefox100 disabled, firefox108 disabled, firefox109 disabled, firefox110 fixed)

RESOLVED FIXED
110 Branch
Tracking Status
firefox-esr91 --- disabled
firefox98 --- wontfix
firefox99 --- disabled
firefox100 --- disabled
firefox108 --- disabled
firefox109 --- disabled
firefox110 --- fixed

People

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

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell disabled])

Attachments

(2 files)

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


[task 2022-04-01T01:00:57.006Z] 01:00:57     INFO - TEST-START | testing/firefox-ui/tests/functional/security/test_ssl_status_after_restart.py TestSSLStatusAfterRestart.test_ssl_status_after_restart
[task 2022-04-01T01:00:57.007Z] 01:00:57     INFO -  1648774857007	Marionette	DEBUG	Accepted connection 5 from 127.0.0.1:49904
[task 2022-04-01T01:00:57.024Z] 01:00:57     INFO -  1648774857024	Marionette	DEBUG	5 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-04-01T01:00:57.026Z] 01:00:57     INFO -  1648774857026	RemoteAgent	TRACE	[24] Document already finished loading: about:blank
[task 2022-04-01T01:00:57.027Z] 01:00:57     INFO -  1648774857026	Marionette	DEBUG	5 <- [1,1,null,{"sessionId":"8623517b-e1f7-4fa6-9481-64407bfd0841","capabilities":{"browserName":"firefox","browserVersion":"100.0 ... e.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2022-04-01T01:00:57.027Z] 01:00:57     INFO -  1648774857027	Marionette	DEBUG	5 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2022-04-01T01:00:57.027Z] 01:00:57     INFO -  1648774857028	Marionette	DEBUG	5 <- [1,2,null,{"value":null}]
[task 2022-04-01T01:00:57.028Z] 01:00:57     INFO -  1648774857028	Marionette	DEBUG	5 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2022-04-01T01:00:57.029Z] 01:00:57     INFO -  1648774857029	Marionette	DEBUG	5 <- [1,3,null,{"value":null}]
[task 2022-04-01T01:00:57.029Z] 01:00:57     INFO -  1648774857029	Marionette	DEBUG	5 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2022-04-01T01:00:57.029Z] 01:00:57     INFO -  1648774857030	Marionette	DEBUG	5 <- [1,4,null,{"value":null}]
[task 2022-04-01T01:00:57.031Z] 01:00:57     INFO -  1648774857031	Marionette	DEBUG	5 -> [0,5,"Marionette:GetContext",{}]
[task 2022-04-01T01:00:57.031Z] 01:00:57     INFO -  1648774857031	Marionette	DEBUG	5 <- [1,5,null,{"value":"content"}]
[task 2022-04-01T01:00:57.031Z] 01:00:57     INFO -  1648774857032	Marionette	DEBUG	5 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:00:57.032Z] 01:00:57     INFO -  1648774857032	Marionette	DEBUG	5 <- [1,6,null,{"value":null}]
[task 2022-04-01T01:00:57.032Z] 01:00:57     INFO -  1648774857032	Marionette	DEBUG	5 -> [0,7,"WebDriver:GetWindowHandle",{}]
[task 2022-04-01T01:00:57.032Z] 01:00:57     INFO -  1648774857033	Marionette	DEBUG	5 <- [1,7,null,{"value":"2c473ade-0632-40ff-8049-0e9d759ec2d6"}]
[task 2022-04-01T01:00:57.033Z] 01:00:57     INFO -  1648774857033	Marionette	DEBUG	5 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-01T01:00:57.033Z] 01:00:57     INFO -  1648774857033	Marionette	DEBUG	5 <- [1,8,null,{"value":null}]
[task 2022-04-01T01:00:57.033Z] 01:00:57     INFO -  1648774857034	Marionette	DEBUG	5 -> [0,9,"Marionette:GetContext",{}]
[task 2022-04-01T01:00:57.034Z] 01:00:57     INFO -  1648774857034	Marionette	DEBUG	5 <- [1,9,null,{"value":"content"}]
[task 2022-04-01T01:00:57.034Z] 01:00:57     INFO -  1648774857034	Marionette	DEBUG	5 -> [0,10,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:00:57.034Z] 01:00:57     INFO -  1648774857035	Marionette	DEBUG	5 <- [1,10,null,{"value":null}]
[task 2022-04-01T01:00:57.035Z] 01:00:57     INFO -  1648774857035	Marionette	DEBUG	5 -> [0,11,"WebDriver:GetWindowHandles",{}]
[task 2022-04-01T01:00:57.035Z] 01:00:57     INFO -  1648774857035	Marionette	DEBUG	5 <- [1,11,null,["2c473ade-0632-40ff-8049-0e9d759ec2d6"]]
[task 2022-04-01T01:00:57.036Z] 01:00:57     INFO -  1648774857036	Marionette	DEBUG	5 -> [0,12,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-01T01:00:57.036Z] 01:00:57     INFO -  1648774857036	Marionette	DEBUG	5 <- [1,12,null,{"value":null}]
[task 2022-04-01T01:00:57.036Z] 01:00:57     INFO -  1648774857037	Marionette	DEBUG	5 -> [0,13,"Marionette:GetContext",{}]
[task 2022-04-01T01:00:57.037Z] 01:00:57     INFO -  1648774857037	Marionette	DEBUG	5 <- [1,13,null,{"value":"content"}]
[task 2022-04-01T01:00:57.037Z] 01:00:57     INFO -  1648774857037	Marionette	DEBUG	5 -> [0,14,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-01T01:00:57.037Z] 01:00:57     INFO -  1648774857038	Marionette	DEBUG	5 <- [1,14,null,{"value":null}]
[task 2022-04-01T01:00:57.038Z] 01:00:57     INFO -  1648774857038	Marionette	DEBUG	5 -> [0,15,"WebDriver:GetWindowHandle",{}]
[task 2022-04-01T01:00:57.038Z] 01:00:57     INFO -  1648774857038	Marionette	DEBUG	5 <- [1,15,null,{"value":"71bad4f4-4b3e-4988-9ea6-a7d77b8d77fd"}]
[task 2022-04-01T01:00:57.039Z] 01:00:57     INFO -  1648774857039	Marionette	DEBUG	5 -> [0,16,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-01T01:00:57.039Z] 01:00:57     INFO -  1648774857039	Marionette	DEBUG	5 <- [1,16,null,{"value":null}]
[task 2022-04-01T01:00:57.039Z] 01:00:57     INFO -  1648774857040	Marionette	DEBUG	5 -> [0,17,"Marionette:GetContext",{}]
[task 2022-04-01T01:00:57.040Z] 01:00:57     INFO -  1648774857040	Marionette	DEBUG	5 <- [1,17,null,{"value":"content"}]
[task 2022-04-01T01:00:57.041Z] 01:00:57     INFO -  1648774857041	Marionette	DEBUG	5 -> [0,18,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-01T01:00:57.041Z] 01:00:57     INFO -  1648774857041	Marionette	DEBUG	5 <- [1,18,null,{"value":null}]
[task 2022-04-01T01:00:57.041Z] 01:00:57     INFO -  1648774857042	Marionette	DEBUG	5 -> [0,19,"WebDriver:GetWindowHandles",{}]
[task 2022-04-01T01:00:57.042Z] 01:00:57     INFO -  1648774857042	Marionette	DEBUG	5 <- [1,19,null,["71bad4f4-4b3e-4988-9ea6-a7d77b8d77fd"]]
[task 2022-04-01T01:00:57.042Z] 01:00:57     INFO -  1648774857042	Marionette	DEBUG	5 -> [0,20,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-01T01:00:57.042Z] 01:00:57     INFO -  1648774857042	Marionette	DEBUG	5 <- [1,20,null,{"value":null}]
[task 2022-04-01T01:00:57.043Z] 01:00:57     INFO -  1648774857043	Marionette	DEBUG	5 -> [0,21,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:00:57.043Z] 01:00:57     INFO -  1648774857043	Marionette	DEBUG	5 <- [1,21,null,{"value":null}]
[task 2022-04-01T01:00:57.043Z] 01:00:57     INFO -  1648774857044	Marionette	DEBUG	5 -> [0,22,"Marionette:GetContext",{}]
[task 2022-04-01T01:00:57.044Z] 01:00:57     INFO -  1648774857044	Marionette	DEBUG	5 <- [1,22,null,{"value":"chrome"}]
[task 2022-04-01T01:00:57.044Z] 01:00:57     INFO -  1648774857044	Marionette	DEBUG	5 -> [0,23,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:00:57.044Z] 01:00:57     INFO -  1648774857044	Marionette	DEBUG	5 <- [1,23,null,{"value":null}]
[task 2022-04-01T01:00:57.046Z] 01:00:57     INFO -  1648774857046	Marionette	DEBUG	5 -> [0,24,"WebDriver:ExecuteScript",{"script":"Components.utils.import(\"resource://gre/modules/Preferences.jsm\");\n\n           ... efault","line":831,"filename":"Z:\\task_164876914258102\\build\\venv\\lib\\site-packages\\marionette_driver\\marionette.py"}]
[task 2022-04-01T01:00:57.046Z] 01:00:57     INFO -  1648774857046	Marionette	TRACE	[7] MarionetteCommands actor created for window id 2
[task 2022-04-01T01:00:57.049Z] 01:00:57     INFO -  1648774857049	Marionette	DEBUG	5 <- [1,24,null,{"value":null}]
[task 2022-04-01T01:00:57.050Z] 01:00:57     INFO -  1648774857050	Marionette	DEBUG	5 -> [0,25,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:00:57.050Z] 01:00:57     INFO -  1648774857050	Marionette	DEBUG	5 <- [1,25,null,{"value":null}]
[task 2022-04-01T01:00:57.050Z] 01:00:57     INFO -  1648774857051	Marionette	DEBUG	5 -> [0,26,"Marionette:GetContext",{}]
[task 2022-04-01T01:00:57.051Z] 01:00:57     INFO -  1648774857051	Marionette	DEBUG	5 <- [1,26,null,{"value":"chrome"}]
[task 2022-04-01T01:00:57.051Z] 01:00:57     INFO -  1648774857051	Marionette	DEBUG	5 -> [0,27,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:00:57.051Z] 01:00:57     INFO -  1648774857052	Marionette	DEBUG	5 <- [1,27,null,{"value":null}]
[task 2022-04-01T01:00:57.053Z] 01:00:57     INFO -  1648774857053	Marionette	DEBUG	5 -> [0,28,"WebDriver:ExecuteScript",{"script":"Components.utils.import(\"resource://gre/modules/Preferences.jsm\");\n\n           ... efault","line":831,"filename":"Z:\\task_164876914258102\\build\\venv\\lib\\site-packages\\marionette_driver\\marionette.py"}]
[task 2022-04-01T01:00:57.054Z] 01:00:57     INFO -  1648774857054	Marionette	DEBUG	5 <- [1,28,null,{"value":null}]
[task 2022-04-01T01:00:57.055Z] 01:00:57     INFO -  1648774857055	Marionette	DEBUG	5 -> [0,29,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:00:57.055Z] 01:00:57     INFO -  1648774857055	Marionette	DEBUG	5 <- [1,29,null,{"value":null}]
[task 2022-04-01T01:00:57.055Z] 01:00:57     INFO -  1648774857056	Marionette	DEBUG	5 -> [0,30,"Marionette:GetContext",{}]
[task 2022-04-01T01:00:57.056Z] 01:00:57     INFO -  1648774857056	Marionette	DEBUG	5 <- [1,30,null,{"value":"chrome"}]
[task 2022-04-01T01:00:57.056Z] 01:00:57     INFO -  1648774857056	Marionette	DEBUG	5 -> [0,31,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-01T01:00:57.056Z] 01:00:57     INFO -  1648774857056	Marionette	DEBUG	5 <- [1,31,null,{"value":null}]
[task 2022-04-01T01:00:57.057Z] 01:00:57     INFO -  1648774857057	Marionette	DEBUG	5 -> [0,32,"WebDriver:Navigate",{"url":"https://sha512.badssl.com/"}]
[task 2022-04-01T01:00:57.059Z] 01:00:57     INFO -  1648774857059	Marionette	TRACE	[24] Received event beforeunload for about:blank
[task 2022-04-01T01:01:17.700Z] 01:01:17     INFO -  1648774877700	Marionette	TRACE	[24] Received event beforeunload for about:blank
[task 2022-04-01T01:01:17.709Z] 01:01:17     INFO -  1648774877709	Marionette	TRACE	[24] Received event pagehide for about:blank
[task 2022-04-01T01:01:17.800Z] 01:01:17     INFO -  1648774877804	Marionette	TRACE	[24] Received event DOMContentLoaded for about:neterror?e=dnsNotFound&u=https%3A//sha512.badssl.com/&c=UTF-8&d=We%20can%E2%80%99t%20connect%20to%20the%20server%20at%20sha512.badssl.com.
[task 2022-04-01T01:01:17.805Z] 01:01:17     INFO -  1648774877805	Marionette	DEBUG	5 <- [1,32,{"error":"unknown error","message":"Reached error page: about:neterror?e=dnsNotFound&u=https%3A//sha512.badssl.com/&c=U ... /EventEmitter.jsm:160:20\nreceiveMessage@chrome://remote/content/marionette/actors/MarionetteEventsParent.jsm:44:25\n"},null]
[task 2022-04-01T01:01:17.809Z] 01:01:17     INFO -  1648774877809	Marionette	DEBUG	5 -> [0,33,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:01:17.809Z] 01:01:17     INFO -  1648774877809	Marionette	DEBUG	5 <- [1,33,null,{"value":null}]
[task 2022-04-01T01:01:17.811Z] 01:01:17     INFO -  1648774877811	Marionette	DEBUG	5 -> [0,34,"Marionette:GetContext",{}]
[task 2022-04-01T01:01:17.811Z] 01:01:17     INFO -  1648774877811	Marionette	DEBUG	5 <- [1,34,null,{"value":"chrome"}]
[task 2022-04-01T01:01:17.812Z] 01:01:17     INFO -  1648774877812	Marionette	DEBUG	5 -> [0,35,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:01:17.812Z] 01:01:17     INFO -  1648774877812	Marionette	DEBUG	5 <- [1,35,null,{"value":null}]
[task 2022-04-01T01:01:17.813Z] 01:01:17     INFO -  1648774877813	Marionette	DEBUG	5 -> [0,36,"WebDriver:TakeScreenshot",{"id":null,"full":true,"hash":false,"scroll":true}]
[task 2022-04-01T01:01:17.872Z] 01:01:17     INFO -  1648774877885	Marionette	DEBUG	5 <- [1,36,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABAAAAAMHCAYAAACnvyW1AAAgAElEQVR4XuzdB7gtVX0w7rkKShMxUSLSLjEGjIAgSomCJQpiwW7EHiSg ... AgQCCygAAgcvfVToAAAQIECBAgQIAAAQJhBAQAYVqtUAIECBAgQIAAAQIECBCILCAAiNx9tRMgQIAAAQIECBAgQIBAGIH/D0aYrBKIrEVuAAAAAElFTkSuQmCC"}]
[task 2022-04-01T01:01:17.888Z] 01:01:17     INFO -  1648774877888	Marionette	DEBUG	5 -> [0,37,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:01:17.888Z] 01:01:17     INFO -  1648774877888	Marionette	DEBUG	5 <- [1,37,null,{"value":null}]
[task 2022-04-01T01:01:17.889Z] 01:01:17     INFO -  1648774877890	Marionette	DEBUG	5 -> [0,38,"Marionette:GetContext",{}]
[task 2022-04-01T01:01:17.890Z] 01:01:17     INFO -  1648774877890	Marionette	DEBUG	5 <- [1,38,null,{"value":"chrome"}]
[task 2022-04-01T01:01:17.890Z] 01:01:17     INFO -  1648774877891	Marionette	DEBUG	5 -> [0,39,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-01T01:01:17.891Z] 01:01:17     INFO -  1648774877891	Marionette	DEBUG	5 <- [1,39,null,{"value":null}]
[task 2022-04-01T01:01:17.891Z] 01:01:17     INFO -  1648774877892	Marionette	DEBUG	5 -> [0,40,"WebDriver:GetPageSource",{}]
[task 2022-04-01T01:01:17.894Z] 01:01:17     INFO -  1648774877894	Marionette	TRACE	[24] MarionetteCommands actor created for window id 4294967298
[task 2022-04-01T01:01:17.895Z] 01:01:17     INFO -  1648774877896	Marionette	DEBUG	5 <- [1,40,null,{"value":"<html xmlns=\"http://www.w3.org/1999/xhtml\" dir=\"ltr\" lang=\"en-US\">\n  <head>\n    <meta http-equiv ... s.js\"></script>\n  <script type=\"module\" src=\"chrome://browser/content/certerror/aboutNetError.js\"></script>\n</html>"}]
[task 2022-04-01T01:01:17.896Z] 01:01:17     INFO -  1648774877896	Marionette	DEBUG	5 -> [0,41,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:01:17.896Z] 01:01:17     INFO -  1648774877897	Marionette	DEBUG	5 <- [1,41,null,{"value":null}]
[task 2022-04-01T01:01:17.956Z] 01:01:17     INFO - TEST-UNEXPECTED-ERROR | testing/firefox-ui/tests/functional/security/test_ssl_status_after_restart.py TestSSLStatusAfterRestart.test_ssl_status_after_restart | marionette_driver.errors.UnknownException: Reached error page: about:neterror?e=dnsNotFound&u=https%3A//sha512.badssl.com/&c=UTF-8&d=We%20can%E2%80%99t%20connect%20to%20the%20server%20at%20sha512.badssl.com.
[task 2022-04-01T01:01:17.956Z] 01:01:17     INFO - stacktrace:
[task 2022-04-01T01:01:17.956Z] 01:01:17     INFO - 	WebDriverError@chrome://remote/content/shared/webdriver/Errors.jsm:183:5
[task 2022-04-01T01:01:17.964Z] 01:01:17     INFO - 	UnknownError@chrome://remote/content/shared/webdriver/Errors.jsm:510:5
[task 2022-04-01T01:01:17.964Z] 01:01:17     INFO - 	checkReadyState@chrome://remote/content/marionette/navigate.js:64:24
[task 2022-04-01T01:01:17.964Z] 01:01:17     INFO - 	onNavigation@chrome://remote/content/marionette/navigate.js:312:39
[task 2022-04-01T01:01:17.964Z] 01:01:17     INFO - 	emit@resource://gre/modules/EventEmitter.jsm:160:20
[task 2022-04-01T01:01:17.964Z] 01:01:17     INFO - 	receiveMessage@chrome://remote/content/marionette/actors/MarionetteEventsParent.jsm:44:25
[task 2022-04-01T01:01:17.964Z] 01:01:17     INFO - Traceback (most recent call last):
[task 2022-04-01T01:01:17.964Z] 01:01:17     INFO -   File "Z:\task_164876914258102\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 202, in run
[task 2022-04-01T01:01:17.964Z] 01:01:17     INFO -     testMethod()
[task 2022-04-01T01:01:17.964Z] 01:01:17     INFO -   File "Z:\task_164876914258102\build\tests\firefox-ui\tests\testing\firefox-ui\tests\functional\security\test_ssl_status_after_restart.py", line 31, in test_ssl_status_after_restart
[task 2022-04-01T01:01:17.964Z] 01:01:17     INFO -     self.marionette.navigate(self.test_url)
[task 2022-04-01T01:01:17.964Z] 01:01:17     INFO -   File "Z:\task_164876914258102\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1551, in navigate
[task 2022-04-01T01:01:17.964Z] 01:01:17     INFO -     self._send_message("WebDriver:Navigate", {"url": url})
[task 2022-04-01T01:01:17.964Z] 01:01:17     INFO -   File "Z:\task_164876914258102\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2022-04-01T01:01:17.964Z] 01:01:17     INFO -     return func(*args, **kwargs)
[task 2022-04-01T01:01:17.964Z] 01:01:17     INFO -   File "Z:\task_164876914258102\build\venv\lib\site-packages\marionette_driver\marionette.py", line 668, in _send_message
[task 2022-04-01T01:01:17.964Z] 01:01:17     INFO -     self._handle_error(err)
[task 2022-04-01T01:01:17.965Z] 01:01:17     INFO -   File "Z:\task_164876914258102\build\venv\lib\site-packages\marionette_driver\marionette.py", line 680, in _handle_error
[task 2022-04-01T01:01:17.965Z] 01:01:17     INFO -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2022-04-01T01:01:17.965Z] 01:01:17     INFO - TEST-INFO took 20892ms
[task 2022-04-01T01:01:17.965Z] 01:01:17     INFO -  1648774877961	Marionette	DEBUG	5 -> [0,42,"Marionette:GetContext",{}]
[task 2022-04-01T01:01:17.965Z] 01:01:17     INFO -  1648774877961	Marionette	DEBUG	5 <- [1,42,null,{"value":"chrome"}]
[task 2022-04-01T01:01:17.966Z] 01:01:17     INFO -  1648774877963	Marionette	DEBUG	5 -> [0,43,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:01:17.966Z] 01:01:17     INFO -  1648774877963	Marionette	DEBUG	5 <- [1,43,null,{"value":null}]
[task 2022-04-01T01:01:17.966Z] 01:01:17     INFO -  1648774877965	Marionette	DEBUG	5 -> [0,44,"WebDriver:ExecuteScript",{"script":"Components.utils.import(\"resource://gre/modules/Preferences.jsm\");\n             ... efault","line":769,"filename":"Z:\\task_164876914258102\\build\\venv\\lib\\site-packages\\marionette_driver\\marionette.py"}]
[task 2022-04-01T01:01:17.969Z] 01:01:17     INFO -  1648774877969	Marionette	DEBUG	5 <- [1,44,null,{"value":null}]
[task 2022-04-01T01:01:17.970Z] 01:01:17     INFO -  1648774877970	Marionette	DEBUG	5 -> [0,45,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:01:17.970Z] 01:01:17     INFO -  1648774877971	Marionette	DEBUG	5 <- [1,45,null,{"value":null}]
[task 2022-04-01T01:01:17.971Z] 01:01:17     INFO -  1648774877971	Marionette	DEBUG	5 -> [0,46,"Marionette:GetContext",{}]
[task 2022-04-01T01:01:17.971Z] 01:01:17     INFO -  1648774877971	Marionette	DEBUG	5 <- [1,46,null,{"value":"chrome"}]
[task 2022-04-01T01:01:17.971Z] 01:01:17     INFO -  1648774877972	Marionette	DEBUG	5 -> [0,47,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:01:17.972Z] 01:01:17     INFO -  1648774877972	Marionette	DEBUG	5 <- [1,47,null,{"value":null}]
[task 2022-04-01T01:01:17.973Z] 01:01:17     INFO -  1648774877973	Marionette	DEBUG	5 -> [0,48,"WebDriver:ExecuteScript",{"script":"Components.utils.import(\"resource://gre/modules/Preferences.jsm\");\n             ... efault","line":769,"filename":"Z:\\task_164876914258102\\build\\venv\\lib\\site-packages\\marionette_driver\\marionette.py"}]
[task 2022-04-01T01:01:17.975Z] 01:01:17     INFO -  1648774877975	Marionette	DEBUG	5 <- [1,48,null,{"value":null}]
[task 2022-04-01T01:01:17.976Z] 01:01:17     INFO -  1648774877976	Marionette	DEBUG	5 -> [0,49,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:01:17.976Z] 01:01:17     INFO -  1648774877976	Marionette	DEBUG	5 <- [1,49,null,{"value":null}]
[task 2022-04-01T01:01:17.979Z] 01:01:17     INFO -  1648774877980	Marionette	DEBUG	5 -> [0,50,"Marionette:GetContext",{}]
[task 2022-04-01T01:01:17.979Z] 01:01:17     INFO -  1648774877980	Marionette	DEBUG	5 <- [1,50,null,{"value":"chrome"}]
[task 2022-04-01T01:01:17.980Z] 01:01:17     INFO -  1648774877981	Marionette	DEBUG	5 -> [0,51,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:01:17.980Z] 01:01:17     INFO -  1648774877981	Marionette	DEBUG	5 <- [1,51,null,{"value":null}]
[task 2022-04-01T01:01:17.981Z] 01:01:17     INFO -  1648774877981	Marionette	DEBUG	5 -> [0,52,"WebDriver:GetWindowHandles",{}]
[task 2022-04-01T01:01:17.981Z] 01:01:17     INFO -  1648774877982	Marionette	DEBUG	5 <- [1,52,null,["2c473ade-0632-40ff-8049-0e9d759ec2d6"]]
[task 2022-04-01T01:01:17.981Z] 01:01:17     INFO -  1648774877982	Marionette	DEBUG	5 -> [0,53,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:01:17.982Z] 01:01:17     INFO -  1648774877982	Marionette	DEBUG	5 <- [1,53,null,{"value":null}]
[task 2022-04-01T01:01:17.982Z] 01:01:17     INFO -  1648774877983	Marionette	DEBUG	5 -> [0,54,"Marionette:GetContext",{}]
[task 2022-04-01T01:01:17.982Z] 01:01:17     INFO -  1648774877983	Marionette	DEBUG	5 <- [1,54,null,{"value":"chrome"}]
[task 2022-04-01T01:01:17.983Z] 01:01:17     INFO -  1648774877984	Marionette	DEBUG	5 -> [0,55,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-01T01:01:17.983Z] 01:01:17     INFO -  1648774877984	Marionette	DEBUG	5 <- [1,55,null,{"value":null}]
[task 2022-04-01T01:01:17.984Z] 01:01:17     INFO -  1648774877984	Marionette	DEBUG	5 -> [0,56,"WebDriver:GetWindowHandles",{}]
[task 2022-04-01T01:01:17.984Z] 01:01:17     INFO -  1648774877985	Marionette	DEBUG	5 <- [1,56,null,["71bad4f4-4b3e-4988-9ea6-a7d77b8d77fd"]]
[task 2022-04-01T01:01:17.985Z] 01:01:17     INFO -  1648774877985	Marionette	DEBUG	5 -> [0,57,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-01T01:01:17.985Z] 01:01:17     INFO -  1648774877985	Marionette	DEBUG	5 <- [1,57,null,{"value":null}]
[task 2022-04-01T01:01:17.985Z] 01:01:17     INFO -  1648774877986	Marionette	DEBUG	5 -> [0,58,"WebDriver:DeleteSession",{}]
[task 2022-04-01T01:01:17.987Z] 01:01:17     INFO -  1648774877987	Marionette	DEBUG	5 <- [1,58,null,{"value":null}]
[task 2022-04-01T01:01:17.988Z] 01:01:17     INFO -  1648774877988	Marionette	DEBUG	Closed connection 5
[task 2022-04-01T01:01:17.988Z] 01:01:17     INFO - 
[task 2022-04-01T01:01:17.988Z] 01:01:17     INFO - SUMMARY
[task 2022-04-01T01:01:17.988Z] 01:01:17     INFO - -------
[task 2022-04-01T01:01:17.989Z] 01:01:17     INFO - passed: 3
[task 2022-04-01T01:01:17.989Z] 01:01:17     INFO - failed: 1
[task 2022-04-01T01:01:17.989Z] 01:01:17     INFO - todo: 0
[task 2022-04-01T01:01:17.990Z] 01:01:17     INFO - 
[task 2022-04-01T01:01:17.990Z] 01:01:17     INFO - FAILED TESTS
[task 2022-04-01T01:01:17.990Z] 01:01:17     INFO - -------
[task 2022-04-01T01:01:17.990Z] 01:01:17     INFO - test_ssl_status_after_restart.py test_ssl_status_after_restart.TestSSLStatusAfterRestart.test_ssl_status_after_restart
[task 2022-04-01T01:01:17.991Z] 01:01:17     INFO - SUITE-END | took 93s
[task 2022-04-01T01:01:18.147Z] 01:01:18     INFO -  1648774878153	Marionette	DEBUG	Accepted connection 6 from 127.0.0.1:49905
[task 2022-04-01T01:01:18.163Z] 01:01:18     INFO -  1648774878163	Marionette	DEBUG	6 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-04-01T01:01:18.166Z] 01:01:18     INFO -  1648774878166	RemoteAgent	TRACE	[24] Document already finished loading: https://sha512.badssl.com/
[task 2022-04-01T01:01:18.167Z] 01:01:18     INFO -  1648774878167	Marionette	DEBUG	6 <- [1,1,null,{"sessionId":"fe7a1048-5790-411f-9025-89bb03159072","capabilities":{"browserName":"firefox","browserVersion":"100.0 ... e.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2022-04-01T01:01:18.167Z] 01:01:18     INFO -  1648774878168	Marionette	DEBUG	6 -> [0,2,"Marionette:AcceptConnections",{"value":false}]
[task 2022-04-01T01:01:18.168Z] 01:01:18     INFO -  1648774878168	Marionette	INFO	Stopped listening on port 2828
[task 2022-04-01T01:01:18.168Z] 01:01:18     INFO -  1648774878168	Marionette	DEBUG	6 <- [1,2,null,{"value":null}]
[task 2022-04-01T01:01:18.168Z] 01:01:18     INFO -  1648774878169	Marionette	DEBUG	6 -> [0,3,"Marionette:Quit",{}]
[task 2022-04-01T01:01:18.232Z] 01:01:18     INFO -  1648774878240	Marionette	TRACE	Received observer notification quit-application
[task 2022-04-01T01:01:18.241Z] 01:01:18     INFO -  1648774878240	Marionette	TRACE	Received observer notification quit-application
[task 2022-04-01T01:01:18.241Z] 01:01:18     INFO -  1648774878241	Marionette	DEBUG	Marionette stopped listening
[task 2022-04-01T01:01:18.242Z] 01:01:18     INFO -  1648774878243	Marionette	DEBUG	6 <- [1,3,null,{"cause":"shutdown","forced":false}]
[task 2022-04-01T01:01:18.255Z] 01:01:18     INFO -  1648774878259	Marionette	DEBUG	Closed connection 6
[task 2022-04-01T01:01:18.842Z] 01:01:18    ERROR - Return code: 10
[task 2022-04-01T01:01:18.843Z] 01:01:18    ERROR - Got 1 unexpected statuses

After backfills and retriggers I can't find the regression bug, can anyone take a look please?

Hello Whimboo, it looks like https://sha512.badssl.com/ has an expiring cert(Not After Fri, 01 Apr 2022) which might cause this issue. Can you please take a look?

Flags: needinfo?(hskupin)

Checking for that domain reveals that it's not accessible anymore from a lot of places around the world. So maybe the owner of that domain did not extend their contract and as result the server was shutdown? Nevertheless it's not accessible from our CI as well and as such we should land a skip patch across all active branches. I'll come up with a patch soon.

Dana, can you please have a look if it would be ok to make this test a local one by just serving a HTTPS page via a local web server? I doubt we really need something external here. If that's the case we can move this test to a basic Marionette test.

Here is the current test:
https://searchfox.org/mozilla-central/source/testing/firefox-ui/tests/functional/security/test_ssl_status_after_restart.py

Flags: needinfo?(hskupin) → needinfo?(dkeeler)
Assignee: nobody → hskupin
Status: NEW → ASSIGNED

For status updates about the badssl.com outage check: https://github.com/chromium/badssl.com/issues/500

Assignee: hskupin → nobody
Status: ASSIGNED → NEW
Pushed by hskupin@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/3c9cc25671e8
[firefox-ui] Disable test_ssl_status_after_restart.py because badssl.com is not reachable. r=webdriver-reviewers,jdescottes
Assignee: nobody → hskupin
Status: NEW → ASSIGNED

beta is already on release so a fix for 98 is no longer needed. Setting leave-open to keep this bug open until we have a proper solution.

It looks like having a local https server instead should work fine.

Flags: needinfo?(dkeeler)

(In reply to Dana Keeler (she/her) (use needinfo) (:keeler for reviews) from comment #10)

It looks like having a local https server instead should work fine.

Great. I assume it doesn't matter which kind of certificate is served as long as it's HTTPS, right? I'm sure we cannot have something else than self-signed.

Flags: needinfo?(dkeeler)

It's probably best if the certificate is treated as valid by Firefox. Presumably marionette has an imported root it trusts, and presumably there are server certificates issued from that root?

Flags: needinfo?(dkeeler)

hm, we use wptserve as HTTP/HTTPS server. Not sure if there is an imported root that could issue certificates.

James, would that work and if yes, what would we need? I assume at least certutil which Marionette tests should already have.

(In reply to Henrik Skupin (:whimboo) [⌚️UTC+1] from comment #6)

For status updates about the badssl.com outage check: https://github.com/chromium/badssl.com/issues/500

The outage is fixed now but I would suggest to not re-enable it. Instead I would try to make this test local by following the suggestions from James above. If it turns out to be not that easy shall we re-enable the test as is right now? Not sure how important it is for us.

Flags: needinfo?(dkeeler)

I wouldn't depend on an external website in tests (other tests tend to explicitly prohibit this, so I'm surprised this was ever allowed).

Flags: needinfo?(dkeeler)
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]
Summary: High frequency [tier 2] testing/firefox-ui/tests/functional/security/test_ssl_status_after_restart.py TestSSLStatusAfterRestart.test_ssl_status_after_restart | marionette_driver.errors.UnknownException: Reached error page: about:neterror?e → Intermittent [tier 2] testing/firefox-ui/tests/functional/security/test_ssl_status_after_restart.py TestSSLStatusAfterRestart.test_ssl_status_after_restart | marionette_driver.errors.UnknownException: Reached error page: about:neterror?e

(In reply to Dana Keeler (she/her) (use needinfo) (:keeler for reviews) from comment #16)

I wouldn't depend on an external website in tests (other tests tend to explicitly prohibit this, so I'm surprised this was ever allowed).

I never actually got back to this particular bug, and also won't have the time in the near future. So how important is that test for you Dana? Should we just re-enable the test? Note that external websites can be accessed but then the test job cannot be run as Tier 1, which is the case here.

Flags: needinfo?(dkeeler)

test_ssl_status_after_restart.py used to use badssl.com, which isn't a site run
by Mozilla. If that site was ever unavailable, the test would fail. This patch
updates the test to use www.itisatrap.org, which other firefox-ui tests also
use apparently without any issues.

How about something like this? Other firefox-ui tests use www.itisatrap.org and don't seem to have a problem.

Flags: needinfo?(dkeeler)

That's a great idea!

Pushed by dkeeler@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/555efa0ad1f0
use www.itisatrap.org in all firefox-ui tests r=whimboo,webdriver-reviewers
Assignee: nobody → dkeeler
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → FIXED
Target Milestone: --- → 110 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: