1906287 - Intermittent dom/media/webrtc/tests/mochitests/test_peerConnection_basicAudioNATRelayTLS.html | single tracking bug

Status: RESOLVED INCOMPLETE

(Core :: WebRTC: Audio/Video, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2024-07-04T11:09:18.269Z] 11:09:18     INFO - TEST-PASS | dom/media/webrtc/tests/mochitests/test_peerConnection_basicAudioNATRelayTLS.html | Have remote-candidate stat(s) 
[task 2024-07-04T11:09:18.269Z] 11:09:18     INFO - Run step 39: PC_LOCAL_CHECK_ICE_CONNECTION_TYPE
[task 2024-07-04T11:09:18.276Z] 11:09:18     INFO - PeerConnectionWrapper (pcLocal): Got stats: {"1ce5ca4b":{"id":"1ce5ca4b","timestamp":1720091357673,"type":"inbound-rtp","codecId":"e7776e9a","kind":"audio","mediaType":"audio","ssrc":3407066238,"jitter":0.001,"packetsDiscarded":0,"packetsLost":0,"packetsReceived":38,"audioLevel":0.1000091555528428,"bytesReceived":6278,"concealedSamples":7968,"concealmentEvents":2,"fecPacketsDiscarded":0,"fecPacketsReceived":0,"headerBytesReceived":760,"insertedSamplesForDeceleration":3360,"jitterBufferDelay":2006.4,"jitterBufferEmittedCount":36480,"lastPacketReceivedTimestamp":1720091357574,"remoteId":"a550ed5d","removedSamplesForAcceleration":672,"silentConcealedSamples":5160,"totalAudioEnergy":0.007872625824560787,"totalSamplesDuration":0.9800000000000006,"totalSamplesReceived":47040,"trackIdentifier":"{cc50498a-4463-41e8-a71b-a0b9c0832eaf}"},"ed4658d7":{"id":"ed4658d7","timestamp":1720091357673,"type":"outbound-rtp","codecId":"e7776e9a","kind":"audio","mediaType":"audio","ssrc":3057662147,"bytesSent":6922,"packetsSent":42,"headerBytesSent":840,"nackCount":0,"retransmittedBytesSent":0,"retransmittedPacketsSent":0},"a550ed5d":{"id":"a550ed5d","timestamp":1720091356875,"type":"remote-outbound-rtp","codecId":"e7776e9a","kind":"audio","mediaType":"audio","ssrc":3407066238,"bytesSent":0,"packetsSent":0,"localId":"1ce5ca4b","remoteTimestamp":1720091356874},"mediasource_audio_0{63988677-fb98-419c-84f9-878b796c5d3a}":{"id":"mediasource_audio_0{63988677-fb98-419c-84f9-878b796c5d3a}","timestamp":1720091357673,"type":"media-source","kind":"audio","trackIdentifier":"{63988677-fb98-419c-84f9-878b796c5d3a}"},"{882ea2fc-2c1d-4a1d-a87a-7abd4e8c5cca}":{"id":"{882ea2fc-2c1d-4a1d-a87a-7abd4e8c5cca}","timestamp":1720091357673,"type":"peer-connection","dataChannelsClosed":0,"dataChannelsOpened":0},"fdc24d94":{"id":"fdc24d94","timestamp":1720091357673,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"19128fa0","nominated":false,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"9efc0a04","selected":false,"state":"inprogress","transportId":"transport_0","writable":true},"46d8044b":{"id":"46d8044b","timestamp":1720091357673,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"ed70ffa5","nominated":false,"priority":35782506363879936,"readable":true,"remoteCandidateId":"9efc0a04","selected":false,"state":"inprogress","transportId":"transport_0","writable":true},"19128fa0":{"id":"19128fa0","timestamp":1720091357673,"type":"local-candidate","address":"10.0.0.159","candidateType":"host","port":62476,"priority":2122252543,"protocol":"udp"},"fcd23157":{"id":"fcd23157","timestamp":1720091357673,"type":"local-candidate","address":"10.0.0.159","candidateType":"host","port":52078,"priority":2105524479,"protocol":"tcp"},"ed70ffa5":{"id":"ed70ffa5","timestamp":1720091357673,"type":"local-candidate","address":"10.0.0.159","candidateType":"relay","port":62480,"priority":8331263,"protocol":"udp","relayProtocol":"tls"},"9efc0a04":{"id":"9efc0a04","timestamp":1720091357673,"type":"remote-candidate","address":"10.0.0.159","candidateType":"host","port":62479,"priority":2122252543,"protocol":"udp"},"7da9182a":{"id":"7da9182a","timestamp":1720091357673,"type":"remote-candidate","address":"10.0.0.159","candidateType":"host","port":9,"priority":2105524479,"protocol":"tcp"},"9e8803f3":{"id":"9e8803f3","timestamp":1720091357673,"type":"remote-candidate","address":"10.0.0.159","candidateType":"relay","port":62482,"priority":8331263,"protocol":"udp"},"e7776e9a":{"id":"e7776e9a","timestamp":1720091357673,"type":"codec","channels":2,"clockRate":48000,"mimeType":"audio/opus","payloadType":109,"sdpFmtpLine":"maxplaybackrate=48000;stereo=1;useinbandfec=1","transportId":"transport_0"}}
[task 2024-07-04T11:09:18.277Z] 11:09:18     INFO - Buffered messages finished
[task 2024-07-04T11:09:18.279Z] 11:09:18     INFO - TEST-UNEXPECTED-FAIL | dom/media/webrtc/tests/mochitests/test_peerConnection_basicAudioNATRelayTLS.html | Got local candidate ID undefined for selected pair - didn't expect undefined, but got it
[task 2024-07-04T11:09:18.279Z] 11:09:18     INFO -     SimpleTest.isnot@SimpleTest/SimpleTest.js:528:14
[task 2024-07-04T11:09:18.279Z] 11:09:18     INFO -     checkStatsIceConnectionType@dom/media/webrtc/tests/mochitests/pc.js:2184:10
[task 2024-07-04T11:09:18.280Z] 11:09:18     INFO -     PC_LOCAL_CHECK_ICE_CONNECTION_TYPE/<@dom/media/webrtc/tests/mochitests/templates.js:477:20
[task 2024-07-04T11:09:18.280Z] 11:09:18     INFO -     promise callback*PC_LOCAL_CHECK_ICE_CONNECTION_TYPE@dom/media/webrtc/tests/mochitests/templates.js:476:36
[task 2024-07-04T11:09:18.280Z] 11:09:18     INFO -     execute/</<@dom/media/webrtc/tests/mochitests/head.js:1063:32
[task 2024-07-04T11:09:18.280Z] 11:09:18     INFO -     promise callback*execute/<@dom/media/webrtc/tests/mochitests/head.js:1061:21
[task 2024-07-04T11:09:18.280Z] 11:09:18     INFO -     execute@dom/media/webrtc/tests/mochitests/head.js:1056:8
[task 2024-07-04T11:09:18.280Z] 11:09:18     INFO -     PeerConnectionTest.prototype.run@dom/media/webrtc/tests/mochitests/pc.js:553:22
[task 2024-07-04T11:09:18.280Z] 11:09:18     INFO -     @dom/media/webrtc/tests/mochitests/test_peerConnection_basicAudioNATRelayTLS.html:37:16
[task 2024-07-04T11:09:18.280Z] 11:09:18     INFO -     async*runNetworkTest/<@dom/media/webrtc/tests/mochitests/pc.js:2488:11
[task 2024-07-04T11:09:18.280Z] 11:09:18     INFO -     async*runTestWhenReady@dom/media/webrtc/tests/mochitests/head.js:476:11
[task 2024-07-04T11:09:18.280Z] 11:09:18     INFO -     async*runNetworkTest@dom/media/webrtc/tests/mochitests/pc.js:2485:9
[task 2024-07-04T11:09:18.280Z] 11:09:18     INFO -     async*@dom/media/webrtc/tests/mochitests/test_peerConnection_basicAudioNATRelayTLS.html:20:17
[task 2024-07-04T11:09:18.281Z] 11:09:18     INFO - GECKO(1644) | (generic/INFO) TestNrSocket IP4:10.0.0.159:0/TLS port mapping IP4:10.0.0.159:0/TLS -> IP4:0.0.0.0:5349/TLS last used 19
[task 2024-07-04T11:09:18.281Z] 11:09:18     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-07-04T11:09:18.283Z] 11:09:18     INFO - TEST-UNEXPECTED-FAIL | dom/media/webrtc/tests/mochitests/test_peerConnection_basicAudioNATRelayTLS.html | Got remote candidate ID undefined for selected pair - didn't expect undefined, but got it
[task 2024-07-04T11:09:18.283Z] 11:09:18     INFO -     SimpleTest.isnot@SimpleTest/SimpleTest.js:528:14
[task 2024-07-04T11:09:18.283Z] 11:09:18     INFO -     checkStatsIceConnectionType@dom/media/webrtc/tests/mochitests/pc.js:2189:10
[task 2024-07-04T11:09:18.284Z] 11:09:18     INFO -     PC_LOCAL_CHECK_ICE_CONNECTION_TYPE/<@dom/media/webrtc/tests/mochitests/templates.js:477:20
[task 2024-07-04T11:09:18.284Z] 11:09:18     INFO -     promise callback*PC_LOCAL_CHECK_ICE_CONNECTION_TYPE@dom/media/webrtc/tests/mochitests/templates.js:476:36
[task 2024-07-04T11:09:18.284Z] 11:09:18     INFO -     execute/</<@dom/media/webrtc/tests/mochitests/head.js:1063:32
[task 2024-07-04T11:09:18.284Z] 11:09:18     INFO -     promise callback*execute/<@dom/media/webrtc/tests/mochitests/head.js:1061:21
[task 2024-07-04T11:09:18.284Z] 11:09:18     INFO -     execute@dom/media/webrtc/tests/mochitests/head.js:1056:8
[task 2024-07-04T11:09:18.284Z] 11:09:18     INFO -     PeerConnectionTest.prototype.run@dom/media/webrtc/tests/mochitests/pc.js:553:22
[task 2024-07-04T11:09:18.285Z] 11:09:18     INFO -     @dom/media/webrtc/tests/mochitests/test_peerConnection_basicAudioNATRelayTLS.html:37:16
[task 2024-07-04T11:09:18.285Z] 11:09:18     INFO -     async*runNetworkTest/<@dom/media/webrtc/tests/mochitests/pc.js:2488:11
[task 2024-07-04T11:09:18.285Z] 11:09:18     INFO -     async*runTestWhenReady@dom/media/webrtc/tests/mochitests/head.js:476:11
[task 2024-07-04T11:09:18.285Z] 11:09:18     INFO -     async*runNetworkTest@dom/media/webrtc/tests/mochitests/pc.js:2485:9
[task 2024-07-04T11:09:18.285Z] 11:09:18     INFO -     async*@dom/media/webrtc/tests/mochitests/test_peerConnection_basicAudioNATRelayTLS.html:20:17
[task 2024-07-04T11:09:18.285Z] 11:09:18     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-07-04T11:09:18.288Z] 11:09:18     INFO - TEST-UNEXPECTED-FAIL | dom/media/webrtc/tests/mochitests/test_peerConnection_basicAudioNATRelayTLS.html | failed to find candidatepair IDs or stats for local: undefined remote: undefined 
[task 2024-07-04T11:09:18.288Z] 11:09:18     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:426:16
[task 2024-07-04T11:09:18.288Z] 11:09:18     INFO -     checkStatsIceConnectionType@dom/media/webrtc/tests/mochitests/pc.js:2197:9
[task 2024-07-04T11:09:18.288Z] 11:09:18     INFO -     PC_LOCAL_CHECK_ICE_CONNECTION_TYPE/<@dom/media/webrtc/tests/mochitests/templates.js:477:20
[task 2024-07-04T11:09:18.288Z] 11:09:18     INFO -     promise callback*PC_LOCAL_CHECK_ICE_CONNECTION_TYPE@dom/media/webrtc/tests/mochitests/templates.js:476:36
[task 2024-07-04T11:09:18.288Z] 11:09:18     INFO -     execute/</<@dom/media/webrtc/tests/mochitests/head.js:1063:32
[task 2024-07-04T11:09:18.288Z] 11:09:18     INFO -     promise callback*execute/<@dom/media/webrtc/tests/mochitests/head.js:1061:21
[task 2024-07-04T11:09:18.288Z] 11:09:18     INFO -     execute@dom/media/webrtc/tests/mochitests/head.js:1056:8
[task 2024-07-04T11:09:18.288Z] 11:09:18     INFO -     PeerConnectionTest.prototype.run@dom/media/webrtc/tests/mochitests/pc.js:553:22
[task 2024-07-04T11:09:18.288Z] 11:09:18     INFO -     @dom/media/webrtc/tests/mochitests/test_peerConnection_basicAudioNATRelayTLS.html:37:16
[task 2024-07-04T11:09:18.288Z] 11:09:18     INFO -     async*runNetworkTest/<@dom/media/webrtc/tests/mochitests/pc.js:2488:11
[task 2024-07-04T11:09:18.288Z] 11:09:18     INFO -     async*runTestWhenReady@dom/media/webrtc/tests/mochitests/head.js:476:11
[task 2024-07-04T11:09:18.288Z] 11:09:18     INFO -     async*runNetworkTest@dom/media/webrtc/tests/mochitests/pc.js:2485:9
[task 2024-07-04T11:09:18.289Z] 11:09:18     INFO -     async*@dom/media/webrtc/tests/mochitests/test_peerConnection_basicAudioNATRelayTLS.html:20:17
[task 2024-07-04T11:09:18.289Z] 11:09:18     INFO - Run step 40: PC_REMOTE_CHECK_ICE_CONNECTION_TYPE
[task 2024-07-04T11:09:18.299Z] 11:09:18     INFO - PeerConnectionWrapper (pcRemote): Got stats: {"12cd0a18":{"id":"12cd0a18","timestamp":1720091357688,"type":"inbound-rtp","codecId":"6e5ca18b","kind":"audio","mediaType":"audio","ssrc":3057662147,"jitter":0.004,"packetsDiscarded":0,"packetsLost":0,"packetsReceived":39,"audioLevel":0.1000091555528428,"bytesReceived":6439,"concealedSamples":12432,"concealmentEvents":2,"fecPacketsDiscarded":0,"fecPacketsReceived":0,"headerBytesReceived":780,"insertedSamplesForDeceleration":3360,"jitterBufferDelay":2169.6,"jitterBufferEmittedCount":37440,"lastPacketReceivedTimestamp":1720091357675,"remoteId":"e8b924e6","removedSamplesForAcceleration":1968,"silentConcealedSamples":9000,"totalAudioEnergy":0.007459040217288432,"totalSamplesDuration":1.0500000000000007,"totalSamplesReceived":50400,"trackIdentifier":"{3dc3399c-3d99-429f-af75-824520b0f810}"},"a6f220d":{"id":"a6f220d","timestamp":1720091357688,"type":"outbound-rtp","codecId":"6e5ca18b","kind":"audio","mediaType":"audio","ssrc":3407066238,"bytesSent":7083,"packetsSent":43,"headerBytesSent":860,"nackCount":0,"retransmittedBytesSent":0,"retransmittedPacketsSent":0},"e8b924e6":{"id":"e8b924e6","timestamp":1720091356886,"type":"remote-outbound-rtp","codecId":"6e5ca18b","kind":"audio","mediaType":"audio","ssrc":3057662147,"bytesSent":0,"packetsSent":0,"localId":"12cd0a18","remoteTimestamp":1720091356884},"mediasource_audio_0{2c7bcd89-bb17-49a5-84c3-6663c7d7c16c}":{"id":"mediasource_audio_0{2c7bcd89-bb17-49a5-84c3-6663c7d7c16c}","timestamp":1720091357688,"type":"media-source","kind":"audio","trackIdentifier":"{2c7bcd89-bb17-49a5-84c3-6663c7d7c16c}"},"{188048b2-538a-49e8-8070-545eb8b7c8b9}":{"id":"{188048b2-538a-49e8-8070-545eb8b7c8b9}","timestamp":1720091357688,"type":"peer-connection","dataChannelsClosed":0,"dataChannelsOpened":0},"58efcc06":{"id":"58efcc06","timestamp":1720091357688,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"3097fe91","nominated":false,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"9b793e72","selected":false,"state":"inprogress","transportId":"transport_0","writable":true},"4445ad80":{"id":"4445ad80","timestamp":1720091357688,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"dbb7870c","nominated":false,"priority":35782506363879936,"readable":true,"remoteCandidateId":"9b793e72","selected":false,"state":"inprogress","transportId":"transport_0","writable":true},"950d7d9f":{"id":"950d7d9f","timestamp":1720091357688,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"3097fe91","nominated":false,"priority":35782506363879936,"readable":true,"remoteCandidateId":"8174bb3b","selected":false,"state":"inprogress","transportId":"transport_0","writable":true},"38658d77":{"id":"38658d77","timestamp":1720091357688,"type":"candidate-pair","bytesReceived":8817,"bytesSent":9523,"lastPacketReceivedTimestamp":18446744072336284000,"lastPacketSentTimestamp":18446744072336284000,"localCandidateId":"dbb7870c","nominated":true,"priority":35782502136037376,"readable":true,"remoteCandidateId":"8174bb3b","selected":true,"state":"succeeded","transportId":"transport_0","writable":true},"3097fe91":{"id":"3097fe91","timestamp":1720091357688,"type":"local-candidate","address":"10.0.0.159","candidateType":"host","port":62479,"priority":2122252543,"protocol":"udp"},"5b78985c":{"id":"5b78985c","timestamp":1720091357688,"type":"local-candidate","address":"10.0.0.159","candidateType":"host","port":52046,"priority":2105524479,"protocol":"tcp"},"dbb7870c":{"id":"dbb7870c","timestamp":1720091357688,"type":"local-candidate","address":"10.0.0.159","candidateType":"relay","port":62482,"priority":8331263,"protocol":"udp","relayProtocol":"tls"},"9b793e72":{"id":"9b793e72","timestamp":1720091357688,"type":"remote-candidate","address":"10.0.0.159","candidateType":"host","port":62476,"priority":2122252543,"protocol":"udp"},"7e4b5009":{"id":"7e4b5009","timestamp":1720091357688,"type":"remote-candidate","address":"10.0.0.159","candidateType":"host","port":9,"priority":2105524479,"protocol":"tcp"},"8174bb3b":{"id":"8174bb3b","timestamp":1720091357688,"type":"remote-candidate","address":"10.0.0.159","candidateType":"relay","port":62480,"priority":8331263,"protocol":"udp"},"6e5ca18b":{"id":"6e5ca18b","timestamp":1720091357688,"type":"codec","channels":2,"clockRate":48000,"mimeType":"audio/opus","payloadType":109,"sdpFmtpLine":"maxplaybackrate=48000;stereo=1;useinbandfec=1","transportId":"transport_0"}}
[task 2024-07-04T11:09:18.300Z] 11:09:18     INFO - GECKO(1644) | (generic/INFO) TestNrSocket IP4:10.0.0.159:0/TLS port mapping IP4:10.0.0.159:0/TLS -> IP4:0.0.0.0:5349/TLS last used 19
[task 2024-07-04T11:09:18.300Z] 11:09:18     INFO - TEST-PASS | dom/media/webrtc/tests/mochitests/test_peerConnection_basicAudioNATRelayTLS.html | Got local candidate ID dbb7870c for selected pair 

Comment 1

[Intermittent Failures Robot]

1 failures in 3199 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-32-2009-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1906287&startday=2024-07-01&endday=2024-07-07&tree=all

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 3

[Treeherder Bug Filer]

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

Comment 4

[Intermittent Failures Robot]

1 failures in 4860 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1906287&startday=2024-09-16&endday=2024-09-22&tree=all

Comment 5

[Intermittent Failures Robot]

6 failures in 3574 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1
• mozilla-esr128: 3
• mozilla-esr115: 2

Platform and build breakdown:

• macosx1015-64-qr: 1
  • opt: 1
• windows11-64-2009-shippable-qr: 2
  • opt: 2
• windows11-32-2009-shippable-qr: 2
  • opt: 2
• macosx1015-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1906287&startday=2024-09-30&endday=2024-10-06&tree=all

Comment 6

[BugBot [:suhaib / :marco/ :calixte]]

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