Closed Bug 1654327 Opened 5 years ago Closed 5 years ago

Intermittent dom/media/tests/mochitest/test_peerConnection_basicAudioNATRelay.html | Got local candidate ID undefined for selected pair - didn't expect undefined, but got it

Categories

(Core :: WebRTC, defect, P5)

Product:
Core
Component:
WebRTC
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2020-07-21T15:52:16.517Z] 15:52:16 INFO - TEST-START | dom/media/tests/mochitest/test_peerConnection_basicAudioNATRelay.html
[task 2020-07-21T15:52:20.187Z] 15:52:20 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_basicAudioNATRelay.html | Local candidate type is what we expected for selected pair
[task 2020-07-21T15:52:20.188Z] 15:52:20 INFO - Run step 40: PC_REMOTE_CHECK_ICE_CONNECTION_TYPE
[task 2020-07-21T15:52:20.188Z] 15:52:20 INFO - PeerConnectionWrapper (pcRemote): Got stats: {"41913774":{"id":"41913774","timestamp":1595346740058,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"ba85dfb6","nominated":false,"priority":396068277530264060,"readable":true,"remoteCandidateId":"2dd4fc8b","selected":false,"state":"inprogress","transportId":"transport_0","writable":true},"b020dde4":{"id":"b020dde4","timestamp":1595346740058,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"6733aeb9","nominated":false,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"2dd4fc8b","selected":false,"state":"inprogress","transportId":"transport_0","writable":true},"6733aeb9":{"id":"6733aeb9","timestamp":1595346740058,"type":"local-candidate","address":"10.143.27.118","candidateType":"host","port":61546,"priority":2122252543,"protocol":"udp"},"f118c1e2":{"id":"f118c1e2","timestamp":1595346740058,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"1a639da6","nominated":false,"priority":35782506363879936,"readable":true,"remoteCandidateId":"2dd4fc8b","selected":false,"state":"inprogress","transportId":"transport_0","writable":true},"b75df4ba":{"id":"b75df4ba","timestamp":1595346740058,"type":"local-candidate","address":"10.143.27.118","candidateType":"srflx","port":61548,"priority":1686052863,"protocol":"udp"},"ba85dfb6":{"id":"ba85dfb6","timestamp":1595346740058,"type":"local-candidate","address":"10.143.27.118","candidateType":"relay","port":61549,"priority":92216831,"protocol":"udp","relayProtocol":"udp"},"334da4a9":{"id":"334da4a9","timestamp":1595346740058,"type":"local-candidate","address":"10.143.27.118","candidateType":"host","port":64850,"priority":2105524479,"protocol":"tcp"},"1a639da6":{"id":"1a639da6","timestamp":1595346740058,"type":"local-candidate","address":"10.143.27.118","candidateType":"relay","port":61550,"priority":8331263,"protocol":"udp","relayProtocol":"tcp"},"2dd4fc8b":{"id":"2dd4fc8b","timestamp":1595346740058,"type":"remote-candidate","address":"10.143.27.118","candidateType":"host","port":61543,"priority":2122252543,"protocol":"udp"},"b23d1c69":{"id":"b23d1c69","timestamp":1595346740058,"type":"remote-candidate","address":"10.143.27.118","candidateType":"host","port":9,"priority":2105524479,"protocol":"tcp"},"7db9b6da":{"id":"7db9b6da","timestamp":1595346740058,"type":"remote-candidate","address":"10.143.27.118","candidateType":"prflx","port":61553,"priority":1853817087,"protocol":"udp"},"4fa29448":{"id":"4fa29448","timestamp":1595346740059,"type":"inbound-rtp","kind":"audio","mediaType":"audio","ssrc":2858915898,"jitter":0,"packetsLost":0,"packetsReceived":41,"bytesReceived":7581,"nackCount":0},"5b3a81cb":{"id":"5b3a81cb","timestamp":1595346740058,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":3225136118,"bytesSent":7581,"packetsSent":41,"nackCount":0}}
[task 2020-07-21T15:52:20.188Z] 15:52:20 INFO - Buffered messages finished
[task 2020-07-21T15:52:20.189Z] 15:52:20 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_basicAudioNATRelay.html | Got local candidate ID undefined for selected pair - didn't expect undefined, but got it
[task 2020-07-21T15:52:20.189Z] 15:52:20 INFO - SimpleTest.isnot@SimpleTest/SimpleTest.js:514:14
[task 2020-07-21T15:52:20.189Z] 15:52:20 INFO - checkStatsIceConnectionType@dom/media/tests/mochitest/pc.js:2231:10
[task 2020-07-21T15:52:20.189Z] 15:52:20 INFO - PC_REMOTE_CHECK_ICE_CONNECTION_TYPE/<@dom/media/tests/mochitest/templates.js:491:21
[task 2020-07-21T15:52:20.190Z] 15:52:20 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-07-21T15:52:20.191Z] 15:52:20 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_basicAudioNATRelay.html | Got remote candidate ID undefined for selected pair - didn't expect undefined, but got it
[task 2020-07-21T15:52:20.191Z] 15:52:20 INFO - SimpleTest.isnot@SimpleTest/SimpleTest.js:514:14
[task 2020-07-21T15:52:20.191Z] 15:52:20 INFO - checkStatsIceConnectionType@dom/media/tests/mochitest/pc.js:2236:10
[task 2020-07-21T15:52:20.191Z] 15:52:20 INFO - PC_REMOTE_CHECK_ICE_CONNECTION_TYPE/<@dom/media/tests/mochitest/templates.js:491:21
[task 2020-07-21T15:52:20.192Z] 15:52:20 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-07-21T15:52:20.192Z] 15:52:20 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_basicAudioNATRelay.html | failed to find candidatepair IDs or stats for local: undefined remote: undefined
[task 2020-07-21T15:52:20.192Z] 15:52:20 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:412:16
[task 2020-07-21T15:52:20.192Z] 15:52:20 INFO - checkStatsIceConnectionType@dom/media/tests/mochitest/pc.js:2244:9
[task 2020-07-21T15:52:20.192Z] 15:52:20 INFO - PC_REMOTE_CHECK_ICE_CONNECTION_TYPE/<@dom/media/tests/mochitest/templates.js:491:21
[task 2020-07-21T15:52:20.192Z] 15:52:20 INFO - Run step 41: PC_LOCAL_CHECK_ICE_CONNECTIONS
[task 2020-07-21T15:52:20.193Z] 15:52:20 INFO - PeerConnectionWrapper (pcLocal): Got stats: {"782b0f3f":{"id":"782b0f3f","timestamp":1595346740065,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"b078cee","nominated":false,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"6bf69125","selected":false,"state":"inprogress","transportId":"transport_0","writable":true},"beb83c41":{"id":"beb83c41","timestamp":1595346740065,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"b078cee","nominated":false,"priority":7241541910156673000,"readable":true,"remoteCandidateId":"e22169a","selected":false,"state":"inprogress","transportId":"transport_0","writable":true},"3d1be2ca":{"id":"3d1be2ca","timestamp":1595346740065,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"b078cee","nominated":false,"priority":396068277530264060,"readable":true,"remoteCandidateId":"c9b03de9","selected":false,"state":"failed","transportId":"transport_0","writable":true},"5f9ece4e":{"id":"5f9ece4e","timestamp":1595346740065,"type":"candidate-pair","bytesReceived":9093,"bytesSent":9147,"lastPacketReceivedTimestamp":354918452049,"lastPacketSentTimestamp":354918452047,"localCandidateId":"fff26242","nominated":true,"priority":396068276993393150,"readable":true,"remoteCandidateId":"c9b03de9","selected":true,"state":"succeeded","transportId":"transport_0","writable":true},"6e1a2253":{"id":"6e1a2253","timestamp":1595346740065,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"b078cee","nominated":false,"priority":35782506363879936,"readable":true,"remoteCandidateId":"75825a9e","selected":false,"state":"cancelled","transportId":"transport_0","writable":true},"b078cee":{"id":"b078cee","timestamp":1595346740065,"type":"local-candidate","address":"10.143.27.118","candidateType":"host","port":61543,"priority":2122252543,"protocol":"udp"},"6a083bc5":{"id":"6a083bc5","timestamp":1595346740065,"type":"local-candidate","address":"10.143.27.118","candidateType":"host","port":63534,"priority":2105524479,"protocol":"tcp"},"fff26242":{"id":"fff26242","timestamp":1595346740065,"type":"local-candidate","address":"10.143.27.118","candidateType":"prflx","port":61553,"priority":1853817087,"protocol":"udp"},"6bf69125":{"id":"6bf69125","timestamp":1595346740065,"type":"remote-candidate","address":"10.143.27.118","candidateType":"host","port":61546,"priority":2122252543,"protocol":"udp"},"98aa517a":{"id":"98aa517a","timestamp":1595346740065,"type":"remote-candidate","address":"10.143.27.118","candidateType":"host","port":9,"priority":2105524479,"protocol":"tcp"},"e22169a":{"id":"e22169a","timestamp":1595346740065,"type":"remote-candidate","address":"10.143.27.118","candidateType":"srflx","port":61548,"priority":1686052863,"protocol":"udp"},"c9b03de9":{"id":"c9b03de9","timestamp":1595346740065,"type":"remote-candidate","address":"10.143.27.118","candidateType":"relay","port":61549,"priority":92216831,"protocol":"udp"},"75825a9e":{"id":"75825a9e","timestamp":1595346740065,"type":"remote-candidate","address":"10.143.27.118","candidateType":"relay","port":61550,"priority":8331263,"protocol":"udp"},"c142dfc7":{"id":"c142dfc7","timestamp":1595346740065,"type":"inbound-rtp","kind":"audio","mediaType":"audio","ssrc":3225136118,"jitter":0,"packetsLost":0,"packetsReceived":41,"bytesReceived":7581,"nackCount":0},"ae2fe12e":{"id":"ae2fe12e","timestamp":1595346740065,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":2858915898,"bytesSent":7581,"packetsSent":41,"nackCount":0}}
[task 2020-07-21T15:52:20.193Z] 15:52:20 INFO - ICE connections according to stats: 1
[task 2020-07-21T15:52:20.194Z] 15:52:20 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_basicAudioNATRelay.html | Number of ICE connections according to stats is not zero
[task 2020-07-21T15:52:20.194Z] 15:52:20 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_basicAudioNATRelay.html | stats reports exactly 1 ICE connection
[task 2020-07-21T15:52:20.194Z] 15:52:20 INFO - Run step 42: PC_REMOTE_CHECK_ICE_CONNECTIONS
[task 2020-07-21T15:52:20.195Z] 15:52:20 INFO - PeerConnectionWrapper (pcRemote): Got stats: {"41913774":{"id":"41913774","timestamp":1595346740070,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"ba85dfb6","nominated":false,"priority":396068277530264060,"readable":true,"remoteCandidateId":"2dd4fc8b","selected":false,"state":"inprogress","transportId":"transport_0","writable":true},"b020dde4":{"id":"b020dde4","timestamp":1595346740070,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"6733aeb9","nominated":false,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"2dd4fc8b","selected":false,"state":"inprogress","transportId":"transport_0","writable":true},"6733aeb9":{"id":"6733aeb9","timestamp":1595346740070,"type":"local-candidate","address":"10.143.27.118","candidateType":"host","port":61546,"priority":2122252543,"protocol":"udp"},"f118c1e2":{"id":"f118c1e2","timestamp":1595346740070,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"1a639da6","nominated":false,"priority":35782506363879936,"readable":true,"remoteCandidateId":"2dd4fc8b","selected":false,"state":"inprogress","transportId":"transport_0","writable":true},"b75df4ba":{"id":"b75df4ba","timestamp":1595346740070,"type":"local-candidate","address":"10.143.27.118","candidateType":"srflx","port":61548,"priority":1686052863,"protocol":"udp"},"ba85dfb6":{"id":"ba85dfb6","timestamp":1595346740070,"type":"local-candidate","address":"10.143.27.118","candidateType":"relay","port":61549,"priority":92216831,"protocol":"udp","relayProtocol":"udp"},"334da4a9":{"id":"334da4a9","timestamp":1595346740070,"type":"local-candidate","address":"10.143.27.118","candidateType":"host","port":64850,"priority":2105524479,"protocol":"tcp"},"1a639da6":{"id":"1a639da6","timestamp":1595346740070,"type":"local-candidate","address":"10.143.27.118","candidateType":"relay","port":61550,"priority":8331263,"protocol":"udp","relayProtocol":"tcp"},"2dd4fc8b":{"id":"2dd4fc8b","timestamp":1595346740070,"type":"remote-candidate","address":"10.143.27.118","candidateType":"host","port":61543,"priority":2122252543,"protocol":"udp"},"b23d1c69":{"id":"b23d1c69","timestamp":1595346740070,"type":"remote-candidate","address":"10.143.27.118","candidateType":"host","port":9,"priority":2105524479,"protocol":"tcp"},"7db9b6da":{"id":"7db9b6da","timestamp":1595346740070,"type":"remote-candidate","address":"10.143.27.118","candidateType":"prflx","port":61553,"priority":1853817087,"protocol":"udp"},"4fa29448":{"id":"4fa29448","timestamp":1595346740070,"type":"inbound-rtp","kind":"audio","mediaType":"audio","ssrc":2858915898,"jitter":0,"packetsLost":0,"packetsReceived":42,"bytesReceived":7762,"nackCount":0},"5b3a81cb":{"id":"5b3a81cb","timestamp":1595346740070,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":3225136118,"bytesSent":7762,"packetsSent":42,"nackCount":0}}
[task 2020-07-21T15:52:20.196Z] 15:52:20 INFO - ICE connections according to stats: 0
[task 2020-07-21T15:52:20.196Z] 15:52:20 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-07-21T15:52:20.197Z] 15:52:20 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_basicAudioNATRelay.html | Number of ICE connections according to stats is not zero - didn't expect +0, but got it
[task 2020-07-21T15:52:20.197Z] 15:52:20 INFO - SimpleTest.isnot@SimpleTest/SimpleTest.js:514:14
[task 2020-07-21T15:52:20.197Z] 15:52:20 INFO - checkStatsIceConnections@dom/media/tests/mochitest/pc.js:2297:10
[task 2020-07-21T15:52:20.198Z] 15:52:20 INFO - PC_REMOTE_CHECK_ICE_CONNECTIONS/<@dom/media/tests/mochitest/templates.js:506:21
[task 2020-07-21T15:52:20.198Z] 15:52:20 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-07-21T15:52:20.198Z] 15:52:20 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_basicAudioNATRelay.html | stats reports exactly 1 ICE connection - got +0, expected 1
[task 2020-07-21T15:52:20.199Z] 15:52:20 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:495:14
[task 2020-07-21T15:52:20.199Z] 15:52:20 INFO - checkStatsIceConnections@dom/media/tests/mochitest/pc.js:2304:11
[task 2020-07-21T15:52:20.199Z] 15:52:20 INFO - PC_REMOTE_CHECK_ICE_CONNECTIONS/<@dom/media/tests/mochitest/templates.js:506:21

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

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