Closed Bug 1744417 Opened 4 years ago Closed 3 years ago

Intermittent [tier2] TEST-UNEXPECTED-TIMEOUT | /websockets/unload-a-document/002.html?wpt_flags=h2 | TestRunner hit external timeout (this may indicate a hang)

Categories

(Core :: Networking: WebSockets, defect, P5)

Product:
Core
Component:
Networking: WebSockets
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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

task 2021-12-04T23:29:11.243Z] 23:29:11     INFO - TEST-START | /websockets/unload-a-document/002.html?wpt_flags=h2
[task 2021-12-04T23:29:11.251Z] 23:29:11     INFO - Closing window a5fec3c1-26ad-495e-9008-7e3d088c1f9a
[task 2021-12-04T23:29:11.438Z] 23:29:11     INFO - PID 28004 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-12-04T23:29:11.440Z] 23:29:11     INFO - PID 28004 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-12-04T23:31:26.246Z] 23:31:26     INFO - Got timeout in harness
[task 2021-12-04T23:31:26.248Z] 23:31:26     INFO - TEST-UNEXPECTED-TIMEOUT | /websockets/unload-a-document/002.html?wpt_flags=h2 | TestRunner hit external timeout (this may indicate a hang)
[task 2021-12-04T23:31:26.248Z] 23:31:26     INFO - TEST-INFO took 135006ms
[task 2021-12-04T23:32:37.306Z] 23:32:37     INFO - Browser exited with return code -15
[task 2021-12-04T23:32:37.354Z] 23:32:37 CRITICAL - Loading initial page https://web-platform.test:9000/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 2021-12-04T23:32:37.354Z] 23:32:37 CRITICAL - Traceback (most recent call last):
[task 2021-12-04T23:32:37.354Z] 23:32:37 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 164, in load_runner
[task 2021-12-04T23:32:37.355Z] 23:32:37 CRITICAL -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2021-12-04T23:32:37.355Z] 23:32:37 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 212, in dismiss_alert
[task 2021-12-04T23:32:37.355Z] 23:32:37 CRITICAL -     f()
[task 2021-12-04T23:32:37.355Z] 23:32:37 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 164, in <lambda>
[task 2021-12-04T23:32:37.356Z] 23:32:37 CRITICAL -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2021-12-04T23:32:37.356Z] 23:32:37 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1561, in navigate
[task 2021-12-04T23:32:37.356Z] 23:32:37 CRITICAL -     self._send_message("WebDriver:Navigate", {"url": url})
[task 2021-12-04T23:32:37.356Z] 23:32:37 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 37, in _
[task 2021-12-04T23:32:37.356Z] 23:32:37 CRITICAL -     m._handle_socket_failure()
[task 2021-12-04T23:32:37.356Z] 23:32:37 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 724, in _handle_socket_failure
[task 2021-12-04T23:32:37.356Z] 23:32:37 CRITICAL -     reraise(exc_cls, exc, tb)
[task 2021-12-04T23:32:37.356Z] 23:32:37 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/six/six.py", line 703, in reraise
[task 2021-12-04T23:32:37.356Z] 23:32:37 CRITICAL -     raise value
[task 2021-12-04T23:32:37.356Z] 23:32:37 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2021-12-04T23:32:37.356Z] 23:32:37 CRITICAL -     return func(*args, **kwargs)
[task 2021-12-04T23:32:37.356Z] 23:32:37 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 660, in _send_message
[task 2021-12-04T23:32:37.357Z] 23:32:37 CRITICAL -     msg = self.client.request(name, params)
[task 2021-12-04T23:32:37.357Z] 23:32:37 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 383, in request
[task 2021-12-04T23:32:37.357Z] 23:32:37 CRITICAL -     return self.receive()
[task 2021-12-04T23:32:37.357Z] 23:32:37 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 230, in receive
[task 2021-12-04T23:32:37.357Z] 23:32:37 CRITICAL -     raise socket.error("No data received over socket")
[task 2021-12-04T23:32:37.357Z] 23:32:37 CRITICAL - OSError: No data received over socket
[task 2021-12-04T23:32:37.357Z] 23:32:37 CRITICAL - 
[task 2021-12-04T23:32:37.357Z] 23:32:37  WARNING - Traceback (most recent call last):
task 2021-12-04T23:32:37.357Z] 23:32:37  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/base.py", line 299, in run_test
[task 2021-12-04T23:32:37.357Z] 23:32:37  WARNING -     self.on_environment_change(test.environment)
[task 2021-12-04T23:32:37.357Z] 23:32:37  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 882, in on_environment_change
[task 2021-12-04T23:32:37.357Z] 23:32:37  WARNING -     self.protocol.testharness.load_runner(new_environment["protocol"])
[task 2021-12-04T23:32:37.357Z] 23:32:37  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 164, in load_runner
[task 2021-12-04T23:32:37.358Z] 23:32:37  WARNING -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2021-12-04T23:32:37.358Z] 23:32:37  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 212, in dismiss_alert
[task 2021-12-04T23:32:37.358Z] 23:32:37  WARNING -     f()
[task 2021-12-04T23:32:37.358Z] 23:32:37  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 164, in <lambda>
[task 2021-12-04T23:32:37.358Z] 23:32:37  WARNING -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2021-12-04T23:32:37.358Z] 23:32:37  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1561, in navigate
[task 2021-12-04T23:32:37.358Z] 23:32:37  WARNING -     self._send_message("WebDriver:Navigate", {"url": url})
[task 2021-12-04T23:32:37.358Z] 23:32:37  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 37, in _
[task 2021-12-04T23:32:37.358Z] 23:32:37  WARNING -     m._handle_socket_failure()
[task 2021-12-04T23:32:37.358Z] 23:32:37  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 724, in _handle_socket_failure
[task 2021-12-04T23:32:37.358Z] 23:32:37  WARNING -     reraise(exc_cls, exc, tb)
[task 2021-12-04T23:32:37.358Z] 23:32:37  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/six/six.py", line 703, in reraise
[task 2021-12-04T23:32:37.359Z] 23:32:37  WARNING -     raise value
[task 2021-12-04T23:32:37.359Z] 23:32:37  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2021-12-04T23:32:37.359Z] 23:32:37  WARNING -     return func(*args, **kwargs)
[task 2021-12-04T23:32:37.359Z] 23:32:37  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 660, in _send_message
[task 2021-12-04T23:32:37.359Z] 23:32:37  WARNING -     msg = self.client.request(name, params)
[task 2021-12-04T23:32:37.359Z] 23:32:37  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 383, in request
[task 2021-12-04T23:32:37.359Z] 23:32:37  WARNING -     return self.receive()
[task 2021-12-04T23:32:37.359Z] 23:32:37  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 230, in receive
[task 2021-12-04T23:32:37.359Z] 23:32:37  WARNING -     raise socket.error("No data received over socket")
[task 2021-12-04T23:32:37.360Z] 23:32:37  WARNING - OSError: No data received over socket
[task 2021-12-04T23:32:37.360Z] 23:32:37  WARNING - 
[task 2021-12-04T23:32:37.360Z] 23:32:37  WARNING - Command left in command_queue during cleanup: 'test_ended', (<wptrunner.wpttest.TestharnessTest /websockets/unload-a-document/002.html?wpt_flags=h2>, (<wptrunner.wpttest.TestharnessResult INTERNAL-ERROR>, []))
[task 2021-12-04T23:32:37.360Z] 23:32:37     INFO - Closing logging queue
[task 2021-12-04T23:32:37.360Z] 23:32:37     INFO - queue closed

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

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=382017830&repo=mozilla-central

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

Status: REOPENED → RESOLVED
Closed: 3 years ago → 3 years ago
Resolution: --- → INCOMPLETE

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=385086405&repo=mozilla-central

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

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