Closed Bug 1633948 Opened 6 years ago Closed 6 years ago

Intermittent /webdriver/tests/back/back.py | test_dismissed_beforeunload - UnknownErrorException: unknown error (500): Failed to decode response from marionette

Categories

(Remote Protocol :: Marionette, defect, P5)

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

Tracking

(Not tracked)

Status:
RESOLVED DUPLICATE of bug 1569480

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: opoprus [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=299877555&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/CoQFbhgBQZmp81cejxeQqg/runs/0/artifacts/public/logs/live_backing.log

[task 2020-04-28T22:15:29.373Z] 22:15:29 INFO - TEST-START | /webdriver/tests/back/back.py

[task 2020-04-28T22:15:53.897Z] 22:15:53 INFO - TEST-PASS | /webdriver/tests/back/back.py | test_data_urls
[task 2020-04-28T22:15:53.897Z] 22:15:53 INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/back/back.py | test_dismissed_beforeunload - UnknownErrorException: unknown error (500): Failed to decode response from marionette
[task 2020-04-28T22:15:53.897Z] 22:15:53 INFO - session = <Session db331356-8966-8848-8b5f-ba8c7f6f851a>
[task 2020-04-28T22:15:53.897Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.897Z] 22:15:53 INFO - def test_dismissed_beforeunload(session):
[task 2020-04-28T22:15:53.897Z] 22:15:53 INFO - url_beforeunload = inline("""
[task 2020-04-28T22:15:53.897Z] 22:15:53 INFO - <input type="text">
[task 2020-04-28T22:15:53.897Z] 22:15:53 INFO - <script>
[task 2020-04-28T22:15:53.897Z] 22:15:53 INFO - window.addEventListener("beforeunload", function (event) {
[task 2020-04-28T22:15:53.897Z] 22:15:53 INFO - event.preventDefault();
[task 2020-04-28T22:15:53.897Z] 22:15:53 INFO - });
[task 2020-04-28T22:15:53.897Z] 22:15:53 INFO - </script>
[task 2020-04-28T22:15:53.897Z] 22:15:53 INFO - """)
[task 2020-04-28T22:15:53.897Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.897Z] 22:15:53 INFO - session.url = inline("<div id=foo>")
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO - session.url = url_beforeunload
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO - element = session.find.css("input", all=False)
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO - element.send_keys("bar")
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO - response = back(session)
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO - assert_success(response)
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO - > assert session.url != url_beforeunload
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO - element = <Element adc4577d-2927-bc4a-be81-596b88758498>
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO - response = <Responsetatus=200 body={"value": null}>
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO - session = <Session db331356-8966-8848-8b5f-ba8c7f6f851a>
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO - url_beforeunload = 'http://web-platform.test:8000/webdriver/tests/support/inline.py?doc=%3C%21doctype+html%3E%0A%3Cmeta+charset%3DUTF-8%3...+++++++event.preventDefault%28%29%3B%0A++++++++%7D%29%3B%0A++++++%3C%2Fscript%3E%0A++++&charset=UTF-8&mime=text%2Fhtml'
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO - tests/web-platform/tests/webdriver/tests/back/back.py:63:
[task 2020-04-28T22:15:53.898Z] 22:15:53 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2020-04-28T22:15:53.899Z] 22:15:53 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:19: in inner
[task 2020-04-28T22:15:53.899Z] 22:15:53 INFO - return func(self, *args, **kwargs)
[task 2020-04-28T22:15:53.899Z] 22:15:53 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:518: in url
[task 2020-04-28T22:15:53.899Z] 22:15:53 INFO - return self.send_session_command("GET", "url")
[task 2020-04-28T22:15:53.899Z] 22:15:53 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:513: in send_session_command
[task 2020-04-28T22:15:53.899Z] 22:15:53 INFO - return self.send_command(method, url, body, timeout)
[task 2020-04-28T22:15:53.899Z] 22:15:53 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2020-04-28T22:15:53.899Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.899Z] 22:15:53 INFO - self = <Session db331356-8966-8848-8b5f-ba8c7f6f851a>, method = 'GET'
[task 2020-04-28T22:15:53.899Z] 22:15:53 INFO - url = 'session/db331356-8966-8848-8b5f-ba8c7f6f851a/url', body = None
[task 2020-04-28T22:15:53.899Z] 22:15:53 INFO - timeout = None
[task 2020-04-28T22:15:53.899Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.900Z] 22:15:53 INFO - def send_command(self, method, url, body=None, timeout=None):
[task 2020-04-28T22:15:53.900Z] 22:15:53 INFO - """
[task 2020-04-28T22:15:53.900Z] 22:15:53 INFO - Send a command to the remote end and validate its success.
[task 2020-04-28T22:15:53.900Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.900Z] 22:15:53 INFO - :param method: HTTP method to use in request.
[task 2020-04-28T22:15:53.900Z] 22:15:53 INFO - :param uri: "Command part" of the HTTP request URL,
[task 2020-04-28T22:15:53.900Z] 22:15:53 INFO - e.g. window/rect.
[task 2020-04-28T22:15:53.900Z] 22:15:53 INFO - :param body: Optional body of the HTTP request.
[task 2020-04-28T22:15:53.900Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.900Z] 22:15:53 INFO - :return: None if the HTTP response body was empty, otherwise
[task 2020-04-28T22:15:53.900Z] 22:15:53 INFO - the value field returned after parsing the response
[task 2020-04-28T22:15:53.900Z] 22:15:53 INFO - body as JSON.
[task 2020-04-28T22:15:53.900Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.900Z] 22:15:53 INFO - :raises error.WebDriverException: If the remote end returns
[task 2020-04-28T22:15:53.900Z] 22:15:53 INFO - an error.
[task 2020-04-28T22:15:53.901Z] 22:15:53 INFO - :raises ValueError: If the response body does not contain a
[task 2020-04-28T22:15:53.901Z] 22:15:53 INFO - value key.
[task 2020-04-28T22:15:53.901Z] 22:15:53 INFO - """
[task 2020-04-28T22:15:53.901Z] 22:15:53 INFO - response = self.transport.send(
[task 2020-04-28T22:15:53.901Z] 22:15:53 INFO - method, url, body,
[task 2020-04-28T22:15:53.901Z] 22:15:53 INFO - encoder=protocol.Encoder, decoder=protocol.Decoder,
[task 2020-04-28T22:15:53.901Z] 22:15:53 INFO - session=self, timeout=timeout)
[task 2020-04-28T22:15:53.901Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.901Z] 22:15:53 INFO - if response.status != 200:
[task 2020-04-28T22:15:53.901Z] 22:15:53 INFO - err = error.from_response(response)
[task 2020-04-28T22:15:53.902Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.902Z] 22:15:53 INFO - if isinstance(err, error.InvalidSessionIdException):
[task 2020-04-28T22:15:53.902Z] 22:15:53 INFO - # The driver could have already been deleted the session.
[task 2020-04-28T22:15:53.902Z] 22:15:53 INFO - self.session_id = None
[task 2020-04-28T22:15:53.902Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.902Z] 22:15:53 INFO - > raise err
[task 2020-04-28T22:15:53.902Z] 22:15:53 INFO - E UnknownErrorException: unknown error (500): Failed to decode response from marionette
[task 2020-04-28T22:15:53.902Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.902Z] 22:15:53 INFO - body = None
[task 2020-04-28T22:15:53.903Z] 22:15:53 INFO - err = <UnknownErrorException http_status=500>
[task 2020-04-28T22:15:53.903Z] 22:15:53 INFO - method = 'GET'
[task 2020-04-28T22:15:53.903Z] 22:15:53 INFO - response = <Response status=500 error=<UnknownErrorException http_status=500>>
[task 2020-04-28T22:15:53.903Z] 22:15:53 INFO - self = <Session db331356-8966-8848-8b5f-ba8c7f6f851a>
[task 2020-04-28T22:15:53.903Z] 22:15:53 INFO - timeout = None
[task 2020-04-28T22:15:53.903Z] 22:15:53 INFO - url = 'session/db331356-8966-8848-8b5f-ba8c7f6f851a/url'
[task 2020-04-28T22:15:53.903Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.903Z] 22:15:53 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:477: UnknownErrorException
[task 2020-04-28T22:15:53.903Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.904Z] 22:15:53 INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/back/back.py | test_dismissed_beforeunload - teardown error
[task 2020-04-28T22:15:53.904Z] 22:15:53 INFO - capabilities = {}, session = <Session f7ffdd72-9f9d-c94d-8ced-96d67eb0662f>
[task 2020-04-28T22:15:53.904Z] 22:15:53 INFO - create_window = <function create_window at 0x11099ab18>
[task 2020-04-28T22:15:53.904Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.904Z] 22:15:53 INFO - @pytest.fixture(name="session")
[task 2020-04-28T22:15:53.904Z] 22:15:53 INFO - def fixture_session(capabilities, session, create_window):
[task 2020-04-28T22:15:53.904Z] 22:15:53 INFO - """Prevent re-using existent history by running the test in a new window."""
[task 2020-04-28T22:15:53.904Z] 22:15:53 INFO - original_handle = session.window_handle
[task 2020-04-28T22:15:53.904Z] 22:15:53 INFO - session.window_handle = create_window()
[task 2020-04-28T22:15:53.904Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.904Z] 22:15:53 INFO - yield session
[task 2020-04-28T22:15:53.904Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.904Z] 22:15:53 INFO - try:
[task 2020-04-28T22:15:53.905Z] 22:15:53 INFO - > session.close()
[task 2020-04-28T22:15:53.905Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.905Z] 22:15:53 INFO - capabilities = {}
[task 2020-04-28T22:15:53.905Z] 22:15:53 INFO - create_window = <function create_window at 0x11099ab18>
[task 2020-04-28T22:15:53.905Z] 22:15:53 INFO - original_handle = '18'
[task 2020-04-28T22:15:53.905Z] 22:15:53 INFO - session = <Session f7ffdd72-9f9d-c94d-8ced-96d67eb0662f>
[task 2020-04-28T22:15:53.905Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.905Z] 22:15:53 INFO - tests/web-platform/tests/webdriver/tests/back/conftest.py:15:
[task 2020-04-28T22:15:53.905Z] 22:15:53 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2020-04-28T22:15:53.905Z] 22:15:53 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:19: in inner
[task 2020-04-28T22:15:53.905Z] 22:15:53 INFO - return func(self, *args, **kwargs)
[task 2020-04-28T22:15:53.905Z] 22:15:53 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:573: in close
[task 2020-04-28T22:15:53.905Z] 22:15:53 INFO - handles = self.send_session_command("DELETE", "window")
[task 2020-04-28T22:15:53.905Z] 22:15:53 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:513: in send_session_command
[task 2020-04-28T22:15:53.905Z] 22:15:53 INFO - return self.send_command(method, url, body, timeout)
[task 2020-04-28T22:15:53.906Z] 22:15:53 INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2020-04-28T22:15:53.906Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.906Z] 22:15:53 INFO - self = <Session f7ffdd72-9f9d-c94d-8ced-96d67eb0662f>, method = 'DELETE'
[task 2020-04-28T22:15:53.906Z] 22:15:53 INFO - url = 'session/db331356-8966-8848-8b5f-ba8c7f6f851a/window', body = None
[task 2020-04-28T22:15:53.906Z] 22:15:53 INFO - timeout = None
[task 2020-04-28T22:15:53.906Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.906Z] 22:15:53 INFO - def send_command(self, method, url, body=None, timeout=None):
[task 2020-04-28T22:15:53.906Z] 22:15:53 INFO - """
[task 2020-04-28T22:15:53.906Z] 22:15:53 INFO - Send a command to the remote end and validate its success.
[task 2020-04-28T22:15:53.906Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.906Z] 22:15:53 INFO - :param method: HTTP method to use in request.
[task 2020-04-28T22:15:53.906Z] 22:15:53 INFO - :param uri: "Command part" of the HTTP request URL,
[task 2020-04-28T22:15:53.906Z] 22:15:53 INFO - e.g. window/rect.
[task 2020-04-28T22:15:53.906Z] 22:15:53 INFO - :param body: Optional body of the HTTP request.
[task 2020-04-28T22:15:53.907Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.907Z] 22:15:53 INFO - :return: None if the HTTP response body was empty, otherwise
[task 2020-04-28T22:15:53.907Z] 22:15:53 INFO - the value field returned after parsing the response
[task 2020-04-28T22:15:53.907Z] 22:15:53 INFO - body as JSON.
[task 2020-04-28T22:15:53.907Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.907Z] 22:15:53 INFO - :raises error.WebDriverException: If the remote end returns
[task 2020-04-28T22:15:53.907Z] 22:15:53 INFO - an error.
[task 2020-04-28T22:15:53.907Z] 22:15:53 INFO - :raises ValueError: If the response body does not contain a
[task 2020-04-28T22:15:53.907Z] 22:15:53 INFO - value key.
[task 2020-04-28T22:15:53.907Z] 22:15:53 INFO - """
[task 2020-04-28T22:15:53.907Z] 22:15:53 INFO - response = self.transport.send(
[task 2020-04-28T22:15:53.907Z] 22:15:53 INFO - method, url, body,
[task 2020-04-28T22:15:53.907Z] 22:15:53 INFO - encoder=protocol.Encoder, decoder=protocol.Decoder,
[task 2020-04-28T22:15:53.907Z] 22:15:53 INFO - session=self, timeout=timeout)
[task 2020-04-28T22:15:53.907Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.908Z] 22:15:53 INFO - if response.status != 200:
[task 2020-04-28T22:15:53.908Z] 22:15:53 INFO - err = error.from_response(response)
[task 2020-04-28T22:15:53.908Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.908Z] 22:15:53 INFO - if isinstance(err, error.InvalidSessionIdException):
[task 2020-04-28T22:15:53.908Z] 22:15:53 INFO - # The driver could have already been deleted the session.
[task 2020-04-28T22:15:53.908Z] 22:15:53 INFO - self.session_id = None
[task 2020-04-28T22:15:53.908Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.908Z] 22:15:53 INFO - > raise err
[task 2020-04-28T22:15:53.908Z] 22:15:53 INFO - E InvalidSessionIdException: invalid session id (404): Tried to run command without establishing a connection
[task 2020-04-28T22:15:53.908Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.908Z] 22:15:53 INFO - body = None
[task 2020-04-28T22:15:53.908Z] 22:15:53 INFO - err = <InvalidSessionIdException http_status=404>
[task 2020-04-28T22:15:53.908Z] 22:15:53 INFO - method = 'DELETE'
[task 2020-04-28T22:15:53.908Z] 22:15:53 INFO - response = <Response status=404 error=<InvalidSessionIdException http_status=404>>
[task 2020-04-28T22:15:53.908Z] 22:15:53 INFO - self = <Session f7ffdd72-9f9d-c94d-8ced-96d67eb0662f>
[task 2020-04-28T22:15:53.909Z] 22:15:53 INFO - timeout = None
[task 2020-04-28T22:15:53.909Z] 22:15:53 INFO - url = 'session/db331356-8966-8848-8b5f-ba8c7f6f851a/window'
[task 2020-04-28T22:15:53.909Z] 22:15:53 INFO -
[task 2020-04-28T22:15:53.909Z] 22:15:53 INFO - tests/web-platform/tests/tools/webdriver/webdriver/client.py:477: InvalidSessionIdException
[task 2020-04-28T22:15:53.909Z] 22:15:53 INFO - ..
[task 2020-04-28T22:15:53.909Z] 22:15:53 INFO - TEST-OK | /webdriver/tests/back/back.py | took 24468ms
[task 2020-04-28T22:15:54.014Z] 22:15:54 INFO - Closing logging queue
[task 2020-04-28T22:15:54.014Z] 22:15:54 INFO - queue closed
[task 2020-04-28T22:15:54.014Z] 22:15:54 INFO - Starting runner
[task 2020-04-28T22:15:54.035Z] 22:15:54 INFO - PID 1779 | 1588112154028 geckodriver DEBUG Listening on 127.0.0.1:50435
[task 2020-04-28T22:15:54.569Z] 22:15:54 INFO - WebDriver HTTP server listening at http://127.0.0.1:50435/

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → DUPLICATE
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.