Closed Bug 1771762 Opened 4 years ago Closed 4 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /html/dom/idlharness.worker.html | TestRunner hit external timeout (this may indicate a hang)

Categories

(Core :: DOM: Core & HTML, defect, P5)

Product:
Core
Component:
DOM: Core & HTML
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2022-05-30T13:06:51.870Z] 13:06:51     INFO - TEST-START | /html/dom/idlharness.worker.html
[task 2022-05-30T13:06:51.871Z] 13:06:51     INFO - Setting pref dom.security.featurePolicy.experimental.enabled to false
[task 2022-05-30T13:06:51.873Z] 13:06:51     INFO - Setting pref dom.security.featurePolicy.header.enabled to false
[task 2022-05-30T13:06:51.874Z] 13:06:51     INFO - Setting pref dom.security.featurePolicy.webidl.enabled to false
[task 2022-05-30T13:06:51.875Z] 13:06:51     INFO - Setting pref dom.forms.inputmode to true
[task 2022-05-30T13:06:51.876Z] 13:06:51     INFO - Setting pref dom.forms.enterkeyhint to true
[task 2022-05-30T13:06:51.877Z] 13:06:51     INFO - Setting pref dom.forms.autocapitalize to true
[task 2022-05-30T13:06:51.878Z] 13:06:51     INFO - Setting pref html5.inert.enabled to true
[task 2022-05-30T13:06:51.884Z] 13:06:51     INFO - Setting pref dom.dialog_element.enabled to true
[task 2022-05-30T13:06:51.896Z] 13:06:51     INFO - Closing window de3eaa54-30fa-487d-b8de-f37d45246423
[task 2022-05-30T13:07:26.801Z] 13:07:26     INFO - Got timeout in harness
[task 2022-05-30T13:07:26.804Z] 13:07:26     INFO - TEST-UNEXPECTED-TIMEOUT | /html/dom/idlharness.worker.html | TestRunner hit external timeout (this may indicate a hang)
[task 2022-05-30T13:07:26.804Z] 13:07:26     INFO - TEST-INFO took 35003ms
[task 2022-05-30T13:08:37.899Z] 13:08:37     INFO - Browser exited with return code -15
[task 2022-05-30T13:08:38.004Z] 13:08:38 CRITICAL - Loading initial page http://web-platform.test:8000/testharness_runner.html failed. Ensure that the there are no other programs bound to this port and that your firewall rules or network setup does not prevent access.
[task 2022-05-30T13:08:38.004Z] 13:08:38 CRITICAL - Traceback (most recent call last):
[task 2022-05-30T13:08:38.004Z] 13:08:38 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 166, in load_runner
[task 2022-05-30T13:08:38.004Z] 13:08:38 CRITICAL -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2022-05-30T13:08:38.004Z] 13:08:38 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 214, in dismiss_alert
[task 2022-05-30T13:08:38.004Z] 13:08:38 CRITICAL -     f()
[task 2022-05-30T13:08:38.004Z] 13:08:38 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 166, in <lambda>
[task 2022-05-30T13:08:38.005Z] 13:08:38 CRITICAL -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2022-05-30T13:08:38.005Z] 13:08:38 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1574, in navigate
[task 2022-05-30T13:08:38.005Z] 13:08:38 CRITICAL -     self._send_message("WebDriver:Navigate", {"url": url})
[task 2022-05-30T13:08:38.005Z] 13:08:38 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 37, in _
[task 2022-05-30T13:08:38.005Z] 13:08:38 CRITICAL -     m._handle_socket_failure()
[task 2022-05-30T13:08:38.005Z] 13:08:38 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 712, in _handle_socket_failure
[task 2022-05-30T13:08:38.005Z] 13:08:38 CRITICAL -     reraise(exc_cls, exc, tb)
[task 2022-05-30T13:08:38.005Z] 13:08:38 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/six/six.py", line 703, in reraise
[task 2022-05-30T13:08:38.005Z] 13:08:38 CRITICAL -     raise value
[task 2022-05-30T13:08:38.005Z] 13:08:38 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2022-05-30T13:08:38.005Z] 13:08:38 CRITICAL -     return func(*args, **kwargs)
[task 2022-05-30T13:08:38.005Z] 13:08:38 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 661, in _send_message
[task 2022-05-30T13:08:38.006Z] 13:08:38 CRITICAL -     msg = self.client.request(name, params)
[task 2022-05-30T13:08:38.006Z] 13:08:38 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 383, in request
[task 2022-05-30T13:08:38.006Z] 13:08:38 CRITICAL -     return self.receive()
[task 2022-05-30T13:08:38.006Z] 13:08:38 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 230, in receive
[task 2022-05-30T13:08:38.006Z] 13:08:38 CRITICAL -     raise socket.error("No data received over socket")
[task 2022-05-30T13:08:38.006Z] 13:08:38 CRITICAL - OSError: No data received over socket
[task 2022-05-30T13:08:38.006Z] 13:08:38 CRITICAL - 
<...>
[task 2022-05-30T13:08:38.012Z] 13:08:38  WARNING - OSError: No data received over socket
[task 2022-05-30T13:08:38.012Z] 13:08:38  WARNING - 
[task 2022-05-30T13:08:38.012Z] 13:08:38  WARNING - Command left in command_queue during cleanup: 'test_ended', (<wptrunner.wpttest.TestharnessTest /html/dom/idlharness.worker.html>, (<wptrunner.wpttest.TestharnessResult INTERNAL-ERROR>, []))
[task 2022-05-30T13:08:38.012Z] 13:08:38     INFO - Closing logging queue
[task 2022-05-30T13:08:38.013Z] 13:08:38     INFO - queue closed
[task 2022-05-30T13:08:38.040Z] 13:08:38     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpsgofgsir
[task 2022-05-30T13:08:38.080Z] 13:08:38     INFO - PID 19954 | [CodeCoverage] Setting handlers for process 19954.
[task 2022-05-30T13:08:38.081Z] 13:08:38     INFO - PID 19954 | 1653915997337	Marionette	INFO	Marionette enabled
[task 2022-05-30T13:08:38.083Z] 13:08:38     INFO - PID 19954 | 1653915997344	Marionette	INFO	Listening on port 33562
[task 2022-05-30T13:08:38.087Z] 13:08:38     INFO - PID 19954 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpdy57i1xk/search.json.mozlz4", (void 0)))
[task 2022-05-30T13:08:38.088Z] 13:08:38     INFO - Starting runner
[task 2022-05-30T13:08:39.043Z] 13:08:39     INFO - PID 19954 | [CodeCoverage] Setting handlers for process 20089.
[task 2022-05-30T13:08:39.176Z] 13:08:39     INFO - PID 19954 | [CodeCoverage] Setting handlers for process 20078.
[task 2022-05-30T13:08:41.051Z] 13:08:41     INFO - PID 19954 | [CodeCoverage] Setting handlers for process 20312.
[task 2022-05-30T13:08:41.425Z] 13:08:41     INFO - TEST-START | /html/dom/reflection-embedded.html

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

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.