Open Bug 1906176 Opened 2 months ago Updated 2 months ago

Intermittent [TVw] TEST-UNEXPECTED-NOTRUN | /webrtc/RTCDataChannel-close.html | Repeated open/send/echo/close negotiated datachannel works - expected PASS

Categories

(Core :: WebRTC, defect, P5)

Product:
Core
Component:
WebRTC
Type:
defect

Tracking

()

Tracking Flags:
Tracking Status
firefox-esr115 --- unaffected
firefox-esr128 --- unaffected
firefox127 --- unaffected
firefox128 --- unaffected
firefox129 --- affected
firefox130 --- affected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

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

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

[task 2024-07-03T19:03:44.077Z] 19:03:44     INFO - TEST-PASS | /webrtc/RTCDataChannel-close.html | Close peerconnection after datachannel close causes no events 
[task 2024-07-03T19:03:44.078Z] 19:03:44     INFO - TEST-KNOWN-INTERMITTENT-TIMEOUT | /webrtc/RTCDataChannel-close.html | Close peerconnection causes close event and error on many channels, datachannel - Test timed out
[task 2024-07-03T19:03:44.078Z] 19:03:44     INFO - 
[task 2024-07-03T19:03:44.078Z] 19:03:44     INFO - TEST-KNOWN-INTERMITTENT-NOTRUN | /webrtc/RTCDataChannel-close.html | Close negotiated datachannel causes onclosing and onclose to be called 
[task 2024-07-03T19:03:44.079Z] 19:03:44     INFO - 
[task 2024-07-03T19:03:44.079Z] 19:03:44     INFO - TEST-KNOWN-INTERMITTENT-NOTRUN | /webrtc/RTCDataChannel-close.html | Close negotiated datachannel causes closing and close event to be called 
[task 2024-07-03T19:03:44.080Z] 19:03:44     INFO - 
[task 2024-07-03T19:03:44.080Z] 19:03:44     INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCDataChannel-close.html | Repeated open/send/echo/close negotiated datachannel works - expected PASS
[task 2024-07-03T19:03:44.080Z] 19:03:44     INFO - 
[task 2024-07-03T19:03:44.080Z] 19:03:44     INFO - TEST-KNOWN-INTERMITTENT-NOTRUN | /webrtc/RTCDataChannel-close.html | Close peerconnection causes close event and error to be called on negotiated datachannel 
[task 2024-07-03T19:03:44.082Z] 19:03:44     INFO - 
[task 2024-07-03T19:03:44.082Z] 19:03:44     INFO - TEST-KNOWN-INTERMITTENT-NOTRUN | /webrtc/RTCDataChannel-close.html | Close peerconnection after negotiated datachannel close causes no events 
[task 2024-07-03T19:03:44.083Z] 19:03:44     INFO - 
[task 2024-07-03T19:03:44.083Z] 19:03:44     INFO - TEST-KNOWN-INTERMITTENT-NOTRUN | /webrtc/RTCDataChannel-close.html | Close peerconnection causes close event and error on many channels, negotiated datachannel 
[task 2024-07-03T19:03:44.083Z] 19:03:44     INFO - TEST-KNOWN-INTERMITTENT-TIMEOUT | /webrtc/RTCDataChannel-close.html | took 60222ms
[task 2024-07-03T19:03:44.085Z] 19:03:44     INFO - PID 2228 | 1720033424080	Marionette	INFO	Stopped listening on port 49962
[task 2024-07-03T19:03:44.168Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2589: Close: Closing PeerConnectionImpl {404883f4-24d6-475d-bee9-59ef51a4fa3c}; ending call
[task 2024-07-03T19:03:44.169Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: I/jsep [{404883f4-24d6-475d-bee9-59ef51a4fa3c} 1720033364820000 (id=8589934638 url=http://web-platform.test:8000/webrtc/RTCDataChannel-close.html)]: stable -> closed
[task 2024-07-03T19:03:44.171Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2596: Close: Destroying DataChannelConnection 2077b57cc00 for {404883f4-24d6-475d-bee9-59ef51a4fa3c}
[task 2024-07-03T19:03:44.171Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel Destroying DataChannelConnection 2077b57cc00
[task 2024-07-03T19:03:44.172Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel Closing all channels (connection 2077b57cc00)
[task 2024-07-03T19:03:44.173Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel DataChannelConnection labeled transport_0 (2077b57cc00) switching connection state OPEN -> CLOSED
[task 2024-07-03T19:03:44.174Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel Connection 2077b57cc00/Channel 2077b4894a0: Closing stream 1
[task 2024-07-03T19:03:44.174Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel Removing channel 1 : 2077b4894a0
[task 2024-07-03T19:03:44.175Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel Channel already closing/closed (CLOSING)
[task 2024-07-03T19:03:44.176Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel Connection 2077b57cc00: Sending outgoing stream reset for 1 streams
[task 2024-07-03T19:03:44.177Z] 19:03:44     INFO - PID 2228 | [Child 7888: Socket Thread]: D/DataChannel Deregistered b from the SCTP stack.
[task 2024-07-03T19:03:44.178Z] 19:03:44     INFO - PID 2228 | [Child 7888: Socket Thread]: D/DataChannel Shutting down connection 2077b57cc00, id b
[task 2024-07-03T19:03:44.179Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2589: Close: Closing PeerConnectionImpl {28f35cb1-db24-48cd-8ffa-b81e7e0f1b99}; ending call
[task 2024-07-03T19:03:44.180Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: I/jsep [{28f35cb1-db24-48cd-8ffa-b81e7e0f1b99} 1720033364821000 (id=8589934638 url=http://web-platform.test:8000/webrtc/RTCDataChannel-close.html)]: stable -> closed
[task 2024-07-03T19:03:44.181Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2596: Close: Destroying DataChannelConnection 2077b546a00 for {28f35cb1-db24-48cd-8ffa-b81e7e0f1b99}
[task 2024-07-03T19:03:44.182Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel Destroying DataChannelConnection 2077b546a00
[task 2024-07-03T19:03:44.182Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel Closing all channels (connection 2077b546a00)
[task 2024-07-03T19:03:44.183Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel DataChannelConnection labeled transport_0 (2077b546a00) switching connection state OPEN -> CLOSED
[task 2024-07-03T19:03:44.184Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel Connection 2077b546a00: Sending outgoing stream reset for 0 streams
[task 2024-07-03T19:03:44.185Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel No streams to reset
[task 2024-07-03T19:03:44.186Z] 19:03:44     INFO - PID 2228 | [Child 7888: Socket Thread]: D/DataChannel Deregistered c from the SCTP stack.
[task 2024-07-03T19:03:44.186Z] 19:03:44     INFO - PID 2228 | [Child 7888: Socket Thread]: D/DataChannel Shutting down connection 2077b546a00, id c
[task 2024-07-03T19:03:44.187Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel Deregistering connection ulp = b
[task 2024-07-03T19:03:44.188Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel Deleting DataChannelConnection 2077b57cc00
[task 2024-07-03T19:03:44.188Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel Deregistering connection ulp = c
[task 2024-07-03T19:03:44.190Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel Deleting DataChannelConnection 2077b546a00
[task 2024-07-03T19:03:44.191Z] 19:03:44     INFO - PID 2228 | [Child 7888: Main Thread]: D/DataChannel Calling usrsctp_finish 2077a820160
[task 2024-07-03T19:03:44.192Z] 19:03:44     INFO - PID 2228 | [Child 7888: Socket Thread]: I/mtransport NrIceCtx(PC:{404883f4-24d6-475d-bee9-59ef51a4fa3c} 1720033364820000 (id=8589934638 url=http://web-platform.test:8000/webrtc/RTCDataChannel-close.html)): Destroy
[task 2024-07-03T19:03:44.192Z] 19:03:44     INFO - PID 2228 | [Child 7888: Socket Thread]: I/mtransport NrIceCtx(PC:{28f35cb1-db24-48cd-8ffa-b81e7e0f1b99} 1720033364821000 (id=8589934638 url=http://web-platform.test:8000/webrtc/RTCDataChannel-close.html)): Destroy
[task 2024-07-03T19:03:44.462Z] 19:03:44     INFO - Browser exited with return code 0
[task 2024-07-03T19:03:44.466Z] 19:03:44     INFO - Closing logging queue
[task 2024-07-03T19:03:44.467Z] 19:03:44     INFO - queue closed
[task 2024-07-03T19:03:44.525Z] 19:03:44     INFO - Application command: D:\task_172003292160642\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_172003292160642\AppData\Local\Temp\tmpv5z1p0ei
[task 2024-07-03T19:03:44.539Z] 19:03:44     INFO - Starting runner
[task 2024-07-03T19:03:45.167Z] 19:03:45     INFO - PID 6424 | 1720033425166	Marionette	INFO	Marionette enabled
[task 2024-07-03T19:03:45.260Z] 19:03:45     INFO - PID 6424 | 1720033425269	Marionette	INFO	Listening on port 50006
[task 2024-07-03T19:03:48.124Z] 19:03:48     INFO - PID 6424 | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-07-03T19:03:48.132Z] 19:03:48     INFO - PID 6424 | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-07-03T19:03:48.383Z] 19:03:48     INFO - TEST-START | /webrtc/RTCDataChannel-close.html

:bwc, since you are the author of the regressor, bug 1902850, could you take a look?

For more information, please visit BugBot documentation.

Flags: needinfo?(docfaraday)

This is a known intermittent.

Flags: needinfo?(docfaraday)

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

status-firefox127: --- → unaffected
status-firefox128: --- → unaffected
status-firefox129: --- → affected
status-firefox-esr115: --- → unaffected

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

status-firefox130: --- → affected
status-firefox-esr128: --- → unaffected
You need to log in before you can comment on or make changes to this bug.