Closed Bug 1826849 Opened 3 years ago Closed 3 years ago

Intermittent /websockets/Close-1005-verify-code.any.worker.html | single tracking bug

Categories

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

Product:
Core
Component:
Networking: WebSockets
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

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

[task 2023-04-06T18:47:40.369Z] 18:47:40     INFO - TEST-OK | /websockets/Close-1005-verify-code.any.html?wpt_flags=h2 | took 917ms
[task 2023-04-06T18:47:40.371Z] 18:47:40     INFO - TEST-START | /websockets/Close-1005-verify-code.any.worker.html
[task 2023-04-06T18:47:40.385Z] 18:47:40     INFO - Closing window be8df345-ebfe-4cb7-9098-a87f464f7ca4
[task 2023-04-06T18:48:15.385Z] 18:48:15     INFO - Got timeout in harness
[task 2023-04-06T18:48:15.822Z] 18:48:15     INFO - TEST-UNEXPECTED-TIMEOUT | /websockets/Close-1005-verify-code.any.worker.html | TestRunner hit external timeout (this may indicate a hang)
[task 2023-04-06T18:48:15.822Z] 18:48:15     INFO - TEST-INFO took 35453ms
[task 2023-04-06T18:49:26.912Z] 18:49:26     INFO - Browser exited with return code -15
[task 2023-04-06T18:49:26.986Z] 18:49:26 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 2023-04-06T18:49:26.986Z] 18:49:26 CRITICAL - Traceback (most recent call last):
[task 2023-04-06T18:49:26.986Z] 18:49:26 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 166, in load_runner
[task 2023-04-06T18:49:26.986Z] 18:49:26 CRITICAL -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2023-04-06T18:49:26.986Z] 18:49:26 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 214, in dismiss_alert
[task 2023-04-06T18:49:26.986Z] 18:49:26 CRITICAL -     f()
[task 2023-04-06T18:49:26.987Z] 18:49:26 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 166, in <lambda>
[task 2023-04-06T18:49:26.987Z] 18:49:26 CRITICAL -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2023-04-06T18:49:26.987Z] 18:49:26 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 1632, in navigate
[task 2023-04-06T18:49:26.987Z] 18:49:26 CRITICAL -     self._send_message("WebDriver:Navigate", {"url": url})
[task 2023-04-06T18:49:26.987Z] 18:49:26 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/decorators.py", line 34, in _
[task 2023-04-06T18:49:26.987Z] 18:49:26 CRITICAL -     m._handle_socket_failure()
[task 2023-04-06T18:49:26.988Z] 18:49:26 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 747, in _handle_socket_failure
[task 2023-04-06T18:49:26.988Z] 18:49:26 CRITICAL -     reraise(exc_cls, exc, tb)
[task 2023-04-06T18:49:26.988Z] 18:49:26 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/six/six.py", line 703, in reraise
[task 2023-04-06T18:49:26.988Z] 18:49:26 CRITICAL -     raise value
[task 2023-04-06T18:49:26.989Z] 18:49:26 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/decorators.py", line 24, in _
[task 2023-04-06T18:49:26.989Z] 18:49:26 CRITICAL -     return func(*args, **kwargs)
[task 2023-04-06T18:49:26.989Z] 18:49:26 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 696, in _send_message
[task 2023-04-06T18:49:26.989Z] 18:49:26 CRITICAL -     msg = self.client.request(name, params)
[task 2023-04-06T18:49:26.989Z] 18:49:26 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/transport.py", line 381, in request
[task 2023-04-06T18:49:26.990Z] 18:49:26 CRITICAL -     return self.receive()
[task 2023-04-06T18:49:26.990Z] 18:49:26 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/transport.py", line 228, in receive
[task 2023-04-06T18:49:26.990Z] 18:49:26 CRITICAL -     raise socket.error("No data received over socket")
[task 2023-04-06T18:49:26.990Z] 18:49:26 CRITICAL - OSError: No data received over socket
[task 2023-04-06T18:49:26.991Z] 18:49:26 CRITICAL - 
[task 2023-04-06T18:49:26.991Z] 18:49:26  WARNING - Traceback (most recent call last):
[task 2023-04-06T18:49:26.991Z] 18:49:26  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/base.py", line 300, in run_test
[task 2023-04-06T18:49:26.991Z] 18:49:26  WARNING -     self.on_environment_change(test.environment)
[task 2023-04-06T18:49:26.990Z] 18:49:26 CRITICAL - OSError: No data received over socket
[task 2023-04-06T18:49:26.991Z] 18:49:26 CRITICAL - 
[task 2023-04-06T18:49:26.991Z] 18:49:26  WARNING - Traceback (most recent call last):
[task 2023-04-06T18:49:26.991Z] 18:49:26  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/base.py", line 300, in run_test
[task 2023-04-06T18:49:26.991Z] 18:49:26  WARNING -     self.on_environment_change(test.environment)
[task 2023-04-06T18:49:26.992Z] 18:49:26  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 910, in on_environment_change
[task 2023-04-06T18:49:26.992Z] 18:49:26  WARNING -     self.protocol.testharness.load_runner(new_environment["protocol"])
[task 2023-04-06T18:49:26.992Z] 18:49:26  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 166, in load_runner
[task 2023-04-06T18:49:26.992Z] 18:49:26  WARNING -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2023-04-06T18:49:26.992Z] 18:49:26  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 214, in dismiss_alert
[task 2023-04-06T18:49:26.992Z] 18:49:26  WARNING -     f()
[task 2023-04-06T18:49:26.993Z] 18:49:26  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 166, in <lambda>
[task 2023-04-06T18:49:26.993Z] 18:49:26  WARNING -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2023-04-06T18:49:26.993Z] 18:49:26  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 1632, in navigate
[task 2023-04-06T18:49:26.993Z] 18:49:26  WARNING -     self._send_message("WebDriver:Navigate", {"url": url})
[task 2023-04-06T18:49:26.993Z] 18:49:26  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/decorators.py", line 34, in _
[task 2023-04-06T18:49:26.993Z] 18:49:26  WARNING -     m._handle_socket_failure()
[task 2023-04-06T18:49:26.993Z] 18:49:26  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 747, in _handle_socket_failure
[task 2023-04-06T18:49:26.993Z] 18:49:26  WARNING -     reraise(exc_cls, exc, tb)
[task 2023-04-06T18:49:26.993Z] 18:49:26  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/six/six.py", line 703, in reraise
[task 2023-04-06T18:49:26.993Z] 18:49:26  WARNING -     raise value
[task 2023-04-06T18:49:26.993Z] 18:49:26  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/decorators.py", line 24, in _
[task 2023-04-06T18:49:26.994Z] 18:49:26  WARNING -     return func(*args, **kwargs)
[task 2023-04-06T18:49:26.994Z] 18:49:26  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 696, in _send_message
[task 2023-04-06T18:49:26.994Z] 18:49:26  WARNING -     msg = self.client.request(name, params)
[task 2023-04-06T18:49:26.994Z] 18:49:26  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/transport.py", line 381, in request
[task 2023-04-06T18:49:26.994Z] 18:49:26  WARNING -     return self.receive()
[task 2023-04-06T18:49:26.994Z] 18:49:26  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/transport.py", line 228, in receive
[task 2023-04-06T18:49:26.994Z] 18:49:26  WARNING -     raise socket.error("No data received over socket")
[task 2023-04-06T18:49:26.994Z] 18:49:26  WARNING - OSError: No data received over socket
[task 2023-04-06T18:49:26.994Z] 18:49:26  WARNING - 
[task 2023-04-06T18:49:26.995Z] 18:49:26  WARNING - Command left in command_queue during cleanup: 'test_ended', (<wptrunner.wpttest.TestharnessTest /websockets/Close-1005-verify-code.any.worker.html>, (<wptrunner.wpttest.TestharnessResult INTERNAL-ERROR>, []))
[task 2023-04-06T18:49:26.995Z] 18:49:26     INFO - Closing logging queue
[task 2023-04-06T18:49:26.995Z] 18:49:26     INFO - queue closed
[task 2023-04-06T18:49:27.005Z] 18:49:27     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpmqb85amg
[task 2023-04-06T18:49:26.994Z] 18:49:26  WARNING - OSError: No data received over socket
[task 2023-04-06T18:49:26.994Z] 18:49:26  WARNING - 
[task 2023-04-06T18:49:26.995Z] 18:49:26  WARNING - Command left in command_queue during cleanup: 'test_ended', (<wptrunner.wpttest.TestharnessTest /websockets/Close-1005-verify-code.any.worker.html>, (<wptrunner.wpttest.TestharnessResult INTERNAL-ERROR>, []))
[task 2023-04-06T18:49:26.995Z] 18:49:26     INFO - Closing logging queue
[task 2023-04-06T18:49:26.995Z] 18:49:26     INFO - queue closed
[task 2023-04-06T18:49:27.005Z] 18:49:27     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpmqb85amg
[task 2023-04-06T18:49:27.027Z] 18:49:27     INFO - PID 22853 | [CodeCoverage] Setting handlers for process 22853.
[task 2023-04-06T18:49:27.028Z] 18:49:27     INFO - PID 22853 | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2023-04-06T18:49:27.029Z] 18:49:27     INFO - PID 22853 | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2023-04-06T18:49:27.029Z] 18:49:27     INFO - PID 22853 | 1680806838448	Marionette	INFO	Marionette enabled
[task 2023-04-06T18:49:27.030Z] 18:49:27     INFO - PID 22853 | 1680806838458	Marionette	INFO	Listening on port 39384
[task 2023-04-06T18:49:27.030Z] 18:49:27     INFO - PID 22853 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-04-06T18:49:27.031Z] 18:49:27     INFO - PID 22853 | [CodeCoverage] Setting handlers for process 22976.
[task 2023-04-06T18:49:27.032Z] 18:49:27     INFO - PID 22853 | [CodeCoverage] Setting handlers for process 22982.
[task 2023-04-06T18:49:27.032Z] 18:49:27     INFO - PID 22853 | console.error: ({})
[task 2023-04-06T18:49:27.033Z] 18:49:27     INFO - PID 22853 | [Child 22976, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-04-06T18:49:27.034Z] 18:49:27     INFO - PID 22853 | [Child 22976, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-04-06T18:49:27.034Z] 18:49:27     INFO - PID 22853 | [ERROR glean_core] Error setting metrics feature config: Json(Error("EOF while parsing a value", line: 1, column: 0))
[task 2023-04-06T18:49:27.035Z] 18:49:27     INFO - PID 22853 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-04-06T18:49:27.035Z] 18:49:27     INFO - PID 22853 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-04-06T18:49:27.036Z] 18:49:27     INFO - PID 22853 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-04-06T18:49:27.037Z] 18:49:27     INFO - PID 22853 | [CodeCoverage] Setting handlers for process 23300.
[task 2023-04-06T18:49:27.037Z] 18:49:27     INFO - PID 22853 | [CodeCoverage] Setting handlers for process 23301.
[task 2023-04-06T18:49:27.038Z] 18:49:27     INFO - PID 22853 | [CodeCoverage] Setting handlers for process 23304.
[task 2023-04-06T18:49:27.038Z] 18:49:27     INFO - PID 22853 | ALSA lib seq_hw.c:466:(snd_seq_hw_open) open /dev/snd/seq failed: No such file or directory
[task 2023-04-06T18:49:27.039Z] 18:49:27     INFO - PID 22853 | console.error: (new Error("Polling for changes failed: Unexpected content-type \"text/plain;charset=US-ASCII\".", "resource://services-settings/remote-settings.sys.mjs", 324))
[task 2023-04-06T18:49:27.039Z] 18:49:27     INFO - Starting runner
[task 2023-04-06T18:49:28.252Z] 18:49:28     INFO - TEST-START | /websockets/Close-1005-verify-code.any.html?wss

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

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