Closed
Bug 1825858
Opened 1 year ago
Closed 1 year ago
Intermittent /webdriver/tests/navigate_to/file.py | test_file_protocol - AssertionError: timeout (500): TimedPromise timed out after 3000 ms
Categories
(Remote Protocol :: Marionette, defect, P5)
Remote Protocol
Marionette
Tracking
(Not tracked)
RESOLVED
DUPLICATE
of bug 1760291
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: assertion, intermittent-failure)
Filed by: nerli [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=410988175&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Wj46qD8FSYuzaFI_9fYF6g/runs/0/artifacts/public/logs/live_backing.log
[task 2023-03-31T18:30:06.784Z] 18:30:06 INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/navigate_to/file.py | test_file_protocol - AssertionError: timeout (500): TimedPromise timed out after 3000 ms
[task 2023-03-31T18:30:06.784Z] 18:30:06 INFO - session = <Session a207e618-e100-46e9-b472-515ac8da481d>
[task 2023-03-31T18:30:06.784Z] 18:30:06 INFO - server_config = {'aliases': [], 'all_domains': {'': {'': 'web-platform.test', 'nonexistent': 'nonexistent.web-platform.test', 'nonexis...t', 'web-platform.test', 'www2.www1.web-platform.test', ...], 'alternate_hosts': {'alt': 'not-web-platform.test'}, ...}
[task 2023-03-31T18:30:06.784Z] 18:30:06 INFO -
[task 2023-03-31T18:30:06.784Z] 18:30:06 INFO - def test_file_protocol(session, server_config):
[task 2023-03-31T18:30:06.784Z] 18:30:06 INFO - # tests that the browsing context remains the same
[task 2023-03-31T18:30:06.784Z] 18:30:06 INFO - # when navigated privileged documents
[task 2023-03-31T18:30:06.784Z] 18:30:06 INFO - path = server_config["doc_root"]
[task 2023-03-31T18:30:06.784Z] 18:30:06 INFO - if platform_name == "windows":
[task 2023-03-31T18:30:06.784Z] 18:30:06 INFO - # Convert the path into the format eg. /c:/foo/bar
[task 2023-03-31T18:30:06.784Z] 18:30:06 INFO - path = "/{}".format(path.replace("\\", "/"))
[task 2023-03-31T18:30:06.785Z] 18:30:06 INFO - url = u"file://{}".format(path)
[task 2023-03-31T18:30:06.785Z] 18:30:06 INFO -
[task 2023-03-31T18:30:06.785Z] 18:30:06 INFO - response = navigate_to(session, url)
[task 2023-03-31T18:30:06.785Z] 18:30:06 INFO - > assert_success(response)
[task 2023-03-31T18:30:06.785Z] 18:30:06 INFO -
[task 2023-03-31T18:30:06.785Z] 18:30:06 INFO - path = '/opt/worker/tasks/task_168028579458801/build/tests/web-platform/tests'
[task 2023-03-31T18:30:06.785Z] 18:30:06 INFO - response = <Response status=500 error=<TimeoutException http_status=500>>
[task 2023-03-31T18:30:06.785Z] 18:30:06 INFO - server_config = {'aliases': [],
[task 2023-03-31T18:30:06.785Z] 18:30:06 INFO - 'all_domains': {'': {'': 'web-platform.test',
[task 2023-03-31T18:30:06.785Z] 18:30:06 INFO - 'nonexistent': 'nonexistent.web-platform.test',
[task 2023-03-31T18:30:06.785Z] 18:30:06 INFO - 'nonexistent.nonexistent': 'nonexistent.nonexistent.web-platform.test',
[task 2023-03-31T18:30:06.785Z] 18:30:06 INFO - 'www': 'www.web-platform.test',
[task 2023-03-31T18:30:06.785Z] 18:30:06 INFO - 'www.www': 'www.www.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'www.www1': 'www.www1.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'www.www2': 'www.www2.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'www.élève': 'www.xn--lve-6lad.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'www.天気の良い日': 'www.xn--n8j6ds53lwwkrqhv28a.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'www1': 'www1.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'www1.www': 'www1.www.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'www1.www1': 'www1.www1.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'www1.www2': 'www1.www2.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'www1.élève': 'www1.xn--lve-6lad.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'www1.天気の良い日': 'www1.xn--n8j6ds53lwwkrqhv28a.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'www2': 'www2.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'www2.www': 'www2.www.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'www2.www1': 'www2.www1.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'www2.www2': 'www2.www2.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'www2.élève': 'www2.xn--lve-6lad.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'www2.天気の良い日': 'www2.xn--n8j6ds53lwwkrqhv28a.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'élève': 'xn--lve-6lad.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'élève.www': 'xn--lve-6lad.www.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'élève.www1': 'xn--lve-6lad.www1.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'élève.www2': 'xn--lve-6lad.www2.web-platform.test',
[task 2023-03-31T18:30:06.786Z] 18:30:06 INFO - 'élève.élève': 'xn--lve-6lad.xn--lve-6lad.web-platform.test',
[task 2023-03-31T18:30:06.787Z] 18:30:06 INFO - 'élève.天気の良い日': 'xn--lve-6lad.xn--n8j6ds53lwwkrqhv28a.web-platform.test',
[task 2023-03-31T18:30:06.787Z] 18:30:06 INFO - '天気の良い日': 'xn--n8j6ds53lwwkrqhv28a.web-platform.test',
[task 2023-03-31T18:30:06.787Z] 18:30:06 INFO - '天気の良い日.www': 'xn--n8j6ds53lwwkrqhv28a.www.web-platform.test',
[task 2023-03-31T18:30:06.787Z] 18:30:06 INFO - '天気の良い日.www1': 'xn--n8j6ds53lwwkrqhv28a.www1.web-platform.test',
[task 2023-03-31T18:30:06.787Z] 18:30:06 INFO - '天気の良い日.www2': 'xn--n8j6ds53lwwkrqhv28a.www2.web-platform.test',
[task 2023-03-31T18:30:06.787Z] 18:30:06 INFO - '天気の良い日.élève': 'xn--n8j6ds53lwwkrqhv28a.xn--lve-6lad.web-platform.test',
[task 2023-03-31T18:30:06.787Z] 18:30:06 INFO - '天気の良い日.天気の良い日': 'xn--n8j6ds53lwwkrqhv28a.xn--n8j6ds53lwwkrqhv28a.web-platform.test'},
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•1 year ago
|
||
The navigation timed out after 3s. Here the exact log:
[task 2023-03-31T18:30:01.585Z] 18:30:01 INFO - PID 3046 | 1680287401581 webdriver::server DEBUG -> POST /session/a207e618-e100-46e9-b472-515ac8da481d/url {"url": "file:///opt/worker/tasks/task_168028579458801/build/tests/web-platform/tests"}
[task 2023-03-31T18:30:01.585Z] 18:30:01 INFO - PID 3046 | 1680287401582 Marionette DEBUG 0 -> [0,7,"WebDriver:Navigate",{"url":"file:///opt/worker/tasks/task_168028579458801/build/tests/web-platform/tests"}]
[task 2023-03-31T18:30:01.585Z] 18:30:01 INFO - PID 3046 | 1680287401584 Marionette TRACE [4] Received event beforeunload for about:blank
[task 2023-03-31T18:30:04.544Z] 18:30:04 INFO - PID 3046 | 1680287404544 Marionette TRACE Remoteness change detected. Set new top-level browsing context to 11
[task 2023-03-31T18:30:04.597Z] 18:30:04 INFO - PID 3046 | 1680287404596 Marionette TRACE [11] Received event beforeunload for about:blank
[task 2023-03-31T18:30:04.603Z] 18:30:04 INFO - PID 3046 | 1680287404603 Marionette TRACE [11] Received event pagehide for about:blank
[task 2023-03-31T18:30:04.729Z] 18:30:04 INFO - PID 3046 | 1680287404727 Marionette DEBUG 0 <- [1,7,{"error":"timeout","message":"TimedPromise timed out after 3000 ms","stacktrace":"RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8\nWebDriverError@chrome://remote/content/shared/webdriver/Er ... or@chrome://remote/content/shared/webdriver/Errors.sys.mjs:492:5\nbail@chrome://remote/content/marionette/sync.sys.mjs:211:19\n"},null]
As it looks like wptserve maybe didn't serve the page.
With bug 1826048 we will get a better error message so that we will have to update the summary once it has been landed.
Depends on: 1760291
|
|
||
Updated•1 year ago
|
You need to log in
before you can comment on or make changes to this bug.
Description
•