1830270 - Intermittent Wd | setup error: webdriver.error.UnknownErrorException: unknown error (500): Failed to decode response from marionette

Status: RESOLVED DUPLICATE of bug 1827125

(Remote Protocol :: Marionette, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: sstanca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=413947334&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/IPwDOuWUQTeaR0hm1caxMg/runs/0/artifacts/public/logs/live_backing.log

---

[task 2023-04-27T10:50:05.289Z] 10:50:05     INFO - TEST-PASS | /webdriver/tests/back/back.py | test_removed_iframe 
[task 2023-04-27T10:50:05.289Z] 10:50:05     INFO - TEST-UNEXPECTED-ERROR | /webdriver/tests/back/back.py | test_cross_origin[capabilities0] - setup error: webdriver.error.UnknownErrorException: unknown error (500): Failed to decode response from marionette
[task 2023-04-27T10:50:05.289Z] 10:50:05     INFO - capabilities = {'acceptInsecureCerts': True}
[task 2023-04-27T10:50:05.289Z] 10:50:05     INFO - session = <Session 4f1ba37f-d346-4037-9343-cb1b3dbc4906>
[task 2023-04-27T10:50:05.289Z] 10:50:05     INFO - 
[task 2023-04-27T10:50:05.289Z] 10:50:05     INFO -     @pytest.fixture(name="session")
[task 2023-04-27T10:50:05.289Z] 10:50:05     INFO -     def fixture_session(capabilities, session):
[task 2023-04-27T10:50:05.289Z] 10:50:05     INFO -         """Prevent re-using existent history by running the test in a new window."""
[task 2023-04-27T10:50:05.289Z] 10:50:05     INFO -         original_handle = session.window_handle
[task 2023-04-27T10:50:05.289Z] 10:50:05     INFO - >       session.window_handle = session.new_window()
[task 2023-04-27T10:50:05.289Z] 10:50:05     INFO - 
[task 2023-04-27T10:50:05.289Z] 10:50:05     INFO - capabilities = {'acceptInsecureCerts': True}
[task 2023-04-27T10:50:05.290Z] 10:50:05     INFO - original_handle = '9f6e2f56-c6c4-4039-b966-6d6183373723'
[task 2023-04-27T10:50:05.290Z] 10:50:05     INFO - session    = <Session 4f1ba37f-d346-4037-9343-cb1b3dbc4906>
[task 2023-04-27T10:50:05.290Z] 10:50:05     INFO - 
[task 2023-04-27T10:50:05.290Z] 10:50:05     INFO - tests\web-platform\tests\webdriver\tests\back\conftest.py:10: 
[task 2023-04-27T10:50:05.290Z] 10:50:05     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2023-04-27T10:50:05.290Z] 10:50:05     INFO - tests\web-platform\tests\tools\webdriver\webdriver\client.py:22: in inner
[task 2023-04-27T10:50:05.290Z] 10:50:05     INFO -     return func(self, *args, **kwargs)
[task 2023-04-27T10:50:05.290Z] 10:50:05     INFO -         args       = ()
[task 2023-04-27T10:50:05.290Z] 10:50:05     INFO -         func       = <function Session.new_window at 0x0000022FA24624C0>
[task 2023-04-27T10:50:05.290Z] 10:50:05     INFO -         kwargs     = {}
[task 2023-04-27T10:50:05.290Z] 10:50:05     INFO -         self       = <Session 4f1ba37f-d346-4037-9343-cb1b3dbc4906>
[task 2023-04-27T10:50:05.290Z] 10:50:05     INFO -         session    = <Session 4f1ba37f-d346-4037-9343-cb1b3dbc4906>
[task 2023-04-27T10:50:05.290Z] 10:50:05     INFO - tests\web-platform\tests\tools\webdriver\webdriver\client.py:698: in new_window
[task 2023-04-27T10:50:05.290Z] 10:50:05     INFO -     value = self.send_session_command("POST", "window/new", body)
[task 2023-04-27T10:50:05.290Z] 10:50:05     INFO -         body       = {'type': 'tab'}
[task 2023-04-27T10:50:05.290Z] 10:50:05     INFO -         self       = <Session 4f1ba37f-d346-4037-9343-cb1b3dbc4906>
[task 2023-04-27T10:50:05.291Z] 10:50:05     INFO -         type_hint  = 'tab'
[task 2023-04-27T10:50:05.291Z] 10:50:05     INFO - tests\web-platform\tests\tools\webdriver\webdriver\client.py:658: in send_session_command
[task 2023-04-27T10:50:05.291Z] 10:50:05     INFO -     return self.send_command(method, url, body, timeout)
[task 2023-04-27T10:50:05.291Z] 10:50:05     INFO -         body       = {'type': 'tab'}
[task 2023-04-27T10:50:05.291Z] 10:50:05     INFO -         method     = 'POST'
[task 2023-04-27T10:50:05.291Z] 10:50:05     INFO -         self       = <Session 4f1ba37f-d346-4037-9343-cb1b3dbc4906>
[task 2023-04-27T10:50:05.291Z] 10:50:05     INFO -         timeout    = None
[task 2023-04-27T10:50:05.291Z] 10:50:05     INFO -         uri        = 'window/new'
[task 2023-04-27T10:50:05.291Z] 10:50:05     INFO -         url        = 'session/4f1ba37f-d346-4037-9343-cb1b3dbc4906/window/new'
[task 2023-04-27T10:50:05.291Z] 10:50:05     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2023-04-27T10:50:05.291Z] 10:50:05     INFO - 
[task 2023-04-27T10:50:05.291Z] 10:50:05     INFO - self = <Session 4f1ba37f-d346-4037-9343-cb1b3dbc4906>, method = 'POST'
[task 2023-04-27T10:50:05.291Z] 10:50:05     INFO - url = 'session/4f1ba37f-d346-4037-9343-cb1b3dbc4906/window/new'
[task 2023-04-27T10:50:05.291Z] 10:50:05     INFO - body = {'type': 'tab'}, timeout = None
[task 2023-04-27T10:50:05.291Z] 10:50:05     INFO - 
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -     def send_command(self, method, url, body=None, timeout=None):
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -         """
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -         Send a command to the remote end and validate its success.
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -     
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -         :param method: HTTP method to use in request.
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -         :param uri: "Command part" of the HTTP request URL,
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -             e.g. `window/rect`.
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -         :param body: Optional body of the HTTP request.
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -     
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -         :return: `None` if the HTTP response body was empty, otherwise
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -             the `value` field returned after parsing the response
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -             body as JSON.
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -     
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -         :raises error.WebDriverException: If the remote end returns
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -             an error.
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -         :raises ValueError: If the response body does not contain a
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -             `value` key.
[task 2023-04-27T10:50:05.292Z] 10:50:05     INFO -         """
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO -     
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO -         response = self.transport.send(
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO -             method, url, body,
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO -             encoder=protocol.Encoder, decoder=protocol.Decoder,
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO -             session=self, timeout=timeout)
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO -     
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO -         if response.status != 200:
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO -             err = error.from_response(response)
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO -     
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO -             if isinstance(err, error.InvalidSessionIdException):
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO -                 # The driver could have already been deleted the session.
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO -                 self.session_id = None
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO -     
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO - >           raise err
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO - E           webdriver.error.UnknownErrorException: unknown error (500): Failed to decode response from marionette
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO - 
[task 2023-04-27T10:50:05.293Z] 10:50:05     INFO - body       = {'type': 'tab'}
[task 2023-04-27T10:50:05.294Z] 10:50:05     INFO - err        = <UnknownErrorException http_status=500>
[task 2023-04-27T10:50:05.294Z] 10:50:05     INFO - method     = 'POST'
[task 2023-04-27T10:50:05.294Z] 10:50:05     INFO - response   = <Response status=500 error=<UnknownErrorException http_status=500>>
[task 2023-04-27T10:50:05.294Z] 10:50:05     INFO - self       = <Session 4f1ba37f-d346-4037-9343-cb1b3dbc4906>
[task 2023-04-27T10:50:05.294Z] 10:50:05     INFO - timeout    = None
[task 2023-04-27T10:50:05.294Z] 10:50:05     INFO - url        = 'session/4f1ba37f-d346-4037-9343-cb1b3dbc4906/window/new'
[task 2023-04-27T10:50:05.294Z] 10:50:05     INFO - 
[task 2023-04-27T10:50:05.294Z] 10:50:05     INFO - tests\web-platform\tests\tools\webdriver\webdriver\client.py:622: UnknownErrorException
[task 2023-04-27T10:50:05.295Z] 10:50:05     INFO - TEST-OK | /webdriver/tests/back/back.py | took 40177ms

Comment 1

[Henrik Skupin [:whimboo][⌚️UTC+2]]

A content process crashed sadly without a minidump file created:

[task 2023-04-27T10:49:59.684Z] 10:49:59     INFO - PID 8152 | [Parent 3948, Main Thread] WARNING: content process pid = 6900 crashed without leaving a minidump behind: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:4505
[task 2023-04-27T10:49:59.685Z] 10:49:59     INFO - PID 8152 | [Parent 3948, Main Thread] WARNING: IPC message 'PContent::Msg_CommitBrowsingContextTransaction' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-04-27T10:49:59.686Z] 10:49:59     INFO - PID 8152 | [Parent 3948, Main Thread] WARNING: IPC message 'PContent::Msg_DiscardBrowsingContext' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-04-27T10:49:59.692Z] 10:49:59     INFO - PID 8152 | A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down

It's basically a variation of bug 1827125.