Closed Bug 1658066 Opened 4 years ago Closed 4 years ago

Intermittent testing\firefox-ui\tests\puppeteer\test_security.py TestSecurity.test_get_certificate | UnknownException: Reached error page: about:neterror?e=netTimeout&u=https%3A//extended-validation.badssl.com/&c=UTF-8&f=regular

Categories

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

Product:
Testing
Component:
Firefox UI Tests
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED DUPLICATE of bug 1382494

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=312434758&repo=mozilla-esr68
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/CAMjpj-cTqOGo2eKXyVJIQ/runs/0/artifacts/public/logs/live_backing.log

20:38:25 INFO - TEST-START | testing\firefox-ui\tests\puppeteer\test_security.py TestSecurity.test_get_certificate
20:38:25 INFO - 1596832705948 Marionette DEBUG Closed connection 4
20:38:25 INFO - 1596832705949 Marionette DEBUG Accepted connection 5 from 127.0.0.1:50012
20:38:25 INFO - 1596832705951 Marionette DEBUG 5 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
20:38:25 INFO - 1596832705952 Marionette TRACE [2147483652] Frame script loaded
20:38:25 INFO - 1596832705953 Marionette TRACE [2147483652] Frame script registered
20:38:25 INFO - 1596832705955 Marionette DEBUG 5 <- [1,1,null,{"sessionId":"bc231f6a-d8ec-4803-a2da-51c98ffaa898","capabilities":{"browserName":"firefox","browserVersion":"68.12 ... d\tmpu1mgs0.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
20:38:25 INFO - 1596832705956 Marionette DEBUG 5 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
20:38:25 INFO - 1596832705956 Marionette DEBUG 5 <- [1,2,null,{"value":null}]
20:38:25 INFO - 1596832705957 Marionette DEBUG 5 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
20:38:25 INFO - 1596832705958 Marionette DEBUG 5 <- [1,3,null,{"value":null}]
20:38:25 INFO - 1596832705959 Marionette DEBUG 5 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
20:38:25 INFO - 1596832705959 Marionette DEBUG 5 <- [1,4,null,{"value":null}]
20:38:25 INFO - 1596832705961 Marionette DEBUG 5 -> [0,5,"WebDriver:GetWindowHandles",{}]
20:38:25 INFO - 1596832705961 Marionette DEBUG 5 <- [1,5,null,["2147483652"]]
20:38:25 INFO - 1596832705962 Marionette DEBUG 5 -> [0,6,"WebDriver:GetWindowHandles",{}]
20:38:25 INFO - 1596832705963 Marionette DEBUG 5 <- [1,6,null,["2147483652"]]
20:38:25 INFO - 1596832705964 Marionette DEBUG 5 -> [0,7,"Marionette:SetContext",{"value":"chrome"}]
20:38:25 INFO - 1596832705964 Marionette DEBUG 5 <- [1,7,null,{"value":null}]
20:38:25 INFO - 1596832705965 Marionette DEBUG 5 -> [0,8,"WebDriver:GetChromeWindowHandle",{}]
20:38:25 INFO - 1596832705965 Marionette DEBUG 5 <- [1,8,null,{"value":"3"}]
20:38:25 INFO - 1596832705966 Marionette DEBUG 5 -> [0,9,"WebDriver:GetChromeWindowHandle",{}]
20:38:25 INFO - 1596832705966 Marionette DEBUG 5 <- [1,9,null,{"value":"3"}]
20:38:25 INFO - 1596832705967 Marionette DEBUG 5 -> [0,10,"Marionette:GetContext",{}]
20:38:25 INFO - 1596832705968 Marionette DEBUG 5 <- [1,10,null,{"value":"chrome"}]
20:38:25 INFO - 1596832705969 Marionette DEBUG 5 -> [0,11,"Marionette:SetContext",{"value":"chrome"}]
20:38:25 INFO - 1596832705969 Marionette DEBUG 5 <- [1,11,null,{"value":null}]
20:38:25 INFO - 1596832705970 Marionette DEBUG 5 -> [0,12,"WebDriver:GetChromeWindowHandle",{}]
20:38:25 INFO - 1596832705970 Marionette DEBUG 5 <- [1,12,null,{"value":"3"}]
20:38:25 INFO - 1596832705971 Marionette DEBUG 5 -> [0,13,"Marionette:GetWindowType",{}]
20:38:25 INFO - 1596832705971 Marionette DEBUG 5 <- [1,13,null,{"value":"navigator:browser"}]
20:38:25 INFO - 1596832705972 Marionette DEBUG 5 -> [0,14,"WebDriver:GetChromeWindowHandles",{}]
20:38:25 INFO - 1596832705973 Marionette DEBUG 5 <- [1,14,null,["3"]]
20:38:25 INFO - 1596832705974 Marionette DEBUG 5 -> [0,15,"Marionette:GetContext",{}]
20:38:25 INFO - 1596832705974 Marionette DEBUG 5 <- [1,15,null,{"value":"chrome"}]
20:38:25 INFO - 1596832705975 Marionette DEBUG 5 -> [0,16,"Marionette:SetContext",{"value":"chrome"}]
20:38:25 INFO - 1596832705976 Marionette DEBUG 5 <- [1,16,null,{"value":null}]
20:38:25 INFO - 1596832705977 Marionette DEBUG 5 -> [0,17,"WebDriver:ExecuteScript",{"script":"Components.utils.import("resource://gre/modules/Services.jsm");\n\n ... e":"Z:\task_1596832315\build\venv\lib\site-packages\firefox_puppeteer\ui\windows.py","sandbox":"default","line":144}]
20:38:25 INFO - 1596832705979 Marionette DEBUG 5 <- [1,17,null,{"value":true}]
20:38:25 INFO - 1596832705980 Marionette DEBUG 5 -> [0,18,"Marionette:SetContext",{"value":"chrome"}]
20:38:25 INFO - 1596832705980 Marionette DEBUG 5 <- [1,18,null,{"value":null}]
20:38:25 INFO - 1596832705981 Marionette DEBUG 5 -> [0,19,"Marionette:SetContext",{"value":"chrome"}]
20:38:25 INFO - 1596832705981 Marionette DEBUG 5 <- [1,19,null,{"value":null}]
20:38:25 INFO - 1596832705982 Marionette DEBUG 5 -> [0,20,"WebDriver:GetChromeWindowHandles",{}]
20:38:25 INFO - 1596832705983 Marionette DEBUG 5 <- [1,20,null,["3"]]
20:38:25 INFO - 1596832705984 Marionette DEBUG 5 -> [0,21,"WebDriver:SwitchToWindow",{"handle":"3","focus":true,"name":"3"}]
20:38:25 INFO - 1596832705984 Marionette DEBUG 5 <- [1,21,null,{"value":null}]
20:38:25 INFO - 1596832705985 Marionette DEBUG 5 -> [0,22,"WebDriver:GetChromeWindowHandle",{}]
20:38:25 INFO - 1596832705985 Marionette DEBUG 5 <- [1,22,null,{"value":"3"}]
20:38:25 INFO - 1596832705986 Marionette DEBUG 5 -> [0,23,"Marionette:GetContext",{}]
20:38:25 INFO - 1596832705986 Marionette DEBUG 5 <- [1,23,null,{"value":"chrome"}]
20:38:25 INFO - 1596832705988 Marionette DEBUG 5 -> [0,24,"Marionette:SetContext",{"value":"chrome"}]
20:38:25 INFO - 1596832705988 Marionette DEBUG 5 <- [1,24,null,{"value":null}]
20:38:25 INFO - 1596832705989 Marionette DEBUG 5 -> [0,25,"WebDriver:GetChromeWindowHandle",{}]
20:38:25 INFO - 1596832705989 Marionette DEBUG 5 <- [1,25,null,{"value":"3"}]
20:38:25 INFO - 1596832705990 Marionette DEBUG 5 -> [0,26,"Marionette:GetWindowType",{}]
20:38:25 INFO - 1596832705991 Marionette DEBUG 5 <- [1,26,null,{"value":"navigator:browser"}]
20:38:25 INFO - 1596832705992 Marionette DEBUG 5 -> [0,27,"WebDriver:GetChromeWindowHandles",{}]
20:38:25 INFO - 1596832705992 Marionette DEBUG 5 <- [1,27,null,["3"]]
20:38:25 INFO - 1596832705993 Marionette DEBUG 5 -> [0,28,"Marionette:GetContext",{}]
20:38:25 INFO - 1596832705993 Marionette DEBUG 5 <- [1,28,null,{"value":"chrome"}]
20:38:25 INFO - 1596832705994 Marionette DEBUG 5 -> [0,29,"Marionette:SetContext",{"value":"chrome"}]
20:38:25 INFO - 1596832705995 Marionette DEBUG 5 <- [1,29,null,{"value":null}]
20:38:25 INFO - 1596832705996 Marionette DEBUG 5 -> [0,30,"WebDriver:ExecuteScript",{"script":"Components.utils.import("resource://gre/modules/Services.jsm");\n\n ... e":"Z:\task_1596832315\build\venv\lib\site-packages\firefox_puppeteer\ui\windows.py","sandbox":"default","line":144}]
20:38:25 INFO - 1596832705997 Marionette DEBUG 5 <- [1,30,null,{"value":true}]
20:38:26 INFO - 1596832705998 Marionette DEBUG 5 -> [0,31,"Marionette:SetContext",{"value":"chrome"}]
20:38:26 INFO - 1596832705999 Marionette DEBUG 5 <- [1,31,null,{"value":null}]
20:38:26 INFO - 1596832706000 Marionette DEBUG 5 -> [0,32,"Marionette:SetContext",{"value":"chrome"}]
20:38:26 INFO - 1596832706000 Marionette DEBUG 5 <- [1,32,null,{"value":null}]
20:38:26 INFO - 1596832706001 Marionette DEBUG 5 -> [0,33,"Marionette:GetContext",{}]
20:38:26 INFO - 1596832706001 Marionette DEBUG 5 <- [1,33,null,{"value":"chrome"}]
20:38:26 INFO - 1596832706002 Marionette DEBUG 5 -> [0,34,"Marionette:SetContext",{"value":"content"}]
20:38:26 INFO - 1596832706002 Marionette DEBUG 5 <- [1,34,null,{"value":null}]
20:38:26 INFO - 1596832706003 Marionette DEBUG 5 -> [0,35,"WebDriver:Navigate",{"url":"about:about"}]
20:38:26 INFO - 1596832706007 Marionette TRACE [2147483652] Received DOM event beforeunload for https://extended-validation.badssl.com/
20:38:26 INFO - 1596832706014 Marionette TRACE [2147483652] Received DOM event pagehide for https://extended-validation.badssl.com/
20:38:26 INFO - 1596832706014 Marionette TRACE [16] Frame script loaded
20:38:26 INFO - 1596832706015 Marionette TRACE [16] Frame script registered
20:38:26 INFO - 1596832706015 Marionette TRACE [2147483652] Received DOM event unload for https://extended-validation.badssl.com/
20:38:26 INFO - 1596832706017 Marionette TRACE [2147483652] Received observer notification outer-window-destroyed
20:38:26 INFO - 1596832706027 Marionette TRACE [16] Check readyState uninitialized for about:blank
20:38:26 INFO - [Parent 5656, Gecko_IOThread] WARNING: pipe error: 109: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 348
20:38:26 INFO - [Child 7600, Chrome_ChildThread] WARNING: pipe error: 109: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 348
20:38:26 INFO - [Child 7600, Chrome_ChildThread] WARNING: pipe error: 109: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_w1596832706066 Marionette TRACE [16] Received DOM event DOMContentLoaded for about:about
20:38:26 INFO - 1596832706070 Marionette TRACE [16] Received DOM event pageshow for about:about
20:38:26 INFO - 1596832706073 Marionette DEBUG 5 <- [1,35,null,{"value":null}]
20:38:26 INFO - 1596832706086 Marionette DEBUG 5 -> [0,36,"Marionette:SetContext",{"value":"chrome"}]
20:38:26 INFO - 1596832706086 Marionette DEBUG 5 <- [1,36,null,{"value":null}]
20:38:26 INFO - 1596832706087 Marionette DEBUG 5 -> [0,37,"Marionette:GetContext",{}]
20:38:26 INFO - 1596832706087 Marionette DEBUG 5 <- [1,37,null,{"value":"chrome"}]
20:38:26 INFO - 1596832706088 Marionette DEBUG 5 -> [0,38,"Marionette:SetContext",{"value":"content"}]
20:38:26 INFO - 1596832706088 Marionette DEBUG 5 <- [1,38,null,{"value":null}]
20:38:26 INFO - 1596832706093 Marionette DEBUG 5 -> [0,39,"WebDriver:Navigate",{"url":"https://extended-validation.badssl.com"}]
20:38:26 INFO - 1596832706095 Marionette TRACE [16] Received DOM event beforeunload for about:about
20:38:26 INFO - 1596832706101 Marionette TRACE [16] Received DOM event pagehide for about:about
20:38:26 INFO - 1596832706101 Marionette TRACE [16] Received DOM event unload for about:about
20:38:26 INFO - 1596832706114 Marionette TRACE [16] Received observer notification outer-window-destroyed
20:38:26 INFO - 1596832706156 Marionette TRACE [8589934593] Frame script loaded
20:38:26 INFO - 1596832706158 Marionette TRACE [8589934593] Frame script registered
20:38:26 INFO - 1596832706176 Marionette TRACE [8589934593] Check readyState uninitialized for about:blank
20:39:08 INFO - 1596832748191 Marionette TRACE [8589934593] Received DOM event pageshow for about:blank
20:39:08 INFO - 1596832748256 Marionette TRACE [8589934593] Received DOM event DOMContentLoaded for about:neterror?e=netTimeout&u=https%3A//extended-validation.badssl.com/&c=UTF-8&f=regular&d=The%20server%20at%20extended-validation.badssl.com%20is%20taking%20too%20long%20to%20respond.
20:39:08 INFO - 1596832748258 Marionette DEBUG 5 <- [1,39,{"error":"unknown error","message":"Reached error page: about:neterror?e=netTimeout&u=https%3A//extended-validation.bad ... adyState@chrome://marionette/content/listener.js:300:13\nhandleEvent@chrome://marionette/content/listener.js:266:14\n"},null]
20:39:08 INFO - 1596832748260 Marionette DEBUG 5 -> [0,40,"Marionette:SetContext",{"value":"chrome"}]
20:39:08 INFO - 1596832748261 Marionette DEBUG 5 <- [1,40,null,{"value":null}]
20:39:08 INFO - 1596832748263 Marionette DEBUG 5 -> [0,41,"Marionette:GetContext",{}]
20:39:08 INFO - 1596832748264 Marionette DEBUG 5 <- [1,41,null,{"value":"chrome"}]
20:39:08 INFO - 1596832748267 Marionette DEBUG 5 -> [0,42,"Marionette:SetContext",{"value":"chrome"}]
20:39:08 INFO - 1596832748267 Marionette DEBUG 5 <- [1,42,null,{"value":null}]
20:39:08 INFO - 1596832748269 Marionette DEBUG 5 -> [0,43,"WebDriver:TakeScreenshot",{"highlights":null,"full":true,"hash":false,"id":null,"scroll":true}]
20:39:08 INFO - 1596832748292 Marionette DEBUG 5 <- [1,43,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABAAAAAMHCAYAAACnvyW1AAAgAElEQVR4nOzdb4xc9Xno8X1ZtS8uUqSqUl6AGulGSBejSDRVUqHcSiRI ... IIAABAXE0CQBwCAAAAAP1OAKgIAAAAAPQ/AaAiAMD/b8eOaQAAABgG+Ze8bz4aDkwAAAD0CYAJAAAAAPoEwAQAAAAAfQcJAc76cxeaUAAAAABJRU5ErkJggg=="}]
20:39:08 INFO - 1596832748294 Marionette DEBUG 5 -> [0,44,"Marionette:SetContext",{"value":"chrome"}]
20:39:08 INFO - 1596832748295 Marionette DEBUG 5 <- [1,44,null,{"value":null}]
20:39:08 INFO - 1596832748298 Marionette DEBUG 5 -> [0,45,"Marionette:GetContext",{}]
20:39:08 INFO - 1596832748298 Marionette DEBUG 5 <- [1,45,null,{"value":"chrome"}]
20:39:08 INFO - 1596832748300 Marionette DEBUG 5 -> [0,46,"Marionette:SetContext",{"value":"content"}]
20:39:08 INFO - 1596832748300 Marionette DEBUG 5 <- [1,46,null,{"value":null}]
20:39:08 INFO - 1596832748302 Marionette DEBUG 5 -> [0,47,"WebDriver:GetPageSource",{}]
20:39:08 INFO - 1596832748306 Marionette DEBUG 5 <- [1,47,null,{"value":"<html xmlns="http://www.w3.org/1999/xhtml">\n <head>\n <meta http-equiv="Content-Security-Policy\ ... /div>\n </div>\n </div>\n </body>\n <script src="chrome://browser/content/aboutNetError.js"></script>\n</html>"}]
20:39:08 INFO - 1596832748307 Marionette DEBUG 5 -> [0,48,"Marionette:SetContext",{"value":"chrome"}]
20:39:08 INFO - 1596832748308 Marionette DEBUG 5 <- [1,48,null,{"value":null}]
20:39:08 INFO - TEST-UNEXPECTED-ERROR | testing\firefox-ui\tests\puppeteer\test_security.py TestSecurity.test_get_certificate | UnknownException: Reached error page: about:neterror?e=netTimeout&u=https%3A//extended-validation.badssl.com/&c=UTF-8&f=regular&d=The%20server%20at%20extended-validation.badssl.com%20is%20taking%20too%20long%20to%20respond.
20:39:08 INFO - stacktrace:
20:39:08 INFO - WebDriverError@chrome://marionette/content/error.js:175:5
20:39:08 INFO - UnknownError@chrome://marionette/content/error.js:482:5
20:39:08 INFO - handleReadyState@chrome://marionette/content/listener.js:300:13
20:39:08 INFO - handleEvent@chrome://marionette/content/listener.js:266:14
20:39:08 INFO - Traceback (most recent call last):
20:39:08 INFO - File "Z:\task_1596832315\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
20:39:08 INFO - testMethod()
20:39:08 INFO - File "Z:\task_1596832315\build\tests\firefox-ui\tests\testing\firefox-ui\tests\puppeteer\test_security.py", line 35, in test_get_certificate
20:39:08 INFO - self.marionette.navigate(url_https)
20:39:08 INFO - File "Z:\task_1596832315\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1467, in navigate
20:39:08 INFO - {"url": url})
20:39:08 INFO - File "Z:\task_1596832315\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
20:39:08 INFO - return func(*args, **kwargs)
20:39:08 INFO - File "Z:\task_1596832315\build\venv\lib\site-packages\marionette_driver\marionette.py", line 598, in _send_message
20:39:08 INFO - self._handle_error(err)
20:39:08 INFO - File "Z:\task_1596832315\build\venv\lib\site-packages\marionette_driver\marionette.py", line 618, in _handle_error
20:39:08 INFO - raise errors.lookup(error)(message, stacktrace=stacktrace)
20:39:08 INFO - TEST-INFO took 42360ms
20:39:08 INFO - 1596832748317 Marionette DEBUG 5 -> [0,49,"Marionette:SetContext",{"value":"chrome"}]
20:39:08 INFO - 1596832748318 Marionette DEBUG 5 <- [1,49,null,{"value":null}]
20:39:08 INFO - 1596832748322 Marionette DEBUG 5 -> [0,50,"WebDriver:GetWindowHandles",{}]
20:39:08 INFO - 1596832748323 Marionette DEBUG 5 <- [1,50,null,["8589934593"]]
20:39:08 INFO - 1596832748324 Marionette DEBUG 5 -> [0,51,"WebDriver:GetChromeWindowHandles",{}]
20:39:08 INFO - 1596832748325 Marionette DEBUG 5 <- [1,51,null,["3"]]
20:39:08 INFO - 1596832748326 Marionette DEBUG 5 -> [0,52,"WebDriver:GetChromeWindowHandles",{}]
20:39:08 INFO - 1596832748326 Marionette DEBUG 5 <- [1,52,null,["3"]]
20:39:08 INFO - 1596832748328 Marionette DEBUG 5 -> [0,53,"WebDriver:GetChromeWindowHandles",{}]
20:39:08 INFO - 1596832748328 Marionette DEBUG 5 <- [1,53,null,["3"]]
20:39:08 INFO - 1596832748329 Marionette DEBUG 5 -> [0,54,"WebDriver:SwitchToWindow",{"handle":"3","focus":true,"name":"3"}]
20:39:08 INFO - 1596832748330 Marionette DEBUG 5 <- [1,54,null,{"value":null}]
20:39:08 INFO - 1596832748332 Marionette DEBUG 5 -> [0,55,"WebDriver:GetChromeWindowHandle",{}]
20:39:08 INFO - 1596832748332 Marionette DEBUG 5 <- [1,55,null,{"value":"3"}]
20:39:08 INFO - 1596832748334 Marionette DEBUG 5 -> [0,56,"Marionette:GetContext",{}]
20:39:08 INFO - 1596832748334 Marionette DEBUG 5 <- [1,56,null,{"value":"chrome"}]
20:39:08 INFO - 1596832748336 Marionette DEBUG 5 -> [0,57,"Marionette:SetContext",{"value":"chrome"}]
20:39:08 INFO - 1596832748336 Marionette DEBUG 5 <- [1,57,null,{"value":null}]
20:39:08 INFO - 1596832748337 Marionette DEBUG 5 -> [0,58,"WebDriver:GetChromeWindowHandle",{}]
20:39:08 INFO - 1596832748337 Marionette DEBUG 5 <- [1,58,null,{"value":"3"}]
20:39:08 INFO - 1596832748339 Marionette DEBUG 5 -> [0,59,"Marionette:GetWindowType",{}]
20:39:08 INFO - 1596832748339 Marionette DEBUG 5 <- [1,59,null,{"value":"navigator:browser"}]
20:39:08 INFO - 1596832748340 Marionette DEBUG 5 -> [0,60,"WebDriver:GetChromeWindowHandles",{}]
20:39:08 INFO - 1596832748341 Marionette DEBUG 5 <- [1,60,null,["3"]]
20:39:08 INFO - 1596832748342 Marionette DEBUG 5 -> [0,61,"Marionette:GetContext",{}]
20:39:08 INFO - 1596832748342 Marionette DEBUG 5 <- [1,61,null,{"value":"chrome"}]
20:39:08 INFO - 1596832748344 Marionette DEBUG 5 -> [0,62,"Marionette:SetContext",{"value":"chrome"}]
20:39:08 INFO - 1596832748344 Marionette DEBUG 5 <- [1,62,null,{"value":null}]
20:39:08 INFO - 1596832748346 Marionette DEBUG 5 -> [0,63,"WebDriver:ExecuteScript",{"script":"Components.utils.import("resource://gre/modules/Services.jsm");\n\n ... e":"Z:\task_1596832315\build\venv\lib\site-packages\firefox_puppeteer\ui\windows.py","sandbox":"default","line":144}]

Looks like a one-off network failure similar to bug 1382494, which we aren't going to fix.

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.