Closed Bug 1763456 Opened 2 years ago Closed 2 years ago

Perma [Tier 2] android /webdriver/tests/print/printcmd.py | test_html_document - AssertionError: unknown error (500): Failed to decode response from marionette

Categories

(Remote Protocol :: Marionette, defect, P5)

defect

Tracking

(firefox100 disabled, firefox101 disabled, firefox103 disabled, firefox104 disabled, firefox105 fixed)

RESOLVED FIXED
105 Branch
Tracking Status
firefox100 --- disabled
firefox101 --- disabled
firefox103 --- disabled
firefox104 --- disabled
firefox105 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: whimboo)

References

(Blocks 1 open bug, Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [stockwell disabled])

Attachments

(2 files)

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


INFO - PID 4201 | 1649265311368	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2022-04-06T17:15:11.407Z] 17:15:11     INFO - STDOUT: ==================================== ERRORS ====================================
[task 2022-04-06T17:15:11.413Z] 17:15:11     INFO - STDOUT: ________________ ERROR at setup of test_no_top_browsing_context ________________
[task 2022-04-06T17:15:11.413Z] 17:15:11     INFO - STDOUT: session = <Session b4d6a53d-3365-483d-a254-f67d303a4b14>
[task 2022-04-06T17:15:11.415Z] 17:15:11     INFO - STDOUT: inline = <function inline.<locals>.inline at 0x7f123d226048>
[task 2022-04-06T17:15:11.415Z] 17:15:11     INFO - STDOUT:     @pytest.fixture
[task 2022-04-06T17:15:11.416Z] 17:15:11     INFO - STDOUT:     def closed_window(session, inline):
[task 2022-04-06T17:15:11.417Z] 17:15:11     INFO - STDOUT:         """Create a window and close it immediately.
[task 2022-04-06T17:15:11.418Z] 17:15:11     INFO - STDOUT:     
[task 2022-04-06T17:15:11.419Z] 17:15:11     INFO - STDOUT:         The window handle will be kept selected, which allows to test for invalid
[task 2022-04-06T17:15:11.420Z] 17:15:11     INFO - STDOUT:         top-level browsing context references.
[task 2022-04-06T17:15:11.421Z] 17:15:11     INFO - STDOUT:         """
[task 2022-04-06T17:15:11.421Z] 17:15:11     INFO - STDOUT:         original_handle = session.window_handle
[task 2022-04-06T17:15:11.422Z] 17:15:11     INFO - STDOUT: >       new_handle = session.new_window()
[task 2022-04-06T17:15:11.423Z] 17:15:11     INFO - STDOUT: inline     = <function inline.<locals>.inline at 0x7f123d226048>
[task 2022-04-06T17:15:11.424Z] 17:15:11     INFO - STDOUT: original_handle = 'aa21a601-95ec-4953-956c-7ab92bda1bba'
[task 2022-04-06T17:15:11.425Z] 17:15:11     INFO - STDOUT: session    = <Session b4d6a53d-3365-483d-a254-f67d303a4b14>
[task 2022-04-06T17:15:11.426Z] 17:15:11     INFO - STDOUT: tests/web-platform/tests/webdriver/tests/support/fixtures_http.py
[task 2022-04-06T17:15:11.427Z] 17:15:11     INFO - STDOUT: :67: 
[task 2022-04-06T17:15:11.428Z] 17:15:11     INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2022-04-06T17:15:11.428Z] 17:15:11     INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py
[task 2022-04-06T17:15:11.429Z] 17:15:11     INFO - STDOUT: :20: in inner
[task 2022-04-06T17:15:11.430Z] 17:15:11     INFO - STDOUT:     return func(self, *args, **kwargs)
[task 2022-04-06T17:15:11.431Z] 17:15:11     INFO - STDOUT:         args       = ()
[task 2022-04-06T17:15:11.432Z] 17:15:11     INFO - STDOUT:         func       = <function Session.new_window at 0x7f123d246ea0>
[task 2022-04-06T17:15:11.432Z] 17:15:11     INFO - STDOUT:         kwargs     = {}
[task 2022-04-06T17:15:11.433Z] 17:15:11     INFO - STDOUT:         self       = <Session b4d6a53d-3365-483d-a254-f67d303a4b14>
[task 2022-04-06T17:15:11.434Z] 17:15:11     INFO - STDOUT:         session    = <Session b4d6a53d-3365-483d-a254-f67d303a4b14>
[task 2022-04-06T17:15:11.435Z] 17:15:11     INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py
[task 2022-04-06T17:15:11.436Z] 17:15:11     INFO - STDOUT: :699: in new_window
[task 2022-04-06T17:15:11.437Z] 17:15:11     INFO - STDOUT:     value = self.send_session_command("POST", "window/new", body)
[task 2022-04-06T17:15:11.438Z] 17:15:11     INFO - STDOUT:         body       = {'type': 'tab'}
INFO - STDOUT:         self       = <Session b4d6a53d-3365-483d-a254-f67d303a4b14>
[task 2022-04-06T17:15:11.439Z] 17:15:11     INFO - STDOUT:         type_hint  = 'tab'
[task 2022-04-06T17:15:11.440Z] 17:15:11     INFO - STDOUT: tests/web-platform/tests/tools/webdriver/webdriver/client.py
[task 2022-04-06T17:15:11.441Z] 17:15:11     INFO - STDOUT: :659: in send_session_command
[task 2022-04-06T17:15:11.442Z] 17:15:11     INFO - STDOUT:     return self.send_command(method, url, body, timeout)
[task 2022-04-06T17:15:11.443Z] 17:15:11     INFO - STDOUT:         body       = {'type': 'tab'}
[task 2022-04-06T17:15:11.444Z] 17:15:11     INFO - STDOUT:         method     = 'POST'
[task 2022-04-06T17:15:11.444Z] 17:15:11     INFO - STDOUT:         self       = <Session b4d6a53d-3365-483d-a254-f67d303a4b14>
[task 2022-04-06T17:15:11.445Z] 17:15:11     INFO - STDOUT:         timeout    = None
[task 2022-04-06T17:15:11.446Z] 17:15:11     INFO - STDOUT:         uri        = 'window/new'
[task 2022-04-06T17:15:11.447Z] 17:15:11     INFO - STDOUT:         url        = 'session/b4d6a53d-3365-483d-a254-f67d303a4b14/window/new'
[task 2022-04-06T17:15:11.447Z] 17:15:11     INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2022-04-06T17:15:11.448Z] 17:15:11     INFO - STDOUT: self = <Session b4d6a53d-3365-483d-a254-f67d303a4b14>, method = 'POST'
[task 2022-04-06T17:15:11.449Z] 17:15:11     INFO - STDOUT: url = 'session/b4d6a53d-3365-483d-a254-f67d303a4b14/window/new'
[task 2022-04-06T17:15:11.450Z] 17:15:11     INFO - STDOUT: body = {'type': 'tab'}, timeout = None
[task 2022-04-06T17:15:11.451Z] 17:15:11     INFO - STDOUT:     def send_command(self, method, url, body=None, timeout=None):
[task 2022-04-06T17:15:11.451Z] 17:15:11     INFO - STDOUT:         """
[task 2022-04-06T17:15:11.452Z] 17:15:11     INFO - STDOUT:         Send a command to the remote end and validate its success.
[task 2022-04-06T17:15:11.453Z] 17:15:11     INFO - STDOUT:     
[task 2022-04-06T17:15:11.454Z] 17:15:11     INFO - STDOUT:         :param method: HTTP method to use in request.
[task 2022-04-06T17:15:11.455Z] 17:15:11     INFO - STDOUT:         :param uri: "Command part" of the HTTP request URL,
[task 2022-04-06T17:15:11.455Z] 17:15:11     INFO - STDOUT:             e.g. `window/rect`.
[task 2022-04-06T17:15:11.456Z] 17:15:11     INFO - STDOUT:         :param body: Optional body of the HTTP request.
[task 2022-04-06T17:15:11.457Z] 17:15:11     INFO - STDOUT:     
[task 2022-04-06T17:15:11.458Z] 17:15:11     INFO - STDOUT:         :return: `None` if the HTTP response body was empty, otherwise
[task 2022-04-06T17:15:11.459Z] 17:15:11     INFO - STDOUT:             the `value` field returned after parsing the response
[task 2022-04-06T17:15:11.459Z] 17:15:11     INFO - STDOUT:             body as JSON.
[task 2022-04-06T17:15:11.460Z] 17:15:11     INFO - STDOUT:     
[task 2022-04-06T17:15:11.461Z] 17:15:11     INFO - STDOUT:         :raises error.WebDriverException: If the remote end returns
[task 2022-04-06T17:15:11.461Z] 17:15:11     INFO - STDOUT:             an error.
[task 2022-04-06T17:15:11.462Z] 17:15:11     INFO - STDOUT:         :raises ValueError: If the response body does not contain a
[task 2022-04-06T17:15:11.463Z] 17:15:11     INFO - STDOUT:             `value` key.
[task 2022-04-06T17:15:11.463Z] 17:15:11     INFO - STDOUT:         """
[task 2022-04-06T17:15:11.464Z] 17:15:11     INFO - STDOUT:     
[task 2022-04-06T17:15:11.465Z] 17:15:11     INFO - STDOUT:         response = self.transport.send(
[task 2022-04-06T17:15:11.465Z] 17:15:11     INFO - STDOUT:             method, url, body,
[task 2022-04-06T17:15:11.466Z] 17:15:11     INFO - STDOUT:             encoder=protocol.Encoder, decoder=protocol.Decoder,
[task 2022-04-06T17:15:11.467Z] 17:15:11     INFO - STDOUT:             session=self, timeout=timeout)
[task 2022-04-06T17:15:11.468Z] 17:15:11     INFO - STDOUT:     
[task 2022-04-06T17:15:11.468Z] 17:15:11     INFO - STDOUT:         if response.status != 200:
INFO - STDOUT:             err = error.from_response(response)
[task 2022-04-06T17:15:11.470Z] 17:15:11     INFO - STDOUT:     
[task 2022-04-06T17:15:11.471Z] 17:15:11     INFO - STDOUT:             if isinstance(err, error.InvalidSessionIdException):
[task 2022-04-06T17:15:11.472Z] 17:15:11     INFO - STDOUT:                 # The driver could have already been deleted the session.
[task 2022-04-06T17:15:11.472Z] 17:15:11     INFO - STDOUT:                 self.session_id = None
[task 2022-04-06T17:15:11.473Z] 17:15:11     INFO - STDOUT:     
[task 2022-04-06T17:15:11.474Z] 17:15:11     INFO - STDOUT: >           raise err
[task 2022-04-06T17:15:11.474Z] 17:15:11     INFO - STDOUT: E           webdriver.error.UnsupportedOperationException: unsupported operation (500): openTab() not supported in GeckoView Test Runner
[task 2022-04-06T17:15:11.475Z] 17:15:11     INFO - STDOUT: E           
[task 2022-04-06T17:15:11.476Z] 17:15:11     INFO - STDOUT: E           Remote-end stacktrace:
[task 2022-04-06T17:15:11.479Z] 17:15:11     INFO - 
[task 2022-04-06T17:15:11.479Z] 17:15:11     INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/print/printcmd.py | test_html_document - AssertionError: unknown error (500): Failed to decode response from marionette
[task 2022-04-06T17:15:11.479Z] 17:15:11     INFO - session = <Session b4d6a53d-3365-483d-a254-f67d303a4b14>
[task 2022-04-06T17:15:11.479Z] 17:15:11     INFO - inline = <function inline.<locals>.inline at 0x7f123c94ce18>
[task 2022-04-06T17:15:11.479Z] 17:15:11     INFO - 
[task 2022-04-06T17:15:11.479Z] 17:15:11     INFO -     def test_html_document(session, inline):
[task 2022-04-06T17:15:11.479Z] 17:15:11     INFO -         session.url = inline("Test")
[task 2022-04-06T17:15:11.479Z] 17:15:11     INFO -     
[task 2022-04-06T17:15:11.479Z] 17:15:11     INFO -         response = do_print(session, {
[task 2022-04-06T17:15:11.479Z] 17:15:11     INFO -             "page": {"width": 10,
[task 2022-04-06T17:15:11.479Z] 17:15:11     INFO -                      "height": 20},
[task 2022-04-06T17:15:11.479Z] 17:15:11     INFO -             "shrinkToFit": False
[task 2022-04-06T17:15:11.479Z] 17:15:11     INFO -         })
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO - >       value = assert_success(response)
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO - 
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO - inline     = <function inline.<locals>.inline at 0x7f123c94ce18>
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO - response   = <Response status=500 error=<UnknownErrorException http_status=500>>
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO - session    = <Session b4d6a53d-3365-483d-a254-f67d303a4b14>
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO - 
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO - tests/web-platform/tests/webdriver/tests/print/printcmd.py:41: 
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO - 
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO - response = <Response status=500 error=<UnknownErrorException http_status=500>>
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO - value = None
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO - 
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO -     def assert_success(response, value=None):
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO -         """
 INFO -         Verify that the provided webdriver.Response instance described
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO -         a valid success response as defined by `dfn-send-a-response` and
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO -         the provided response value.
[task 2022-04-06T17:15:11.480Z] 17:15:11     INFO -     
[task 2022-04-06T17:15:11.481Z] 17:15:11     INFO -         :param response: ``webdriver.Response`` instance.
[task 2022-04-06T17:15:11.481Z] 17:15:11     INFO -         :param value: Expected value of the response body, if any.
[task 2022-04-06T17:15:11.481Z] 17:15:11     INFO -         """
[task 2022-04-06T17:15:11.481Z] 17:15:11     INFO - >       assert response.status == 200, str(response.error)
[task 2022-04-06T17:15:11.481Z] 17:15:11     INFO - E       AssertionError: unknown error (500): Failed to decode response from marionette
[task 2022-04-06T17:15:11.481Z] 17:15:11     INFO - E         
[task 2022-04-06T17:15:11.481Z] 17:15:11     INFO - E       assert 500 == 200
[task 2022-04-06T17:15:11.481Z] 17:15:11     INFO - E         +500
[task 2022-04-06T17:15:11.481Z] 17:15:11     INFO - E         -200
[task 2022-04-06T17:15:11.481Z] 17:15:11     INFO - 
[task 2022-04-06T17:15:11.481Z] 17:15:11     INFO - response   = <Response status=500 error=<UnknownErrorException http_status=500>>
[task 2022-04-06T17:15:11.481Z] 17:15:11     INFO - value      = None
[task 2022-04-06T17:15:11.481Z] 17:15:11     INFO - 
[task 2022-04-06T17:15:11.481Z] 17:15:11     INFO - tests/web-platform/tests/webdriver/tests/support/asserts.py:69: AssertionError

@whimbo, can you take a look at this perma failures?

Flags: needinfo?(hskupin)
Summary: Perma [Tier 2] /webdriver/tests/print/printcmd.py | test_html_document - AssertionError: unknown error (500): Failed to decode response from marionette → Perma [Tier 2] android /webdriver/tests/print/printcmd.py | test_html_document - AssertionError: unknown error (500): Failed to decode response from marionette
Depends on: 1763466

This only affects the Android debug-isolated-process jobs. Sadly there doesn't seem to be a mozinfo flag that we could use to disable specifically for this job type, so we may have to still keep all debug disabled.

Assignee: nobody → hskupin
Status: NEW → ASSIGNED

Note that this bug is blocked on bug 1763466.

Assignee: hskupin → nobody
Status: ASSIGNED → NEW
Flags: needinfo?(hskupin)
Assignee: nobody → hskupin
Status: NEW → ASSIGNED
Pushed by hskupin@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/9cea4ea9f632
[wdspec] Disable print to PDF tests for Android debug due to crashes for isolated-process jobs. r=webdriver-reviewers,jgraham
Assignee: hskupin → nobody
Status: ASSIGNED → NEW

:emilio, since you are the author of the regressor, bug 1762428, could you take a look?
For more information, please visit auto_nag documentation.

Flags: needinfo?(emilio)
Flags: needinfo?(emilio)
Has Regression Range: --- → yes
Whiteboard: [stockwell disabled]

Hi Henrik, this is still failing on mozilla-beta, should it be uplifted?

Flags: needinfo?(hskupin)

Yes, if the tests are not disabled please uplift. It might be good to also include the patch from bug 1763478 so it's really only disabled for the isolated process jobs and not for all debug ones.

Flags: needinfo?(hskupin) → needinfo?(apavel)

(In reply to Henrik Skupin (:whimboo) [⌚️UTC+1] from comment #11)

Yes, if the tests are not disabled please uplift. It might be good to also include the patch from bug 1763478 so it's really only disabled for the isolated process jobs and not for all debug ones.

Thank you Herik, Aryx will take care of it.

Flags: needinfo?(apavel) → needinfo?(aryx.bugmail)
Assignee: nobody → hskupin
Status: NEW → ASSIGNED
Blocks: 1729409
Attachment #9289483 - Attachment description: Bug 1763456 - [wdspec] Re-enable print to PDF tests for Android debug due to crashes for isolated-process jobs. → Bug 1763456 - [wdspec] Re-enable print to PDF tests for Android debug since bug 1763466 is fixed now
Pushed by hskupin@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/6b69dbc1510e
[wdspec] Re-enable print to PDF tests for Android debug since bug 1763466 is fixed now r=webdriver-reviewers,jdescottes
Status: ASSIGNED → RESOLVED
Closed: 2 years ago
Resolution: --- → FIXED
Target Milestone: --- → 105 Branch
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.

Attachment

General

Created:
Updated:
Size: