Closed Bug 1492244 Opened 6 years ago Closed 5 years ago

Intermittent /webdriver/tests/get_element_rect/get.py | test_no_browsing_context - setup error

Categories

(Remote Protocol :: Marionette, defect, P5)

Product:
Remote Protocol
Component:
Marionette
Version:
Version 3
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=199974081&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/ElMPdqWfT9evQbUpSWFZiA/runs/0/artifacts/public/logs/live_backing.log [task 2018-09-18T16:30:30.656Z] 16:30:30 INFO - STDOUT: self = <Session (disconnected)> [task 2018-09-18T16:30:30.657Z] 16:30:30 INFO - STDOUT: url = 'session' [task 2018-09-18T16:30:30.657Z] 16:30:30 INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py [task 2018-09-18T16:30:30.658Z] 16:30:30 INFO - STDOUT: :473: UnknownErrorException [task 2018-09-18T16:30:30.658Z] 16:30:30 INFO - STDOUT: =============================== warnings summary =============================== [task 2018-09-18T16:30:30.658Z] 16:30:30 INFO - STDOUT: <undetermined location> [task 2018-09-18T16:30:30.659Z] 16:30:30 INFO - STDOUT: Module already imported so cannot be rewritten: mozlog [task 2018-09-18T16:30:30.660Z] 16:30:30 INFO - STDOUT: -- Docs: http://doc.pytest.org/en/latest/warnings.html [task 2018-09-18T16:30:30.661Z] 16:30:30 INFO - STDOUT: ================ 3 passed, 1 warnings, 1 error in 70.22 seconds ================ [task 2018-09-18T16:30:30.662Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.663Z] 16:30:30 INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/get_element_rect/get.py | test_no_browsing_context - setup error [task 2018-09-18T16:30:30.664Z] 16:30:30 INFO - capabilities = {} [task 2018-09-18T16:30:30.664Z] 16:30:30 INFO - configuration = {'capabilities': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'pref...b-platform.test,www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test'}}}, 'host': '127.0.0.1', 'port': 4444} [task 2018-09-18T16:30:30.665Z] 16:30:30 INFO - request = <SubRequest 'session' for <Function 'test_no_browsing_context'>> [task 2018-09-18T16:30:30.666Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.666Z] 16:30:30 INFO - @pytest.fixture(scope="function") [task 2018-09-18T16:30:30.667Z] 16:30:30 INFO - def session(capabilities, configuration, request): [task 2018-09-18T16:30:30.668Z] 16:30:30 INFO - """Create and start a session for a test that does not itself test session creation. [task 2018-09-18T16:30:30.668Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.669Z] 16:30:30 INFO - By default the session will stay open after each test, but we always try to start a [task 2018-09-18T16:30:30.670Z] 16:30:30 INFO - new one and assume that if that fails there is already a valid session. This makes it [task 2018-09-18T16:30:30.670Z] 16:30:30 INFO - possible to recover from some errors that might leave the session in a bad state, but [task 2018-09-18T16:30:30.671Z] 16:30:30 INFO - does not demand that we start a new session per test.""" [task 2018-09-18T16:30:30.672Z] 16:30:30 INFO - global _current_session [task 2018-09-18T16:30:30.672Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.673Z] 16:30:30 INFO - # Update configuration capabilities with custom ones from the [task 2018-09-18T16:30:30.674Z] 16:30:30 INFO - # capabilities fixture, which can be set by tests [task 2018-09-18T16:30:30.674Z] 16:30:30 INFO - caps = copy.deepcopy(configuration["capabilities"]) [task 2018-09-18T16:30:30.675Z] 16:30:30 INFO - caps.update(capabilities) [task 2018-09-18T16:30:30.675Z] 16:30:30 INFO - caps = {"alwaysMatch": caps} [task 2018-09-18T16:30:30.676Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.677Z] 16:30:30 INFO - # If there is a session with different capabilities active, end it now [task 2018-09-18T16:30:30.677Z] 16:30:30 INFO - if _current_session is not None and ( [task 2018-09-18T16:30:30.678Z] 16:30:30 INFO - caps != _current_session.requested_capabilities): [task 2018-09-18T16:30:30.678Z] 16:30:30 INFO - _current_session.end() [task 2018-09-18T16:30:30.679Z] 16:30:30 INFO - _current_session = None [task 2018-09-18T16:30:30.679Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.680Z] 16:30:30 INFO - if _current_session is None: [task 2018-09-18T16:30:30.680Z] 16:30:30 INFO - _current_session = webdriver.Session( [task 2018-09-18T16:30:30.681Z] 16:30:30 INFO - configuration["host"], [task 2018-09-18T16:30:30.682Z] 16:30:30 INFO - configuration["port"], [task 2018-09-18T16:30:30.682Z] 16:30:30 INFO - capabilities=caps) [task 2018-09-18T16:30:30.683Z] 16:30:30 INFO - try: [task 2018-09-18T16:30:30.684Z] 16:30:30 INFO - > _current_session.start() [task 2018-09-18T16:30:30.684Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.685Z] 16:30:30 INFO - capabilities = {} [task 2018-09-18T16:30:30.685Z] 16:30:30 INFO - caps = {'alwaysMatch': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'prefs...w1.not-web-platform.test,www.not-web-platform.test,www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test'}}}} [task 2018-09-18T16:30:30.686Z] 16:30:30 INFO - configuration = {'capabilities': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox/firefox', 'pref...b-platform.test,www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test'}}}, 'host': '127.0.0.1', 'port': 4444} [task 2018-09-18T16:30:30.687Z] 16:30:30 INFO - request = <SubRequest 'session' for <Function 'test_no_browsing_context'>> [task 2018-09-18T16:30:30.689Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.690Z] 16:30:30 INFO - tests/web-platform/tests/webdriver/tests/conftest.py:150: [task 2018-09-18T16:30:30.692Z] 16:30:30 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ [task 2018-09-18T16:30:30.692Z] 16:30:30 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:422: in start [task 2018-09-18T16:30:30.693Z] 16:30:30 INFO - value = self.send_command("POST", "session", body=body) [task 2018-09-18T16:30:30.694Z] 16:30:30 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ [task 2018-09-18T16:30:30.695Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.695Z] 16:30:30 INFO - self = <Session (disconnected)>, method = 'POST', url = 'session' [task 2018-09-18T16:30:30.697Z] 16:30:30 INFO - body = {'capabilities': {'alwaysMatch': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox...1.not-web-platform.test,www.not-web-platform.test,www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test'}}}}} [task 2018-09-18T16:30:30.698Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.698Z] 16:30:30 INFO - def send_command(self, method, url, body=None): [task 2018-09-18T16:30:30.699Z] 16:30:30 INFO - """ [task 2018-09-18T16:30:30.699Z] 16:30:30 INFO - Send a command to the remote end and validate its success. [task 2018-09-18T16:30:30.700Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.700Z] 16:30:30 INFO - :param method: HTTP method to use in request. [task 2018-09-18T16:30:30.701Z] 16:30:30 INFO - :param uri: "Command part" of the HTTP request URL, [task 2018-09-18T16:30:30.701Z] 16:30:30 INFO - e.g. `window/rect`. [task 2018-09-18T16:30:30.701Z] 16:30:30 INFO - :param body: Optional body of the HTTP request. [task 2018-09-18T16:30:30.702Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.702Z] 16:30:30 INFO - :return: `None` if the HTTP response body was empty, otherwise [task 2018-09-18T16:30:30.703Z] 16:30:30 INFO - the `value` field returned after parsing the response [task 2018-09-18T16:30:30.703Z] 16:30:30 INFO - body as JSON. [task 2018-09-18T16:30:30.704Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.704Z] 16:30:30 INFO - :raises error.WebDriverException: If the remote end returns [task 2018-09-18T16:30:30.704Z] 16:30:30 INFO - an error. [task 2018-09-18T16:30:30.705Z] 16:30:30 INFO - :raises ValueError: If the response body does not contain a [task 2018-09-18T16:30:30.705Z] 16:30:30 INFO - `value` key. [task 2018-09-18T16:30:30.706Z] 16:30:30 INFO - """ [task 2018-09-18T16:30:30.706Z] 16:30:30 INFO - response = self.transport.send( [task 2018-09-18T16:30:30.706Z] 16:30:30 INFO - method, url, body, [task 2018-09-18T16:30:30.707Z] 16:30:30 INFO - encoder=protocol.Encoder, decoder=protocol.Decoder, [task 2018-09-18T16:30:30.709Z] 16:30:30 INFO - session=self) [task 2018-09-18T16:30:30.709Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.709Z] 16:30:30 INFO - if response.status != 200: [task 2018-09-18T16:30:30.709Z] 16:30:30 INFO - err = error.from_response(response) [task 2018-09-18T16:30:30.709Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.709Z] 16:30:30 INFO - if isinstance(err, error.InvalidSessionIdException): [task 2018-09-18T16:30:30.709Z] 16:30:30 INFO - # The driver could have already been deleted the session. [task 2018-09-18T16:30:30.711Z] 16:30:30 INFO - self.session_id = None [task 2018-09-18T16:30:30.711Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.711Z] 16:30:30 INFO - > raise err [task 2018-09-18T16:30:30.712Z] 16:30:30 INFO - E UnknownErrorException: unknown error (500): connection refused [task 2018-09-18T16:30:30.712Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.712Z] 16:30:30 INFO - body = {'capabilities': {'alwaysMatch': {'moz:firefoxOptions': {'binary': '/builds/worker/workspace/build/application/firefox...1.not-web-platform.test,www.not-web-platform.test,www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test'}}}}} [task 2018-09-18T16:30:30.712Z] 16:30:30 INFO - err = <UnknownErrorException http_status=500> [task 2018-09-18T16:30:30.712Z] 16:30:30 INFO - method = 'POST' [task 2018-09-18T16:30:30.712Z] 16:30:30 INFO - response = <Response status=500 error=<UnknownErrorException http_status=500>> [task 2018-09-18T16:30:30.714Z] 16:30:30 INFO - self = <Session (disconnected)> [task 2018-09-18T16:30:30.714Z] 16:30:30 INFO - url = 'session' [task 2018-09-18T16:30:30.714Z] 16:30:30 INFO - [task 2018-09-18T16:30:30.714Z] 16:30:30 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:473: UnknownErrorException [task 2018-09-18T16:30:30.714Z] 16:30:30 INFO - ... [task 2018-09-18T16:30:30.714Z] 16:30:30 INFO - TEST-OK | /webdriver/tests/get_element_rect/get.py | took 70536ms [task 2018-09-18T16:30:30.772Z] 16:30:30 WARNING - u'runner_teardown': () [task 2018-09-18T16:30:30.774Z] 16:30:30 INFO - Starting runner [task 2018-09-18T16:30:30.805Z] 16:30:30 INFO - PID 8107 | 1537288230801 webdriver::httpapi DEBUG Creating routes [task 2018-09-18T16:30:30.813Z] 16:30:30 INFO - PID 8107 | 1537288230806 geckodriver DEBUG Listening on 127.0.0.1:4444 [task 2018-09-18T16:30:31.305Z] 16:30:31 INFO - WebDriver HTTP server listening at http://127.0.0.1:4444/ [task 2018-09-18T16:30:31.306Z] 16:30:31 INFO - TEST-START | /webdriver/tests/get_element_rect/user_prompts.py
Missing "sessionstore-window-restored" observer notification.
Depends on: 1382162
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE

New occurrence:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=268677714&repo=autoland&lineNumber=73957

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: REOPENED → RESOLVED
Closed: 6 years ago → 5 years ago
Resolution: --- → INCOMPLETE

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: REOPENED → RESOLVED
Closed: 5 years ago → 5 years ago
Resolution: --- → INCOMPLETE
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.