Closed
Bug 1767137
Opened 4 years ago
Closed 4 years ago
Intermittent testing/firefox-ui/tests/functional/safebrowsing/test_warning_pages.py TestSafeBrowsingWarningPages.test_warning_pages | marionette_driver.errors.TimeoutException: TimedPromise timed out after 300000 ms
Categories
(Testing :: Firefox UI Tests, defect, P5)
Testing
Firefox UI Tests
Tracking
(Not tracked)
RESOLVED
DUPLICATE
of bug 1752259
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=376351327&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Q0ShM1KsRy-IH7LSxFdVxg/runs/0/artifacts/public/logs/live_backing.log
[task 2022-04-30T05:45:34.161Z] 05:45:34 INFO - TEST-START | testing/firefox-ui/tests/functional/safebrowsing/test_warning_pages.py TestSafeBrowsingWarningPages.test_warning_pages
[task 2022-04-30T05:45:34.169Z] 05:45:34 INFO - 1651297534168 Marionette DEBUG Accepted connection 3 from 127.0.0.1:55706
[task 2022-04-30T05:45:34.174Z] 05:45:34 INFO - 1651297534170 Marionette DEBUG 3 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-04-30T05:45:34.186Z] 05:45:34 INFO - 1651297534186 RemoteAgent TRACE [24] Document already finished loading: about:blank
[task 2022-04-30T05:45:34.190Z] 05:45:34 INFO - 1651297534189 Marionette DEBUG 3 <- [1,1,null,{"sessionId":"6771e722-eb86-4d99-9f82-fe3c8a065036","capabilities":{"browserName":"firefox","browserVersion":"101.0 ... .mozrunner","moz:shutdownTimeout":300000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2022-04-30T05:45:34.197Z] 05:45:34 INFO - 1651297534197 Marionette DEBUG 3 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2022-04-30T05:45:34.199Z] 05:45:34 INFO - 1651297534198 Marionette DEBUG 3 <- [1,2,null,{"value":null}]
[task 2022-04-30T05:45:34.200Z] 05:45:34 INFO - 1651297534200 Marionette DEBUG 3 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2022-04-30T05:45:34.202Z] 05:45:34 INFO - 1651297534201 Marionette DEBUG 3 <- [1,3,null,{"value":null}]
[task 2022-04-30T05:45:34.203Z] 05:45:34 INFO - 1651297534203 Marionette DEBUG 3 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2022-04-30T05:45:34.205Z] 05:45:34 INFO - 1651297534204 Marionette DEBUG 3 <- [1,4,null,{"value":null}]
[task 2022-04-30T05:45:34.206Z] 05:45:34 INFO - 1651297534206 Marionette DEBUG 3 -> [0,5,"Marionette:GetContext",{}]
[task 2022-04-30T05:45:34.207Z] 05:45:34 INFO - 1651297534207 Marionette DEBUG 3 <- [1,5,null,{"value":"content"}]
[task 2022-04-30T05:45:34.209Z] 05:45:34 INFO - 1651297534209 Marionette DEBUG 3 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-30T05:45:34.210Z] 05:45:34 INFO - 1651297534210 Marionette DEBUG 3 <- [1,6,null,{"value":null}]
[task 2022-04-30T05:45:34.212Z] 05:45:34 INFO - 1651297534212 Marionette DEBUG 3 -> [0,7,"WebDriver:GetWindowHandle",{}]
[task 2022-04-30T05:45:34.214Z] 05:45:34 INFO - 1651297534214 Marionette DEBUG 3 <- [1,7,null,{"value":"e3092281-5fc5-41a3-82a8-5cdcaaa3796a"}]
[task 2022-04-30T05:45:34.216Z] 05:45:34 INFO - 1651297534215 Marionette DEBUG 3 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-30T05:45:34.217Z] 05:45:34 INFO - 1651297534217 Marionette DEBUG 3 <- [1,8,null,{"value":null}]
[task 2022-04-30T05:45:34.219Z] 05:45:34 INFO - 1651297534219 Marionette DEBUG 3 -> [0,9,"Marionette:GetContext",{}]
[task 2022-04-30T05:45:34.221Z] 05:45:34 INFO - 1651297534220 Marionette DEBUG 3 <- [1,9,null,{"value":"content"}]
[task 2022-04-30T05:45:34.223Z] 05:45:34 INFO - 1651297534222 Marionette DEBUG 3 -> [0,10,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-30T05:45:34.224Z] 05:45:34 INFO - 1651297534224 Marionette DEBUG 3 <- [1,10,null,{"value":null}]
[task 2022-04-30T05:45:34.226Z] 05:45:34 INFO - 1651297534226 Marionette DEBUG 3 -> [0,11,"WebDriver:GetWindowHandles",{}]
[task 2022-04-30T05:45:34.228Z] 05:45:34 INFO - 1651297534227 Marionette DEBUG 3 <- [1,11,null,["e3092281-5fc5-41a3-82a8-5cdcaaa3796a"]]
[task 2022-04-30T05:45:34.229Z] 05:45:34 INFO - 1651297534229 Marionette DEBUG 3 -> [0,12,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-30T05:45:34.231Z] 05:45:34 INFO - 1651297534230 Marionette DEBUG 3 <- [1,12,null,{"value":null}]
[task 2022-04-30T05:45:34.234Z] 05:45:34 INFO - 1651297534234 Marionette DEBUG 3 -> [0,13,"Marionette:GetContext",{}]
[task 2022-04-30T05:45:34.235Z] 05:45:34 INFO - 1651297534235 Marionette DEBUG 3 <- [1,13,null,{"value":"content"}]
[task 2022-04-30T05:45:34.237Z] 05:45:34 INFO - 1651297534237 Marionette DEBUG 3 -> [0,14,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-30T05:45:34.239Z] 05:45:34 INFO - 1651297534238 Marionette DEBUG 3 <- [1,14,null,{"value":null}]
[task 2022-04-30T05:45:34.249Z] 05:45:34 INFO - 1651297534249 Marionette DEBUG 3 -> [0,15,"WebDriver:GetWindowHandle",{}]
[task 2022-04-30T05:45:34.253Z] 05:45:34 INFO - 1651297534249 Marionette DEBUG 3 <- [1,15,null,{"value":"0aa51c06-95a9-4b34-9e86-4e863d1e045d"}]
[task 2022-04-30T05:45:34.257Z] 05:45:34 INFO - 1651297534254 Marionette DEBUG 3 -> [0,16,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-30T05:45:34.261Z] 05:45:34 INFO - 1651297534254 Marionette DEBUG 3 <- [1,16,null,{"value":null}]
[task 2022-04-30T05:45:34.267Z] 05:45:34 INFO - 1651297534258 Marionette DEBUG 3 -> [0,17,"Marionette:GetContext",{}]
[task 2022-04-30T05:45:34.268Z] 05:45:34 INFO - 1651297534258 Marionette DEBUG 3 <- [1,17,null,{"value":"content"}]
[task 2022-04-30T05:45:34.268Z] 05:45:34 INFO - 1651297534264 Marionette DEBUG 3 -> [0,18,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-30T05:45:34.269Z] 05:45:34 INFO - 1651297534264 Marionette DEBUG 3 <- [1,18,null,{"value":null}]
[task 2022-04-30T05:45:34.269Z] 05:45:34 INFO - 1651297534266 Marionette DEBUG 3 -> [0,19,"WebDriver:GetWindowHandles",{}]
[task 2022-04-30T05:45:34.270Z] 05:45:34 INFO - 1651297534266 Marionette DEBUG 3 <- [1,19,null,["0aa51c06-95a9-4b34-9e86-4e863d1e045d"]]
[task 2022-04-30T05:45:34.271Z] 05:45:34 INFO - 1651297534267 Marionette DEBUG 3 -> [0,20,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-30T05:45:34.272Z] 05:45:34 INFO - 1651297534268 Marionette DEBUG 3 <- [1,20,null,{"value":null}]
[task 2022-04-30T05:45:34.272Z] 05:45:34 INFO - 1651297534269 Marionette DEBUG 3 -> [0,21,"Marionette:GetContext",{}]
[task 2022-04-30T05:45:34.273Z] 05:45:34 INFO - 1651297534269 Marionette DEBUG 3 <- [1,21,null,{"value":"content"}]
[task 2022-04-30T05:45:34.274Z] 05:45:34 INFO - 1651297534271 Marionette DEBUG 3 -> [0,22,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-30T05:45:34.275Z] 05:45:34 INFO - 1651297534272 Marionette DEBUG 3 <- [1,22,null,{"value":null}]
[task 2022-04-30T05:45:34.276Z] 05:45:34 INFO - 1651297534275 Marionette DEBUG 3 -> [0,23,"WebDriver:ExecuteScript",{"script":"Components.utils.import(\"resource://gre/modules/Preferences.jsm\");\n\n ... ox":true,"sandbox":"default","line":805,"filename":"../../venv/lib/python3.6/site-packages/marionette_driver/marionette.py"}]
[task 2022-04-30T05:45:34.278Z] 05:45:34 INFO - 1651297534278 Marionette TRACE [7] MarionetteCommands actor created for window id 2
[task 2022-04-30T05:46:01.094Z] 05:46:01 INFO - 1651297561091 Marionette DEBUG 3 -> [0,153,"WebDriver:Navigate",{"url":"https://www.itisatrap.org/firefox/its-an-attack.html"}]
[task 2022-04-30T05:46:01.125Z] 05:46:01 INFO - 1651297561124 Marionette TRACE [66] Received event beforeunload for https://www.itisatrap.org/firefox/its-an-attack.html
[task 2022-04-30T05:46:01.214Z] 05:46:01 INFO - 1651297561214 Marionette TRACE [66] Received event pagehide for https://www.itisatrap.org/firefox/its-an-attack.html
[task 2022-04-30T05:46:01.314Z] 05:46:01 INFO - 1651297561313 Marionette TRACE [66] Received event DOMContentLoaded for https://www.itisatrap.org/firefox/its-an-attack.html
[task 2022-04-30T05:51:01.095Z] 05:51:01 INFO - 1651297861094 Marionette DEBUG 3 <- [1,153,{"error":"timeout","message":"TimedPromise timed out after 300000 ms","stacktrace":"WebDriverError@chrome://remote/con ... r@chrome://remote/content/shared/webdriver/Errors.jsm:472:5\nbail@chrome://remote/content/marionette/sync.js:227:19\n"},null]
[task 2022-04-30T05:51:01.099Z] 05:51:01 INFO - 1651297861098 Marionette DEBUG 3 -> [0,154,"Marionette:GetContext",{}]
[task 2022-04-30T05:51:01.103Z] 05:51:01 INFO - 1651297861099 Marionette DEBUG 3 <- [1,154,null,{"value":"content"}]
[task 2022-04-30T05:51:01.107Z] 05:51:01 INFO - 1651297861101 Marionette DEBUG 3 -> [0,155,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-04-30T05:51:01.110Z] 05:51:01 INFO - 1651297861102 Marionette DEBUG 3 <- [1,155,null,{"value":null}]
[task 2022-04-30T05:51:01.112Z] 05:51:01 INFO - 1651297861104 Marionette DEBUG 3 -> [0,156,"WebDriver:TakeScreenshot",{"id":null,"full":true,"hash":false,"scroll":true}]
[task 2022-04-30T05:51:01.323Z] 05:51:01 INFO - 1651297861322 Marionette DEBUG 3 <- [1,156,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAQQCAYAAAC9RfbYAAAgAElEQVR4XuydB5wlVbXuV+fck/OQEYmKcEGR9+69ioACl6BiQrgiWVE ... AsBCAAZ8HERSAAAiAAAiAAAiAAAiAAAiAAAjeKAATgjSqOZoMACGyHAATgdljyJBAAARAAARAAARAAARAAARAAgetD4P8ByLlIu01ns0sAAAAASUVORK5CYII="}]
[task 2022-04-30T05:51:01.352Z] 05:51:01 INFO - 1651297861351 Marionette DEBUG 3 -> [0,157,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-30T05:51:01.354Z] 05:51:01 INFO - 1651297861352 Marionette DEBUG 3 <- [1,157,null,{"value":null}]
[task 2022-04-30T05:51:01.355Z] 05:51:01 INFO - 1651297861355 Marionette DEBUG 3 -> [0,158,"Marionette:GetContext",{}]
[task 2022-04-30T05:51:01.357Z] 05:51:01 INFO - 1651297861356 Marionette DEBUG 3 <- [1,158,null,{"value":"content"}]
[task 2022-04-30T05:51:01.359Z] 05:51:01 INFO - 1651297861359 Marionette DEBUG 3 -> [0,159,"Marionette:SetContext",{"value":"content"}]
[task 2022-04-30T05:51:01.364Z] 05:51:01 INFO - 1651297861360 Marionette DEBUG 3 <- [1,159,null,{"value":null}]
[task 2022-04-30T05:51:01.366Z] 05:51:01 INFO - 1651297861361 Marionette DEBUG 3 -> [0,160,"WebDriver:GetPageSource",{}]
[task 2022-04-30T05:51:01.367Z] 05:51:01 INFO - 1651297861364 Marionette TRACE [66] MarionetteCommands actor created for window id 36507222019
[task 2022-04-30T05:51:01.368Z] 05:51:01 INFO - 1651297861368 Marionette DEBUG 3 <- [1,160,null,{"value":"<html dir=\"ltr\" lang=\"en-US\"><head>\n <meta charset=\"utf-8\">\n\n <title>It's an attack!</ti ... nse v3.0</a> or any later version.</p>\n\n </div>\n </div>\n </div>\n <!-- end #footer -->\n\n\n</body></html>"}]
[task 2022-04-30T05:51:01.778Z] 05:51:01 INFO - TEST-UNEXPECTED-ERROR | testing/firefox-ui/tests/functional/safebrowsing/test_warning_pages.py TestSafeBrowsingWarningPages.test_warning_pages | marionette_driver.errors.TimeoutException: TimedPromise timed out after 300000 ms
[task 2022-04-30T05:51:01.780Z] 05:51:01 INFO - stacktrace:
[task 2022-04-30T05:51:01.780Z] 05:51:01 INFO - WebDriverError@chrome://remote/content/shared/webdriver/Errors.jsm:183:5
[task 2022-04-30T05:51:01.780Z] 05:51:01 INFO - TimeoutError@chrome://remote/content/shared/webdriver/Errors.jsm:472:5
[task 2022-04-30T05:51:01.781Z] 05:51:01 INFO - bail@chrome://remote/content/marionette/sync.js:227:19
[task 2022-04-30T05:51:01.781Z] 05:51:01 INFO - Traceback (most recent call last):
[task 2022-04-30T05:51:01.781Z] 05:51:01 INFO - File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_harness/marionette_test/testcases.py", line 202, in run
[task 2022-04-30T05:51:01.782Z] 05:51:01 INFO - testMethod()
[task 2022-04-30T05:51:01.782Z] 05:51:01 INFO - File "/builds/worker/workspace/build/tests/firefox-ui/tests/testing/firefox-ui/tests/functional/safebrowsing/test_warning_pages.py", line 71, in test_warning_pages
[task 2022-04-30T05:51:01.782Z] 05:51:01 INFO - self.check_ignore_warning_button(unsafe_page)
[task 2022-04-30T05:51:01.783Z] 05:51:01 INFO - File "/builds/worker/workspace/build/tests/firefox-ui/tests/testing/firefox-ui/tests/functional/safebrowsing/test_warning_pages.py", line 139, in check_ignore_warning_button
[task 2022-04-30T05:51:01.783Z] 05:51:01 INFO - self.assertEqual(self.marionette.get_url(), self.get_final_url(unsafe_page))
[task 2022-04-30T05:51:01.783Z] 05:51:01 INFO - File "/builds/worker/workspace/build/tests/firefox-ui/tests/testing/firefox-ui/tests/functional/safebrowsing/test_warning_pages.py", line 74, in get_final_url
[task 2022-04-30T05:51:01.783Z] 05:51:01 INFO - self.marionette.navigate(url)
[task 2022-04-30T05:51:01.783Z] 05:51:01 INFO - File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1551, in navigate
[task 2022-04-30T05:51:01.783Z] 05:51:01 INFO - self._send_message("WebDriver:Navigate", {"url": url})
[task 2022-04-30T05:51:01.784Z] 05:51:01 INFO - File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2022-04-30T05:51:01.784Z] 05:51:01 INFO - return func(*args, **kwargs)
[task 2022-04-30T05:51:01.784Z] 05:51:01 INFO - File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 668, in _send_message
[task 2022-04-30T05:51:01.785Z] 05:51:01 INFO - self._handle_error(err)
[task 2022-04-30T05:51:01.785Z] 05:51:01 INFO - File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 680, in _handle_error
[task 2022-04-30T05:51:01.785Z] 05:51:01 INFO - raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2022-04-30T05:51:01.786Z] 05:51:01 INFO - TEST-INFO took 327211ms
| Comment hidden (Intermittent Failures Robot) |
|
||
Updated•4 years ago
|
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.
Description
•