1700338 - Intermittent [TV] TEST-UNEXPECTED-CRASH | /html/syntax/xmldecl/xmldecl.html | expected OK

Status: RESOLVED INCOMPLETE

(Testing :: Marionette Client and Harness, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: smolnar [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=334125485&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FP54ou_vStu5ePSPRHnlAw/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FP54ou_vStu5ePSPRHnlAw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

INFO - TEST-START | /html/syntax/xmldecl/xmldecl.html
[task 2021-03-23T10:49:26.806Z] 10:49:26     INFO - Run 0/10
[task 2021-03-23T10:49:28.276Z] 10:49:28     INFO - PID 1338 | [1353, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-03-23T10:49:28.277Z] 10:49:28     INFO - PID 1338 | [1353, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-03-23T10:49:28.278Z] 10:49:28     INFO - PID 1338 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/tw/sldlt3b133n8qj1hs8vldpz4000017/T/tmpae5_yw8e/runtests_leaks_1060_tab_pid1353.log
[task 2021-03-23T10:49:28.278Z] 10:49:28     INFO - PID 1338 | [1353, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-03-23T10:49:28.279Z] 10:49:28     INFO - PID 1338 | [1353, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2021-03-23T10:49:28.280Z] 10:49:28     INFO - PID 1338 | [1353, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:254
[task 2021-03-23T10:49:28.518Z] 10:49:28     INFO - PID 1338 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2021-03-23T10:49:28.519Z] 10:49:28     INFO - PID 1338 | [Child 1353, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:377
[task 2021-03-23T10:50:07.654Z] 10:50:07  WARNING - Traceback (most recent call last):
[task 2021-03-23T10:50:07.654Z] 10:50:07  WARNING -   File "/Users/cltbld/tasks/task_1616496213/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 791, in run_func
[task 2021-03-23T10:50:07.654Z] 10:50:07  WARNING -     self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2021-03-23T10:50:07.654Z] 10:50:07  WARNING -   File "/Users/cltbld/tasks/task_1616496213/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 915, in do_testharness
[task 2021-03-23T10:50:07.654Z] 10:50:07  WARNING -     self.script_resume % format_map, asynchronous=True)
[task 2021-03-23T10:50:07.654Z] 10:50:07  WARNING -   File "/Users/cltbld/tasks/task_1616496213/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 84, in execute_script
[task 2021-03-23T10:50:07.656Z] 10:50:07  WARNING -     return method(script, new_sandbox=False, sandbox=None)
[task 2021-03-23T10:50:07.656Z] 10:50:07  WARNING -   File "/Users/cltbld/tasks/task_1616496213/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 1729, in execute_async_script
[task 2021-03-23T10:50:07.656Z] 10:50:07  WARNING -     rv = self._send_message("WebDriver:ExecuteAsyncScript", body, key="value")
[task 2021-03-23T10:50:07.656Z] 10:50:07  WARNING -   File "/Users/cltbld/tasks/task_1616496213/build/venv/lib/python3.7/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2021-03-23T10:50:07.656Z] 10:50:07  WARNING -     return func(*args, **kwargs)
[task 2021-03-23T10:50:07.656Z] 10:50:07  WARNING -   File "/Users/cltbld/tasks/task_1616496213/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 621, in _send_message
[task 2021-03-23T10:50:07.656Z] 10:50:07  WARNING -     msg = self.client.request(name, params)
[task 2021-03-23T10:50:07.656Z] 10:50:07  WARNING -   File "/Users/cltbld/tasks/task_1616496213/build/venv/lib/python3.7/site-packages/marionette_driver/transport.py", line 292, in request
[task 2021-03-23T10:50:07.656Z] 10:50:07  WARNING -     return self.receive()
[task 2021-03-23T10:50:07.656Z] 10:50:07  WARNING -   File "/Users/cltbld/tasks/task_1616496213/build/venv/lib/python3.7/site-packages/marionette_driver/transport.py", line 178, in receive
[task 2021-03-23T10:50:07.656Z] 10:50:07  WARNING -     if len(remaining) == int(length):
[task 2021-03-23T10:50:07.656Z] 10:50:07  WARNING - ValueError: invalid literal for int() with base 10: b'ull,{"value"'
[task 2021-03-23T10:50:07.656Z] 10:50:07  WARNING - 
[task 2021-03-23T10:50:08.649Z] 10:50:08     INFO - Browser not responding, setting status to CRASH
[task 2021-03-23T10:50:08.651Z] 10:50:08     INFO - TEST-UNEXPECTED-CRASH | /html/syntax/xmldecl/xmldecl.html | expected OK
[task 2021-03-23T10:50:08.651Z] 10:50:08     INFO - TEST-INFO took 41844ms
[task 2021-03-23T10:50:08.690Z] 10:50:08     INFO - PID 1338 | 1616496608688	Marionette	INFO	Stopped listening on port 50889```

Comment 1

[Henri Sivonen (:hsivonen) (temporarily away from Bugzilla)]

Browser not responding, setting status to CRASH

So this isn't evidence of actual crash, right? And could be a timeout?

Comment 2

[Henri Sivonen (:hsivonen) (temporarily away from Bugzilla)]

jgraham, does this look like an actual crash to you?

Comment 3

[BugBot [:suhaib / :marco/ :calixte]]

Set release status flags based on info from the regressing bug 673087

Comment 4

[James Graham [:jgraham]]

It looks like we're getting invalid data over the marionette connection; the client thinks its reading a length but it actually looks like it's partway through a message. So I think it's not an actual crash, although it's not something I remember seeing before. Having said that I have previously had worries about the robustness of the transport code in marionette_client, so it could be related to that.

If we start seeing more "crashes" with invalid literal for int() with base 10 in the stack they are likely all the same bug.

Comment 5

[Intermittent Failures Robot]

1 failures in 3967 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• macosx1014-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1700338&startday=2021-03-22&endday=2021-03-28&tree=all

Comment 6

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 7

[BMO Automation]

Moving bug to Testing::Marionette Client and Harness component per bug 1815831.