Closed Bug 1406866 Opened 7 years ago Closed 7 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/minimize_window.py | expected OK

Categories

(Remote Protocol :: Marionette, defect, P5)

Version 3
defect

Tracking

(firefox61 fixed)

RESOLVED FIXED
mozilla61
Tracking Status
firefox61 --- fixed

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: archaeopteryx [at] coole-files.de https://treeherder.mozilla.org/logviewer.html#?job_id=135605563&repo=autoland https://queue.taskcluster.net/v1/task/Cot9CNbrQOyAaWJKvSA6JQ/runs/0/artifacts/public/logs/live_backing.log [task 2017-10-09T00:01:47.492Z] 00:01:47 INFO - STDOUT: FAILED [task 2017-10-09T00:01:47.512Z] 00:01:47 INFO - PID 1582 | 1507507307500 Marionette INFO New connections will no longer be accepted [task 2017-10-09T00:01:48.053Z] 00:01:48 INFO - PID 1582 | [Child 2258] WARNING: pipe error (17): Connection reset by peer: file /build/firefox-S0wyi8/firefox-55.0.2+build1/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 353 [task 2017-10-09T00:01:48.053Z] 00:01:48 INFO - PID 1582 | [Child 2258] WARNING: pipe error (3): Connection reset by peer: file /build/firefox-S0wyi8/firefox-55.0.2+build1/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 353 [task 2017-10-09T00:01:48.134Z] 00:01:48 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/minimize_window.py::test_handle_prompt_missing_value [task 2017-10-09T00:01:48.143Z] 00:01:48 INFO - PID 1582 | 1507507308136 mozrunner::runner INFO Running command: "/builds/worker/workspace/build/application/firefox/firefox" "-marionette" "-profile" "/tmp/rust_mozprofile.JvaRdRmPtib3" [task 2017-10-09T00:01:50.780Z] 00:01:50 INFO - PID 1582 | 1507507310770 Marionette INFO Enabled via --marionette [task 2017-10-09T00:01:56.481Z] 00:01:56 INFO - PID 1582 | 1507507316466 Marionette INFO Listening on port 2828 [task 2017-10-09T00:01:56.618Z] 00:01:56 INFO - PID 1582 | 1507507316611 Marionette DEBUG Register listener.js for window 2147483649 [task 2017-10-09T00:01:56.646Z] 00:01:56 INFO - PID 1582 | 1507507316641 Marionette DEBUG Received DOM event "beforeunload" for "about:blank" [task 2017-10-09T00:01:56.727Z] 00:01:56 INFO - PID 1582 | 1507507316719 Marionette DEBUG Received DOM event "pagehide" for "about:blank" [task 2017-10-09T00:01:56.729Z] 00:01:56 INFO - PID 1582 | 1507507316720 Marionette DEBUG Received DOM event "unload" for "about:blank" [task 2017-10-09T00:01:56.731Z] 00:01:56 INFO - PID 1582 | 1507507316726 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://web-platform.test:8000/webdriver/tests/support/inline.py?doc=%3Ctitle%3EWD+doc+title%3C%2Ftitle%3E&content-type=text%2Fhtml%3Bcharset%3Dutf-8" [task 2017-10-09T00:01:56.733Z] 00:01:56 INFO - PID 1582 | 1507507316728 Marionette DEBUG Received DOM event "pageshow" for "http://web-platform.test:8000/webdriver/tests/support/inline.py?doc=%3Ctitle%3EWD+doc+title%3C%2Ftitle%3E&content-type=text%2Fhtml%3Bcharset%3Dutf-8" [task 2017-10-09T00:01:57.327Z] 00:01:57 INFO - STDOUT: FAILED [task 2017-10-09T00:01:57.327Z] 00:01:57 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/minimize_window.py::test_fully_exit_fullscreen [task 2017-10-09T00:01:57.328Z] 00:01:57 INFO - STDOUT: PASSED [task 2017-10-09T00:01:57.331Z] 00:01:57 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/minimize_window.py::test_minimize [task 2017-10-09T00:01:57.331Z] 00:01:57 INFO - STDOUT: PASSED [task 2017-10-09T00:01:57.331Z] 00:01:57 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/minimize_window.py::test_payload [task 2017-10-09T00:01:57.334Z] 00:01:57 INFO - STDOUT: PASSED [task 2017-10-09T00:01:57.335Z] 00:01:57 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/minimize_window.py::test_minimize_twice_is_idempotent [task 2017-10-09T00:01:57.335Z] 00:01:57 INFO - STDOUT: PASSED [task 2017-10-09T00:01:57.337Z] 00:01:57 INFO - STDOUT: =================================== FAILURES =================================== [task 2017-10-09T00:01:57.337Z] 00:01:57 INFO - STDOUT: __________________________ test_handle_prompt_accept ___________________________ [task 2017-10-09T00:01:57.338Z] 00:01:57 INFO - STDOUT: new_session = <function create_session at 0x7ff23557acf8> [task 2017-10-09T00:01:57.338Z] 00:01:57 INFO - STDOUT: def test_handle_prompt_accept(new_session): [task 2017-10-09T00:01:57.339Z] 00:01:57 INFO - STDOUT: """ [task 2017-10-09T00:01:57.341Z] 00:01:57 INFO - STDOUT: 2. Handle any user prompts and return its value if it is an error. [task 2017-10-09T00:01:57.344Z] 00:01:57 INFO - STDOUT: [task 2017-10-09T00:01:57.345Z] 00:01:57 INFO - STDOUT: [...] [task 2017-10-09T00:01:57.346Z] 00:01:57 INFO - STDOUT: [task 2017-10-09T00:01:57.346Z] 00:01:57 INFO - STDOUT: In order to handle any user prompts a remote end must take the [task 2017-10-09T00:01:57.347Z] 00:01:57 INFO - STDOUT: following steps: [task 2017-10-09T00:01:57.348Z] 00:01:57 INFO - STDOUT: [task 2017-10-09T00:01:57.349Z] 00:01:57 INFO - STDOUT: [...] [task 2017-10-09T00:01:57.350Z] 00:01:57 INFO - STDOUT: [task 2017-10-09T00:01:57.350Z] 00:01:57 INFO - STDOUT: 2. Perform the following substeps based on the current session's [task 2017-10-09T00:01:57.351Z] 00:01:57 INFO - STDOUT: user prompt handler: [task 2017-10-09T00:01:57.352Z] 00:01:57 INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/minimize_window.py | expected OK [task 2017-10-09T00:01:57.353Z] 00:01:57 INFO - TEST-INFO took 30538ms [task 2017-10-09T00:01:57.353Z] 00:01:57 INFO - STDOUT: [task 2017-10-09T00:01:57.354Z] 00:01:57 INFO - STDOUT: [...] [task 2017-10-09T00:01:57.355Z] 00:01:57 INFO - STDOUT: [task 2017-10-09T00:01:57.356Z] 00:01:57 INFO - STDOUT: - accept state [task 2017-10-09T00:01:57.357Z] 00:01:57 INFO - STDOUT: Accept the current user prompt. [task 2017-10-09T00:01:57.358Z] 00:01:57 INFO - STDOUT: [task 2017-10-09T00:01:57.359Z] 00:01:57 INFO - STDOUT: """ [task 2017-10-09T00:01:57.360Z] 00:01:57 INFO - STDOUT: _, session = new_session({"alwaysMatch": {"unhandledPromptBehavior": "accept"}}) [task 2017-10-09T00:01:57.361Z] 00:01:57 INFO - STDOUT: > session.url = inline("<title>WD doc title</title>") [task 2017-10-09T00:01:57.361Z] 00:01:57 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/minimize_window.py:61: [task 2017-10-09T00:01:57.362Z] 00:01:57 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ [task 2017-10-09T00:01:57.363Z] 00:01:57 INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py:25: in inner [task 2017-10-09T00:01:57.363Z] 00:01:57 INFO - STDOUT: return func(self, *args, **kwargs) [task 2017-10-09T00:01:57.363Z] 00:01:57 INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py:479: in url [task 2017-10-09T00:01:57.364Z] 00:01:57 INFO - STDOUT: return self.send_session_command("POST", "url", body) [task 2017-10-09T00:01:57.364Z] 00:01:57 INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py:466: in send_session_command [task 2017-10-09T00:01:57.365Z] 00:01:57 INFO - STDOUT: return self.send_command(method, url, body) [task 2017-10-09T00:01:57.366Z] 00:01:57 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ [task 2017-10-09T00:01:57.366Z] 00:01:57 INFO - STDOUT: self = <webdriver.client.Session object at 0x7ff23c0fd650>, method = 'POST' [task 2017-10-09T00:01:57.367Z] 00:01:57 INFO - STDOUT: url = 'session/75066785-d0d5-400d-940f-dd694549b185/url' [task 2017-10-09T00:01:57.367Z] 00:01:57 INFO - STDOUT: body = {'url': 'http://web-platform.test:8000/webdriver/tests/support/inline.py?doc=%3Ctitle%3EWD+doc+title%3C%2Ftitle%3E&content-type=text%2Fhtml%3Bcharset%3Dutf-8'} [task 2017-10-09T00:01:57.368Z] 00:01:57 INFO - STDOUT: def send_command(self, method, url, body=None): [task 2017-10-09T00:01:57.368Z] 00:01:57 INFO - STDOUT: """ [task 2017-10-09T00:01:57.369Z] 00:01:57 INFO - STDOUT: Send a command to the remote end and validate its success. [task 2017-10-09T00:01:57.369Z] 00:01:57 INFO - STDOUT: [task 2017-10-09T00:01:57.370Z] 00:01:57 INFO - STDOUT: :param method: HTTP method to use in request. [task 2017-10-09T00:01:57.370Z] 00:01:57 INFO - STDOUT: :param uri: "Command part" of the HTTP request URL, [task 2017-10-09T00:01:57.371Z] 00:01:57 INFO - STDOUT: e.g. `window/rect`. [task 2017-10-09T00:01:57.371Z] 00:01:57 INFO - STDOUT: :param body: Optional body of the HTTP request. [task 2017-10-09T00:01:57.372Z] 00:01:57 INFO - STDOUT: [task 2017-10-09T00:01:57.372Z] 00:01:57 INFO - STDOUT: :return: `None` if the HTTP response body was empty, otherwise [task 2017-10-09T00:01:57.373Z] 00:01:57 INFO - STDOUT: the result of parsing the body as JSON. [task 2017-10-09T00:01:57.373Z] 00:01:57 INFO - STDOUT: [task 2017-10-09T00:01:57.374Z] 00:01:57 INFO - STDOUT: :raises error.WebDriverException: If the remote end returns [task 2017-10-09T00:01:57.374Z] 00:01:57 INFO - STDOUT: an error. [task 2017-10-09T00:01:57.374Z] 00:01:57 INFO - STDOUT: """ [task 2017-10-09T00:01:57.375Z] 00:01:57 INFO - STDOUT: response = self.transport.send(method, url, body) [task 2017-10-09T00:01:57.375Z] 00:01:57 INFO - STDOUT: [task 2017-10-09T00:01:57.375Z] 00:01:57 INFO - STDOUT: if "value" in response.body: [task 2017-10-09T00:01:57.376Z] 00:01:57 INFO - STDOUT: value = response.body["value"] [task 2017-10-09T00:01:57.376Z] 00:01:57 INFO - STDOUT: else: [task 2017-10-09T00:01:57.377Z] 00:01:57 INFO - STDOUT: raise error.UnknownErrorException("No 'value' key in response body:\n%s" % [task 2017-10-09T00:01:57.377Z] 00:01:57 INFO - STDOUT: json.dumps(response.body)) [task 2017-10-09T00:01:57.377Z] 00:01:57 INFO - STDOUT: [task 2017-10-09T00:01:57.378Z] 00:01:57 INFO - STDOUT: if response.status != 200: [task 2017-10-09T00:01:57.378Z] 00:01:57 INFO - STDOUT: cls = error.get(value.get("error")) [task 2017-10-09T00:01:57.378Z] 00:01:57 INFO - STDOUT: > raise cls(value.get("message")) [task 2017-10-09T00:01:57.379Z] 00:01:57 INFO - STDOUT: E UnknownErrorException: Reached error page: about:neterror?e=dnsNotFound&u=http%3A//web-platform.test%3A8000/webdriver/tests/support/inline.py%3Fdoc%3D%253Ctitle%253EWD+doc+title%253C%252Ftitle%253E%26content-type%3Dtext%252Fhtml%253Bcharset%253Dutf-8&c=UTF-8&f=regular&d=Firefox%20can%E2%80%99t%20find%20the%20server%20at%20web-platform.test. [task 2017-10-09T00:01:57.379Z] 00:01:57 INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py:440: UnknownErrorException
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
It's not clearly showing a hang in Workers but I would assume it's also dependent on bug 1425559. [task 2018-01-12T03:44:39.790Z] 03:44:39 INFO - PID 2358 | [Parent 2870, IPDL Background] WARNING: Some storage operations are taking longer than expected during shutdown and will be aborted!: file /builds/worker/workspace/build/src/dom/quota/ActorsParent.cpp, line 5824 [task 2018-01-12T03:44:57.991Z] 03:44:57 INFO - PID 2358 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping [task 2018-01-12T03:45:02.084Z] 03:45:02 INFO - PID 2358 | [Parent 2870, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/dom/xhr/XMLHttpRequestMainThread.cpp, line 2744 [task 2018-01-12T03:45:02.084Z] 03:45:02 INFO - PID 2358 | [Parent 2870, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/dom/xhr/XMLHttpRequestMainThread.cpp, line 2744 [task 2018-01-12T03:45:02.085Z] 03:45:02 INFO - PID 2358 | [Parent 2870, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/dom/xhr/XMLHttpRequestMainThread.cpp, line 2744 [task 2018-01-12T03:45:02.085Z] 03:45:02 INFO - PID 2358 | [Parent 2870, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/dom/xhr/XMLHttpRequestMainThread.cpp, line 2744 [task 2018-01-12T03:45:02.086Z] 03:45:02 INFO - PID 2358 | [Parent 2870, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/dom/xhr/XMLHttpRequestMainThread.cpp, line 2744 [task 2018-01-12T03:45:02.086Z] 03:45:02 INFO - PID 2358 | [Parent 2870, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/dom/xhr/XMLHttpRequestMainThread.cpp, line 2744 [task 2018-01-12T03:45:12.735Z] 03:45:12 INFO - PID 2358 | Hit MOZ_CRASH(Shutdown too long, probably frozen, causing a crash.) at /builds/worker/workspace/build/src/toolkit/components/terminator/nsTerminator.cpp:163 [task 2018-01-12T03:45:12.736Z] 03:45:12 INFO - PID 2358 | #01: ???[/builds/worker/workspace/build/application/firefox/libnspr4.so +0x286e6] [task 2018-01-12T03:45:12.737Z] 03:45:12 INFO - PID 2358 | #02: ???[/lib/x86_64-linux-gnu/libpthread.so.0 +0x76ba] [task 2018-01-12T03:45:12.737Z] 03:45:12 INFO - PID 2358 | #03: clone[/lib/x86_64-linux-gnu/libc.so.6 +0x1073dd] [task 2018-01-12T03:45:12.737Z] 03:45:12 INFO - PID 2358 | #04: ??? (???:???) [task 2018-01-12T03:45:12.737Z] 03:45:12 INFO - PID 2358 | ExceptionHandler::GenerateDump cloned child 3016 [task 2018-01-12T03:45:12.738Z] 03:45:12 INFO - PID 2358 | ExceptionHandler::SendContinueSignalToChild sent continue signal to child [task 2018-01-12T03:45:12.738Z] 03:45:12 INFO - PID 2358 | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
Flags: needinfo?(amarchesini)
This test module has the prompt tests included which restart Firefox. So the timeout should be related to bug 1449538.
Depends on: 1449538
Flags: needinfo?(amarchesini)
Fixed by bug 1449538.
Status: REOPENED → RESOLVED
Closed: 7 years ago7 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla61
Moving bug to Remote Protocol::Marionette component per bug 1815831.
Component: geckodriver → Marionette
Product: Testing → Remote Protocol
You need to log in before you can comment on or make changes to this bug.