Closed Bug 1830966 Opened 3 years ago Closed 3 years ago

Intermittent TEST-UNEXPECTED-NOTRUN | /webtransport/streams-echo.https.any.html | single tracking bug

Categories

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

Product:
Core
Component:
Networking: HTTP
Type:
defect

Tracking

()

Status:
RESOLVED DUPLICATE of bug 1830965
Tracking Flags:
Tracking Status
firefox-esr102 --- unaffected
firefox112 --- unaffected
firefox113 --- unaffected
firefox114 --- affected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, test-verify-fail)

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=414363777&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Xgv5q5gBRcemCa0_gkjxxw/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/Xgv5q5gBRcemCa0_gkjxxw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2023-05-02T17:40:03.810Z] 17:40:03     INFO - TEST-START | /webtransport/streams-echo.https.any.html
[task 2023-05-02T17:40:03.811Z] 17:40:03     INFO - Run 8/10
[task 2023-05-02T17:40:03.812Z] 17:40:03     INFO - Closing window a99df252-6f18-4012-9abe-c33b0c7220de
[task 2023-05-02T17:40:06.723Z] 17:40:06  WARNING - wptserve cannot call write() after FIN
[task 2023-05-02T17:40:06.724Z] 17:40:06     INFO - Test harness output was not a valid structured log message
[task 2023-05-02T17:40:06.724Z] 17:40:06     INFO - Traceback (most recent call last):
[task 2023-05-02T17:40:06.724Z] 17:40:06     INFO -   File "Z:\task_168304807850142\build\tests\web-platform\tests\tools\webtransport\h3\webtransport_h3_server.py", line 438, in _run_callback
[task 2023-05-02T17:40:06.724Z] 17:40:06     INFO -     self._callbacks[callback_name](*args, **kwargs)
[task 2023-05-02T17:40:06.724Z] 17:40:06     INFO -   File "/webtransport/handlers/echo.py", line 29, in stream_data_received
[task 2023-05-02T17:40:06.725Z] 17:40:06     INFO -   File "Z:\task_168304807850142\build\tests\web-platform\tests\tools\webtransport\h3\webtransport_h3_server.py", line 387, in send_stream_data
[task 2023-05-02T17:40:06.725Z] 17:40:06     INFO -     self._http._quic.send_stream_data(stream_id=stream_id,
[task 2023-05-02T17:40:06.726Z] 17:40:06     INFO -   File "Z:\task_168304807850142\build\venv\lib\site-packages\aioquic\quic\connection.py", line 1090, in send_stream_data
[task 2023-05-02T17:40:06.726Z] 17:40:06     INFO -     stream.sender.write(data, end_stream=end_stream)
[task 2023-05-02T17:40:06.726Z] 17:40:06     INFO -   File "Z:\task_168304807850142\build\venv\lib\site-packages\aioquic\quic\stream.py", line 300, in write
[task 2023-05-02T17:40:06.726Z] 17:40:06     INFO -     assert self._buffer_fin is None, "cannot call write() after FIN"
[task 2023-05-02T17:40:06.727Z] 17:40:06     INFO - AssertionError: cannot call write() after FIN
[task 2023-05-02T17:40:06.727Z] 17:40:06  WARNING - wptserve cannot call write() after FIN
[task 2023-05-02T17:40:06.727Z] 17:40:06     INFO - Test harness output was not a valid structured log message
[task 2023-05-02T17:40:06.727Z] 17:40:06     INFO - Traceback (most recent call last):
[task 2023-05-02T17:40:06.728Z] 17:40:06     INFO -   File "Z:\task_168304807850142\build\tests\web-platform\tests\tools\webtransport\h3\webtransport_h3_server.py", line 438, in _run_callback
[task 2023-05-02T17:40:06.728Z] 17:40:06     INFO -     self._callbacks[callback_name](*args, **kwargs)
[task 2023-05-02T17:40:06.728Z] 17:40:06     INFO -   File "/webtransport/handlers/echo.py", line 29, in stream_data_received
[task 2023-05-02T17:40:06.728Z] 17:40:06     INFO -   File "Z:\task_168304807850142\build\tests\web-platform\tests\tools\webtransport\h3\webtransport_h3_server.py", line 387, in send_stream_data
[task 2023-05-02T17:40:06.729Z] 17:40:06     INFO -     self._http._quic.send_stream_data(stream_id=stream_id,
[task 2023-05-02T17:40:06.729Z] 17:40:06     INFO -   File "Z:\task_168304807850142\build\venv\lib\site-packages\aioquic\quic\connection.py", line 1090, in send_stream_data
[task 2023-05-02T17:40:06.729Z] 17:40:06     INFO -     stream.sender.write(data, end_stream=end_stream)
[task 2023-05-02T17:40:06.729Z] 17:40:06     INFO -   File "Z:\task_168304807850142\build\venv\lib\site-packages\aioquic\quic\stream.py", line 300, in write
[task 2023-05-02T17:40:06.729Z] 17:40:06     INFO -     assert self._buffer_fin is None, "cannot call write() after FIN"
[task 2023-05-02T17:40:06.730Z] 17:40:06     INFO - AssertionError: cannot call write() after FIN
[task 2023-05-02T17:40:13.931Z] 17:40:13     INFO - 
[task 2023-05-02T17:40:13.931Z] 17:40:13     INFO - TEST-PASS | /webtransport/streams-echo.https.any.html | WebTransport client should be able to create and handle a bidirectional stream 
[task 2023-05-02T17:40:13.931Z] 17:40:13     INFO - TEST-PASS | /webtransport/streams-echo.https.any.html | WebTransport client should be able to create and handle a bidirectional stream without waiting for ready 
[task 2023-05-02T17:40:13.931Z] 17:40:13     INFO - TEST-KNOWN-INTERMITTENT-TIMEOUT | /webtransport/streams-echo.https.any.html | WebTransport server should be able to accept and handle a bidirectional stream - Test timed out
[task 2023-05-02T17:40:13.931Z] 17:40:13     INFO - 
[task 2023-05-02T17:40:13.931Z] 17:40:13     INFO - TEST-UNEXPECTED-NOTRUN | /webtransport/streams-echo.https.any.html | WebTransport client should be able to create, accept, and handle a unidirectional stream - expected PASS
[task 2023-05-02T17:40:13.938Z] 17:40:13     INFO - 
[task 2023-05-02T17:40:13.938Z] 17:40:13     INFO - TEST-UNEXPECTED-NOTRUN | /webtransport/streams-echo.https.any.html | WebTransport client should be able to create, accept, and handle a unidirectional stream without waiting for ready - expected PASS
[task 2023-05-02T17:40:13.938Z] 17:40:13     INFO - 
[task 2023-05-02T17:40:13.938Z] 17:40:13     INFO - TEST-KNOWN-INTERMITTENT-NOTRUN | /webtransport/streams-echo.https.any.html | Can read data from a bidirectional stream with BYOB reader 
[task 2023-05-02T17:40:13.939Z] 17:40:13     INFO - 
[task 2023-05-02T17:40:13.939Z] 17:40:13     INFO - TEST-KNOWN-INTERMITTENT-NOTRUN | /webtransport/streams-echo.https.any.html | Can read data from a unidirectional stream with BYOB reader 
[task 2023-05-02T17:40:13.940Z] 17:40:13     INFO - 
[task 2023-05-02T17:40:13.940Z] 17:40:13     INFO - TEST-KNOWN-INTERMITTENT-NOTRUN | /webtransport/streams-echo.https.any.html | Transfer large chunks of data on a bidirectional stream 
[task 2023-05-02T17:40:13.940Z] 17:40:13     INFO - 
[task 2023-05-02T17:40:13.940Z] 17:40:13     INFO - TEST-KNOWN-INTERMITTENT-NOTRUN | /webtransport/streams-echo.https.any.html | Transfer large chunks of data on a unidirectional stream 
[task 2023-05-02T17:40:13.941Z] 17:40:13     INFO - 
[task 2023-05-02T17:40:13.942Z] 17:40:13     INFO - TEST-KNOWN-INTERMITTENT-NOTRUN | /webtransport/streams-echo.https.any.html | Closing the stream with no data still resolves the read request 
[task 2023-05-02T17:40:13.942Z] 17:40:13     INFO - TEST-KNOWN-INTERMITTENT-TIMEOUT | /webtransport/streams-echo.https.any.html | took 10134ms
[task 2023-05-02T17:40:13.944Z] 17:40:13     INFO - PID 5760 | 1683049213940	Marionette	INFO	Stopped listening on port 51934
[task 2023-05-02T17:40:13.966Z] 17:40:13     INFO - PID 5760 | [Child 2332, 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-05-02T17:40:13.967Z] 17:40:13     INFO - PID 5760 | [Child 2332, 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-05-02T17:40:13.969Z] 17:40:13     INFO - PID 5760 | [Child 2332, 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-05-02T17:40:13.969Z] 17:40:13     INFO - PID 5760 | [Child 2332, 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-05-02T17:40:13.971Z] 17:40:13     INFO - PID 5760 | [Child 2332, 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-05-02T17:40:13.974Z] 17:40:13     INFO - PID 5760 | [Child 2332, 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-05-02T17:40:13.975Z] 17:40:13     INFO - PID 5760 | [Child 2332, 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-05-02T17:40:14.437Z] 17:40:14     INFO - PID 5760 | *** You are running in background task mode. ***
[task 2023-05-02T17:40:14.450Z] 17:40:14     INFO - PID 5760 | *** You are running in headless mode.
[task 2023-05-02T17:40:14.531Z] 17:40:14     INFO - PID 5760 | console.error: "C:\\Users\\task_168304807850142\\AppData\\Local\\Temp\\tmpnh8kyvcq\\storage" "to-be-removed" 0 "" ""
[task 2023-05-02T17:40:14.578Z] 17:40:14     INFO - PID 5760 | console.error: "started removing C:\\Users\\task_168304807850142\\AppData\\Local\\Temp\\tmpnh8kyvcq\\storage\\to-be-removed"
[task 2023-05-02T17:40:14.592Z] 17:40:14     INFO - PID 5760 | console.error: "done removing C:\\Users\\task_168304807850142\\AppData\\Local\\Temp\\tmpnh8kyvcq\\storage\\to-be-removed. removed 1 entries."
[task 2023-05-02T17:40:14.593Z] 17:40:14     INFO - PID 5760 | console.warn: "Skipping Glean as no metrics id is passed"
[task 2023-05-02T17:40:15.440Z] 17:40:15     INFO - Browser exited with return code 0
[task 2023-05-02T17:40:15.444Z] 17:40:15     INFO - Closing logging queue
[task 2023-05-02T17:40:15.445Z] 17:40:15     INFO - queue closed
[task 2023-05-02T17:40:15.472Z] 17:40:15     INFO - Application command: Z:\task_168304807850142\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_168304807850142\AppData\Local\Temp\tmphhzrlrbi
[task 2023-05-02T17:40:15.487Z] 17:40:15     INFO - Starting runner
[task 2023-05-02T17:40:15.956Z] 17:40:15     INFO - PID 8304 | 1683049215957	Marionette	INFO	Marionette enabled
[task 2023-05-02T17:40:15.960Z] 17:40:15     INFO - PID 8304 | 1683049215960	Marionette	INFO	Listening on port 51963
[task 2023-05-02T17:40:16.755Z] 17:40:16     INFO - PID 8304 | JavaScript error: resource://gre/modules/XULStore.sys.mjs, line 60: Error: Can't find profile directory.
[task 2023-05-02T17:40:17.665Z] 17:40:17     INFO - PID 8304 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-05-02T17:40:17.759Z] 17:40:17     INFO - PID 8304 | [Child 8896, 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-05-02T17:40:17.763Z] 17:40:17     INFO - PID 8304 | [Child 8896, 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-05-02T17:40:19.123Z] 17:40:19     INFO - PID 8304 | console.error: (new TypeError("can't access property \"find\", this._searchProviderInfo is null", "resource:///modules/SearchSERPTelemetry.sys.mjs", 638))
[task 2023-05-02T17:40:19.185Z] 17:40:19     INFO - TEST-START | /webtransport/streams-echo.https.any.html

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

:jesup, since you are the author of the regressor, bug 1830096, could you take a look?

For more information, please visit BugBot documentation.

status-firefox112: --- → unaffected
status-firefox113: --- → unaffected
status-firefox114: --- → affected
status-firefox-esr102: --- → unaffected
Flags: needinfo?(rjesup)

https://treeherder.mozilla.org/logviewer?job_id=414478380&repo=autoland

this might be fixed after https://bugzilla.mozilla.org/show_bug.cgi?id=1830965#c2

Summary: Intermittent TVw TEST-UNEXPECTED-NOTRUN | /webtransport/streams-echo.https.any.html | single tracking bug → Intermittent TEST-UNEXPECTED-NOTRUN | /webtransport/streams-echo.https.any.html | single tracking bug
See Also: → 1831308

Here also the server is panicking. Will probably be fixed by bug 1830965

Status: NEW → RESOLVED
Closed: 3 years ago
Duplicate of bug: 1830965
Resolution: --- → DUPLICATE
Flags: needinfo?(rjesup)
You need to log in before you can comment on or make changes to this bug.