Closed Bug 1512602 Opened 7 years ago Closed 7 years ago

Intermittent testing/firefox-ui/tests/functional/safebrowsing/test_notification.py TestSafeBrowsingNotificationBar.test_notification_bar | UnknownException: Reached error page: about:neterror?e=networkProtocolError&u=https%3A//www.itisatrap.org/f

Categories

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

Version 3
defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: rmaries [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=215906687&repo=mozilla-central https://queue.taskcluster.net/v1/task/LtS_MM9wTryJVGkeozBxOQ/runs/0/artifacts/public/logs/live_backing.log [task 2018-12-07T11:45:07.175Z] 11:45:07 INFO - 1544183107171 Marionette DEBUG 2 -> [0,277,"Marionette:SetContext",{"value":"content"}] [task 2018-12-07T11:45:07.176Z] 11:45:07 INFO - 1544183107171 Marionette DEBUG 2 <- [1,277,null,{"value":null}] [task 2018-12-07T11:45:07.178Z] 11:45:07 INFO - 1544183107175 Marionette DEBUG 2 -> [0,278,"WebDriver:Navigate",{"url":"https://www.itisatrap.org/firefox/its-an-attack.html"}] [task 2018-12-07T11:45:07.187Z] 11:45:07 INFO - 1544183107184 Marionette TRACE [12884901889] Received DOM event beforeunload for https://www.itisatrap.org/firefox/its-a-trap.html [task 2018-12-07T11:45:07.217Z] 11:45:07 INFO - 1544183107209 Marionette TRACE [12884901889] Received DOM event beforeunload for https://www.itisatrap.org/firefox/its-a-trap.html [task 2018-12-07T11:45:07.218Z] 11:45:07 INFO - 1544183107210 Marionette TRACE [12884901889] Received DOM event pagehide for https://www.itisatrap.org/firefox/its-a-trap.html [task 2018-12-07T11:45:07.242Z] 11:45:07 INFO - 1544183107239 Marionette TRACE [12884901889] Received DOM event DOMContentLoaded for about:blocked?e=malwareBlocked&u=https%3A//www.itisatrap.org/firefox/its-an-attack.html&o=1&s=blacklist&c=UTF-8&f=regular&d=T ... %20has%20been%20reported%20as%20an%20attack%20site%20and%20has%20been%20blocked%20based%20on%20your%20security%20preferences. [task 2018-12-07T11:45:07.259Z] 11:45:07 INFO - 1544183107250 Marionette DEBUG 2 <- [1,278,null,{"value":null}] [task 2018-12-07T11:45:08.264Z] 11:45:08 INFO - 1544183108254 Marionette DEBUG 2 -> [0,279,"WebDriver:FindElement",{"using":"id","value":"seeDetailsButton"}] [task 2018-12-07T11:45:08.269Z] 11:45:08 INFO - 1544183108258 Marionette DEBUG 2 <- [1,279,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8a3aca60-f004-4c77-95d4-82004669c3d4"}}] [task 2018-12-07T11:45:08.273Z] 11:45:08 INFO - 1544183108260 Marionette DEBUG 2 -> [0,280,"WebDriver:ElementClick",{"id":"8a3aca60-f004-4c77-95d4-82004669c3d4"}] [task 2018-12-07T11:45:08.502Z] 11:45:08 INFO - 1544183108492 Marionette DEBUG [12884901889] Canceled page load listener because no navigation has been detected [task 2018-12-07T11:45:08.503Z] 11:45:08 INFO - 1544183108493 Marionette DEBUG 2 <- [1,280,null,{"value":null}] [task 2018-12-07T11:45:08.689Z] 11:45:08 INFO - console.error: BroadcastService: [task 2018-12-07T11:45:08.695Z] 11:45:08 INFO - receivedBroadcastMessage: handler for [task 2018-12-07T11:45:08.696Z] 11:45:08 INFO - remote-settings/monitor_changes [task 2018-12-07T11:45:08.696Z] 11:45:08 INFO - threw error: [task 2018-12-07T11:45:08.696Z] 11:45:08 INFO - Message: Error: Polling for changes failed: NetworkError when attempting to fetch resource.. [task 2018-12-07T11:45:08.696Z] 11:45:08 INFO - Stack: [task 2018-12-07T11:45:08.696Z] 11:45:08 INFO - remoteSettingsFunction/remoteSettings.pollChanges@resource://services-settings/remote-settings.js:188:13 [task 2018-12-07T11:45:08.696Z] 11:45:08 INFO - async*receivedBroadcastMessage@resource://services-settings/remote-settings.js:313:12 [task 2018-12-07T11:45:08.696Z] 11:45:08 INFO - async*receivedBroadcastMessage@resource://gre/modules/PushBroadcastService.jsm:194:15 [task 2018-12-07T11:45:08.696Z] 11:45:08 INFO - async*receivedBroadcastMessage@resource://gre/modules/PushService.jsm:762:5 [task 2018-12-07T11:45:08.696Z] 11:45:08 INFO - _handleBroadcastReply@resource://gre/modules/PushServiceWebSocket.jsm:761:5 [task 2018-12-07T11:45:08.696Z] 11:45:08 INFO - _wsOnMessageAvailable@resource://gre/modules/PushServiceWebSocket.jsm:1052:18 [task 2018-12-07T11:45:08.696Z] 11:45:08 INFO - onMessageAvailable@resource://gre/modules/PushServiceWebSocket.jsm:97:5 [task 2018-12-07T11:45:09.501Z] 11:45:09 INFO - 1544183109495 Marionette DEBUG 2 -> [0,281,"WebDriver:FindElement",{"using":"id","value":"ignore_warning_link"}] [task 2018-12-07T11:45:09.502Z] 11:45:09 INFO - 1544183109499 Marionette DEBUG 2 <- [1,281,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"02141a6e-3d64-4320-b817-617e0bb60008"}}] [task 2018-12-07T11:45:09.503Z] 11:45:09 INFO - 1544183109500 Marionette DEBUG 2 -> [0,282,"WebDriver:ElementClick",{"id":"02141a6e-3d64-4320-b817-617e0bb60008"}] [task 2018-12-07T11:45:09.520Z] 11:45:09 INFO - 1544183109516 Marionette TRACE [12884901889] Received DOM event beforeunload for about:blocked?e=malwareBlocked&u=https%3A//www.itisatrap.org/firefox/its-an-attack.html&o=1&s=blacklist&c=UTF-8&f=regular&d=T ... %20has%20been%20reported%20as%20an%20attack%20site%20and%20has%20been%20blocked%20based%20on%20your%20security%20preferences. [task 2018-12-07T11:45:09.541Z] 11:45:09 INFO - 1544183109539 Marionette TRACE [12884901889] Received DOM event beforeunload for about:blocked?e=malwareBlocked&u=https%3A//www.itisatrap.org/firefox/its-an-attack.html&o=1&s=blacklist&c=UTF-8&f=regular&d=T ... %20has%20been%20reported%20as%20an%20attack%20site%20and%20has%20been%20blocked%20based%20on%20your%20security%20preferences. [task 2018-12-07T11:45:09.545Z] 11:45:09 INFO - 1544183109540 Marionette TRACE [12884901889] Received DOM event pagehide for about:blocked?e=malwareBlocked&u=https%3A//www.itisatrap.org/firefox/its-an-attack.html&o=1&s=blacklist&c=UTF-8&f=regular&d=T ... %20has%20been%20reported%20as%20an%20attack%20site%20and%20has%20been%20blocked%20based%20on%20your%20security%20preferences. [task 2018-12-07T11:45:09.550Z] 11:45:09 INFO - 1544183109543 Marionette TRACE [12884901889] Received DOM event unload for about:blocked?e=malwareBlocked&u=https%3A//www.itisatrap.org/firefox/its-an-attack.html&o=1&s=blacklist&c=UTF-8&f=regular&d=T ... %20has%20been%20reported%20as%20an%20attack%20site%20and%20has%20been%20blocked%20based%20on%20your%20security%20preferences. [task 2018-12-07T11:45:09.586Z] 11:45:09 INFO - JavaScript error: resource:///actors/NetErrorChild.jsm, line 696: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIStringBundle.GetStringFromName] [task 2018-12-07T11:45:09.587Z] 11:45:09 INFO - 1544183109584 Marionette TRACE [12884901889] Received DOM event DOMContentLoaded for about:neterror?e=networkProtocolError&u=https%3A//www.itisatrap.org/firefox/its-an-attack.html&c=UTF-8&f=regular&d=Firefox%20has%20experienced%20a%20network%20protocol%20violation%20that%20cannot%20be%20repaired. [task 2018-12-07T11:45:09.590Z] 11:45:09 INFO - 1544183109585 Marionette DEBUG 2 <- [1,282,{"error":"unknown error","message":"Reached error page: about:neterror?e=networkProtocolError&u=https%3A//www.itisatra ... :527:3\nregisterSelf@chrome://marionette/content/listener.js:461:5\n@chrome://marionette/content/listener.js:1683:1\n"},null] [task 2018-12-07T11:45:09.595Z] 11:45:09 INFO - 1544183109591 Marionette DEBUG 2 -> [0,283,"Marionette:SetContext",{"value":"chrome"}] [task 2018-12-07T11:45:09.596Z] 11:45:09 INFO - 1544183109591 Marionette DEBUG 2 <- [1,283,null,{"value":null}] [task 2018-12-07T11:45:09.605Z] 11:45:09 INFO - 1544183109598 Marionette DEBUG 2 -> [0,284,"Marionette:GetContext",{}] [task 2018-12-07T11:45:09.605Z] 11:45:09 INFO - 1544183109598 Marionette DEBUG 2 <- [1,284,null,{"value":"chrome"}] [task 2018-12-07T11:45:09.607Z] 11:45:09 INFO - 1544183109600 Marionette DEBUG 2 -> [0,285,"Marionette:SetContext",{"value":"chrome"}] [task 2018-12-07T11:45:09.609Z] 11:45:09 INFO - 1544183109600 Marionette DEBUG 2 <- [1,285,null,{"value":null}] [task 2018-12-07T11:45:09.610Z] 11:45:09 INFO - 1544183109601 Marionette DEBUG 2 -> [0,286,"WebDriver:TakeScreenshot",{"highlights":null,"full":true,"hash":false,"id":null,"scroll":true}] [task 2018-12-07T11:45:09.666Z] 11:45:09 INFO - 1544183109658 Marionette DEBUG 2 <- [1,286,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAQQCAYAAAC9RfbYAAAgAElEQVR4nOzde4xc93nY/ZESpHLcOE7S2nWMFLZ8aegLGqOBbMBGnKJ ... OAAAAAADAmAAEAAABgTAACAAAAwJgABAAAAIAxAQgAAAAAYwIQAAAAAMYEIAAAAACMCUAAAAAAGBOAAAAAADAmAAEAAABgLJyCZGRgA8W7AAAAAElFTkSuQmCC"}] [task 2018-12-07T11:45:09.668Z] 11:45:09 INFO - 1544183109662 Marionette DEBUG 2 -> [0,287,"Marionette:SetContext",{"value":"chrome"}] [task 2018-12-07T11:45:09.668Z] 11:45:09 INFO - 1544183109662 Marionette DEBUG 2 <- [1,287,null,{"value":null}] [task 2018-12-07T11:45:09.669Z] 11:45:09 INFO - 1544183109663 Marionette DEBUG 2 -> [0,288,"Marionette:GetContext",{}] [task 2018-12-07T11:45:09.670Z] 11:45:09 INFO - 1544183109663 Marionette DEBUG 2 <- [1,288,null,{"value":"chrome"}] [task 2018-12-07T11:45:09.671Z] 11:45:09 INFO - 1544183109664 Marionette DEBUG 2 -> [0,289,"Marionette:SetContext",{"value":"content"}] [task 2018-12-07T11:45:09.672Z] 11:45:09 INFO - 1544183109665 Marionette DEBUG 2 <- [1,289,null,{"value":null}] [task 2018-12-07T11:45:09.672Z] 11:45:09 INFO - 1544183109665 Marionette DEBUG 2 -> [0,290,"WebDriver:GetPageSource",{}] [task 2018-12-07T11:45:09.724Z] 11:45:09 INFO - TEST-UNEXPECTED-ERROR | testing/firefox-ui/tests/functional/safebrowsing/test_notification.py TestSafeBrowsingNotificationBar.test_notification_bar | UnknownException: Reached error page: about:neterror?e=networkProtocolError&u=https%3A//www.itisatrap.org/firefox/its-an-attack.html&c=UTF-8&f=regular&d=Firefox%20has%20experienced%20a%20network%20protocol%20violation%20that%20cannot%20be%20repaired. [task 2018-12-07T11:45:09.726Z] 11:45:09 INFO - stacktrace: [task 2018-12-07T11:45:09.727Z] 11:45:09 INFO - WebDriverError@chrome://marionette/content/error.js:179:5 [task 2018-12-07T11:45:09.727Z] 11:45:09 INFO - UnknownError@chrome://marionette/content/error.js:484:5 [task 2018-12-07T11:45:09.727Z] 11:45:09 INFO - handleReadyState@chrome://marionette/content/listener.js:276:21 [task 2018-12-07T11:45:09.728Z] 11:45:09 INFO - handleEvent@chrome://marionette/content/listener.js:244:9 [task 2018-12-07T11:45:09.728Z] 11:45:09 INFO - EventListener.handleEvent*handleEvent@chrome://marionette/content/listener.js:232:9 [task 2018-12-07T11:45:09.728Z] 11:45:09 INFO - EventListener.handleEvent*start@chrome://marionette/content/listener.js:144:7 [task 2018-12-07T11:45:09.729Z] 11:45:09 INFO - navigate@chrome://marionette/content/listener.js:407:7 [task 2018-12-07T11:45:09.729Z] 11:45:09 INFO - clickElement@chrome://marionette/content/listener.js:1210:5 [task 2018-12-07T11:45:09.729Z] 11:45:09 INFO - MessageListener.receiveMessage*startListeners@chrome://marionette/content/listener.js:527:3 [task 2018-12-07T11:45:09.730Z] 11:45:09 INFO - registerSelf@chrome://marionette/content/listener.js:461:5 [task 2018-12-07T11:45:09.730Z] 11:45:09 INFO - @chrome://marionette/content/listener.js:1683:1 [task 2018-12-07T11:45:09.731Z] 11:45:09 INFO - Traceback (most recent call last): [task 2018-12-07T11:45:09.731Z] 11:45:09 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 159, in run [task 2018-12-07T11:45:09.731Z] 11:45:09 INFO - testMethod() [task 2018-12-07T11:45:09.731Z] 11:45:09 INFO - File "/builds/worker/workspace/build/tests/firefox-ui/tests/testing/firefox-ui/tests/functional/safebrowsing/test_notification.py", line 86, in test_notification_bar [task 2018-12-07T11:45:09.731Z] 11:45:09 INFO - self.check_ignore_warning_link(unsafe_page) [task 2018-12-07T11:45:09.731Z] 11:45:09 INFO - File "/builds/worker/workspace/build/tests/firefox-ui/tests/testing/firefox-ui/tests/functional/safebrowsing/test_notification.py", line 102, in check_ignore_warning_link [task 2018-12-07T11:45:09.732Z] 11:45:09 INFO - link.click() [task 2018-12-07T11:45:09.732Z] 11:45:09 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 94, in click [task 2018-12-07T11:45:09.732Z] 11:45:09 INFO - {"id": self.id}) [task 2018-12-07T11:45:09.732Z] 11:45:09 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _ [task 2018-12-07T11:45:09.733Z] 11:45:09 INFO - return func(*args, **kwargs) [task 2018-12-07T11:45:09.733Z] 11:45:09 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 764, in _send_message [task 2018-12-07T11:45:09.733Z] 11:45:09 INFO - self._handle_error(err) [task 2018-12-07T11:45:09.733Z] 11:45:09 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 784, in _handle_error [task 2018-12-07T11:45:09.733Z] 11:45:09 INFO - raise errors.lookup(error)(message, stacktrace=stacktrace) [task 2018-12-07T11:45:09.734Z] 11:45:09 INFO - TEST-INFO took 20135ms [task 2018-12-07T11:45:09.736Z] 11:45:09 INFO - 1544183109674 Marionette DEBUG 2 <- [1,290,null,{"value":"<html xmlns=\"http://www.w3.org/1999/xhtml\">\n <head>\n <meta http-equiv=\"Content-Security-Policy ... n </body>\n <script type=\"application/javascript\" src=\"chrome://browser/content/aboutNetError.js\"></script>\n</html>"}] [task 2018-12-07T11:45:09.738Z] 11:45:09 INFO - 1544183109675 Marionette DEBUG 2 -> [0,291,"Marionette:SetContext",{"value":"chrome"}] [task 2018-12-07T11:45:09.739Z] 11:45:09 INFO - 1544183109676 Marionette DEBUG 2 <- [1,291,null,{"value":null}] [task 2018-12-07T11:45:09.741Z] 11:45:09 INFO - 1544183109680 Marionette DEBUG 2 -> [0,292,"Marionette:GetContext",{}] [task 2018-12-07T11:45:09.744Z] 11:45:09 INFO - 1544183109680 Marionette DEBUG 2 <- [1,292,null,{"value":"chrome"}] [task 2018-12-07T11:45:09.744Z] 11:45:09 INFO - 1544183109681 Marionette DEBUG 2 -> [0,293,"Marionette:SetContext",{"value":"chrome"}] [task 2018-12-07T11:45:09.745Z] 11:45:09 INFO - 1544183109681 Marionette DEBUG 2 <- [1,293,null,{"value":null}] [task 2018-12-07T11:45:09.747Z] 11:45:09 INFO - 1544183109683 Marionette DEBUG 2 -> [0,294,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":["https://www.itisatrap.org","safe-browsing"] ... .newURI(arguments[0], null, null);\n Services.perms.remove(uri, arguments[1]);","sandbox":"default","line":116}] [task 2018-12-07T11:45:09.749Z] 11:45:09 INFO - 1544183109687 Marionette DEBUG 2 <- [1,294,null,{"value":null}] [task 2018-12-07T11:45:09.750Z] 11:45:09 INFO - 1544183109689 Marionette DEBUG 2 -> [0,295,"Marionette:SetContext",{"value":"chrome"}] [task 2018-12-07T11:45:09.753Z] 11:45:09 INFO - 1544183109689 Marionette DEBUG 2 <- [1,295,null,{"value":null}] [task 2018-12-07T11:45:09.754Z] 11:45:09 INFO - 1544183109690 Marionette DEBUG 2 -> [0,296,"WebDriver:GetChromeWindowHandles",{}] [task 2018-12-07T11:45:09.756Z] 11:45:09 INFO - 1544183109691 Marionette DEBUG 2 <- [1,296,null,["3"]] [task 2018-12-07T11:45:09.758Z] 11:45:09 INFO - 1544183109692 Marionette DEBUG 2 -> [0,297,"WebDriver:SwitchToWindow",{"focus":true,"name":"3"}] [task 2018-12-07T11:45:09.761Z] 11:45:09 INFO - 1544183109692 Marionette DEBUG 2 <- [1,297,null,{"value":null}] [task 2018-12-07T11:45:09.762Z] 11:45:09 INFO - 1544183109693 Marionette DEBUG 2 -> [0,298,"WebDriver:GetChromeWindowHandle",{}] [task 2018-12-07T11:45:09.764Z] 11:45:09 INFO - 1544183109694 Marionette DEBUG 2 <- [1,298,null,{"value":"3"}] [task 2018-12-07T11:45:09.766Z] 11:45:09 INFO - 1544183109694 Marionette DEBUG 2 -> [0,299,"Marionette:GetContext",{}] [task 2018-12-07T11:45:09.768Z] 11:45:09 INFO - 1544183109695 Marionette DEBUG 2 <- [1,299,null,{"value":"chrome"}] [task 2018-12-07T11:45:09.769Z] 11:45:09 INFO - 1544183109696 Marionette DEBUG 2 -> [0,300,"Marionette:SetContext",{"value":"chrome"}] [task 2018-12-07T11:45:09.772Z] 11:45:09 INFO - 1544183109696 Marionette DEBUG 2 <- [1,300,null,{"value":null}] [task 2018-12-07T11:45:09.774Z] 11:45:09 INFO - 1544183109697 Marionette DEBUG 2 -> [0,301,"WebDriver:GetChromeWindowHandle",{}] [task 2018-12-07T11:45:09.776Z] 11:45:09 INFO - 1544183109697 Marionette DEBUG 2 <- [1,301,null,{"value":"3"}] [task 2018-12-07T11:45:09.778Z] 11:45:09 INFO - 1544183109698 Marionette DEBUG 2 -> [0,302,"Marionette:GetWindowType",{}] [task 2018-12-07T11:45:09.780Z] 11:45:09 INFO - 1544183109698 Marionette DEBUG 2 <- [1,302,null,{"value":"navigator:browser"}] [task 2018-12-07T11:45:09.782Z] 11:45:09 INFO - 1544183109699 Marionette DEBUG 2 -> [0,303,"WebDriver:GetChromeWindowHandles",{}] [task 2018-12-07T11:45:09.783Z] 11:45:09 INFO - 1544183109700 Marionette DEBUG 2 <- [1,303,null,["3"]] [task 2018-12-07T11:45:09.786Z] 11:45:09 INFO - 1544183109701 Marionette DEBUG 2 -> [0,304,"Marionette:GetContext",{}] [task 2018-12-07T11:45:09.788Z] 11:45:09 INFO - 1544183109701 Marionette DEBUG 2 <- [1,304,null,{"value":"chrome"}] [task 2018-12-07T11:45:09.792Z] 11:45:09 INFO - 1544183109702 Marionette DEBUG 2 -> [0,305,"Marionette:SetContext",{"value":"chrome"}] [task 2018-12-07T11:45:09.794Z] 11:45:09 INFO - 1544183109702 Marionette DEBUG 2 <- [1,305,null,{"value":null}] [task 2018-12-07T11:45:09.797Z] 11:45:09 INFO - 1544183109704 Marionette DEBUG 2 -> [0,306,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":["3"],"filename":"../../venv/lib/python2.7/si ... wWithId(Number(arguments[0]));\n return win.document.readyState == 'complete';","sandbox":"default","line":157}]
> [task 2018-12-07T11:45:09.724Z] 11:45:09 INFO - TEST-UNEXPECTED-ERROR | testing/firefox-ui/tests/functional/safebrowsing/test_notification.py TestSafeBrowsingNotificationBar.test_notification_bar | UnknownException: Reached error page: about:neterror?e=networkProtocolError&u=https%3A//www.itisatrap.org/firefox/its-an-attack.html&c=UTF-8&f=regular&d=Firefox%20has%20experienced%20a%20network%20protocol%20violation%20that%20cannot%20be%20repaired. The above message shows that there is a network protocol violation, which cannot be repaired. Francois, do you have an idea? Maybe this is caused by some new feature?
Flags: needinfo?(francois)
Redirecting to Dimi since I have left Mozilla.
Flags: needinfo?(francois) → needinfo?(dlee)
Hi Henrik, I am on PTO until 1 Jan, I'll check this when I am back.
I'll guess perhaps something went wrong while fetching about:blocked, it only happened two times for the past 30 days so I guess we can just ignore it for now.
Flags: needinfo?(dlee)
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.