Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=242097498&repo=autoland&lineNumber=3021
16:58:25 INFO - 151 INFO TEST-START | dom/media/tests/mochitest/test_peerConnection_basicVideo.html
16:58:25 INFO - 152 INFO TEST-OK | dom/media/tests/mochitest/test_peerConnection_basicVideo.html | took 2980ms
16:58:25 INFO - 153 INFO TEST-START | dom/media/tests/mochitest/test_peerConnection_basicVideoVerifyRtpHeaderExtensions.html
16:58:25 INFO - <snipped 258 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
16:58:25 INFO - Buffered messages logged at 16:58:20
16:58:25 INFO - 154 INFO PeerConnectionWrapper (pcRemote): onicegatheringstatechange fired, new state is: complete
16:58:25 INFO - 155 INFO pcRemote: iceCandidate = {"candidate":"candidate:0 1 UDP 2122252543 10.7.205.35 49770 typ host","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"2444d52f"}
16:58:25 INFO - 156 INFO TEST-PASS | dom/media/tests/mochitest/test_peerConnection_basicVideoVerifyRtpHeaderExtensions.html | SDP mid not empty
16:58:25 INFO - 157 INFO TEST-PASS | dom/media/tests/mochitest/test_peerConnection_basicVideoVerifyRtpHeaderExtensions.html | usernameFragment not empty
16:58:25 INFO - 236 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_basicVideoVerifyRtpHeaderExtensions.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
16:58:25 INFO - Buffered messages logged at 16:58:21
16:58:25 INFO - 237 INFO Checking for stats in [["inbound_rtp_video_0",{"id":"inbound_rtp_video_0","timestamp":1556038701203,"type":"inbound-rtp","kind":"video","mediaType":"video","ssrc":0,"discardedPackets":0,"jitter":0,"packetsLost":0,"packetsReceived":0,"bitrateMean":-1.4064692027288192e+82,"bitrateStdDev":-2.9914453477557844e+217,"bytesReceived":0,"firCount":0,"framerateMean":0,"framerateStdDev":2.121995791e-314,"framesDecoded":0,"nackCount":0,"pliCount":0,"remoteId":"inbound_rtcp_video_0"}],["inbound_rtcp_video_0",{"id":"inbound_rtcp_video_0","timestamp":1556038701203,"type":"remote-outbound-rtp","kind":"video","mediaType":"video","ssrc":0,"bytesSent":0,"packetsSent":0,"localId":"inbound_rtp_video_0"}],["UJbC",{"id":"UJbC","timestamp":1556038701203,"type":"candidate-pair","bytesReceived":833,"bytesSent":8186,"componentId":1,"lastPacketReceivedTimestamp":1260540005,"lastPacketSentTimestamp":1260539984,"localCandidateId":"Fn2P","nominated":true,"priority":7962083765675491000,"readable":true,"remoteCandidateId":"8kCu","selected":true,"state":"succeeded","transportId":"transport_0","writable":true}],["Fn2P",{"id":"Fn2P","timestamp":1556038701203,"type":"local-candidate","address":"10.7.205.35","candidateType":"host","port":40482,"priority":2122252543,"protocol":"udp"}],["0BHX",{"id":"0BHX","timestamp":1556038701203,"type":"local-candidate","address":"10.7.205.35","candidateType":"host","port":50819,"priority":2105508095,"protocol":"tcp"}],["2ekU",{"id":"2ekU","timestamp":1556038701203,"type":"local-candidate","address":"10.7.205.35","candidateType":"host","port":54208,"priority":2105524479,"protocol":"tcp"}],["8kCu",{"id":"8kCu","timestamp":1556038701203,"type":"remote-candidate","address":"10.7.205.35","candidateType":"prflx","port":49770,"priority":1853817087,"protocol":"udp"}]] for video track {b26d5f51-8e21-46ca-80d8-7aea905b7763}retry number 1
16:58:25 INFO - 238 INFO Should have RTP stats for track {b26d5f51-8e21-46ca-80d8-7aea905b7763}
16:58:25 INFO - 239 INFO RTP stats: {"id":"inbound_rtp_video_0","timestamp":1556038701203,"type":"inbound-rtp","kind":"video","mediaType":"video","ssrc":0,"discardedPackets":0,"jitter":0,"packetsLost":0,"packetsReceived":0,"bitrateMean":-1.4064692027288192e+82,"bitrateStdDev":-2.9914453477557844e+217,"bytesReceived":0,"firCount":0,"framerateMean":0,"framerateStdDev":2.121995791e-314,"framesDecoded":0,"nackCount":0,"pliCount":0,"remoteId":"inbound_rtcp_video_0"}
16:58:25 INFO - 240 INFO Track {b26d5f51-8e21-46ca-80d8-7aea905b7763} has 0 inbound-rtp RTP packets.
16:58:25 INFO - 241 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_basicVideoVerifyRtpHeaderExtensions.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
16:58:25 INFO - 242 INFO Checking for stats in [["outbound_rtp_video_0",{"id":"outbound_rtp_video_0","timestamp":1556038701221,"type":"outbound-rtp","kind":"video","mediaType":"video","ssrc":1668214667,"bytesSent":6854,"packetsSent":27,"bitrateMean":-1.4783618996577789e-86,"bitrateStdDev":4.0872021456e-314,"droppedFrames":0,"firCount":0,"framerateMean":4.0872021456e-314,"framerateStdDev":-1.3169942692898314e+161,"framesEncoded":0,"nackCount":0,"pliCount":0,"remoteId":""}],["UJbC",{"id":"UJbC","timestamp":1556038701221,"type":"candidate-pair","bytesReceived":833,"bytesSent":8186,"componentId":1,"lastPacketReceivedTimestamp":1260540005,"lastPacketSentTimestamp":1260539984,"localCandidateId":"Fn2P","nominated":true,"priority":7962083765675491000,"readable":true,"remoteCandidateId":"8kCu","selected":true,"state":"succeeded","transportId":"transport_0","writable":true}],["Fn2P",{"id":"Fn2P","timestamp":1556038701221,"type":"local-candidate","address":"10.7.205.35","candidateType":"host","port":40482,"priority":2122252543,"protocol":"udp"}],["0BHX",{"id":"0BHX","timestamp":1556038701221,"type":"local-candidate","address":"10.7.205.35","candidateType":"host","port":50819,"priority":2105508095,"protocol":"tcp"}],["2ekU",{"id":"2ekU","timestamp":1556038701221,"type":"local-candidate","address":"10.7.205.35","candidateType":"host","port":54208,"priority":2105524479,"protocol":"tcp"}],["8kCu",{"id":"8kCu","timestamp":1556038701221,"type":"remote-candidate","address":"10.7.205.35","candidateType":"prflx","port":49770,"priority":1853817087,"protocol":"udp"}]] for video track {d6020c9f-9650-400a-bcf3-fb6acd884540}retry number 1
16:58:25 INFO - 243 INFO Should have RTP stats for track {d6020c9f-9650-400a-bcf3-fb6acd884540}
16:58:25 INFO - 244 INFO RTP stats: {"id":"outbound_rtp_video_0","timestamp":1556038701221,"type":"outbound-rtp","kind":"video","mediaType":"video","ssrc":1668214667,"bytesSent":6854,"packetsSent":27,"bitrateMean":-1.4783618996577789e-86,"bitrateStdDev":4.0872021456e-314,"droppedFrames":0,"firCount":0,"framerateMean":4.0872021456e-314,"framerateStdDev":-1.3169942692898314e+161,"framesEncoded":0,"nackCount":0,"pliCount":0,"remoteId":""}
16:58:25 INFO - 245 INFO Track {d6020c9f-9650-400a-bcf3-fb6acd884540} has 27 outbound-rtp RTP packets.
16:58:25 INFO - 246 INFO TEST-PASS | dom/media/tests/mochitest/test_peerConnection_basicVideoVerifyRtpHeaderExtensions.html | RTP flowing for video track {d6020c9f-9650-400a-bcf3-fb6acd884540}
16:58:25 INFO - 247 INFO Element remote{b26d5f51-8e21-46ca-80d8-7aea905b7763} has enough data.
16:58:25 INFO - 248 INFO Checking for stats in [["inbound_rtp_video_0",{"id":"inbound_rtp_video_0","timestamp":1556038701740,"type":"inbound-rtp","kind":"video","mediaType":"video","ssrc":1284178038,"discardedPackets":0,"jitter":0,"packetsLost":0,"packetsReceived":28,"bitrateMean":318011,"bitrateStdDev":0,"bytesReceived":7251,"firCount":0,"framerateMean":0,"framerateStdDev":0,"framesDecoded":1,"nackCount":0,"pliCount":0,"remoteId":"inbound_rtcp_video_0"}],["inbound_rtcp_video_0",{"id":"inbound_rtcp_video_0","timestamp":1556038701740,"type":"remote-outbound-rtp","kind":"video","mediaType":"video","ssrc":1284178038,"bytesSent":0,"packetsSent":0,"localId":"inbound_rtp_video_0"}],["UJbC",{"id":"UJbC","timestamp":1556038701740,"type":"candidate-pair","bytesReceived":8780,"bytesSent":16777,"componentId":1,"lastPacketReceivedTimestamp":1260540441,"lastPacketSentTimestamp":1260540289,"localCandidateId":"Fn2P","nominated":true,"priority":7962083765675491000,"readable":true,"remoteCandidateId":"8kCu","selected":true,"state":"succeeded","transportId":"transport_0","writable":true}],["Fn2P",{"id":"Fn2P","timestamp":1556038701740,"type":"local-candidate","address":"10.7.205.35","candidateType":"host","port":40482,"priority":2122252543,"protocol":"udp"}],["0BHX",{"id":"0BHX","timestamp":1556038701740,"type":"local-candidate","address":"10.7.205.35","candidateType":"host","port":50819,"priority":2105508095,"protocol":"tcp"}],["2ekU",{"id":"2ekU","timestamp":1556038701740,"type":"local-candidate","address":"10.7.205.35","candidateType":"host","port":54208,"priority":2105524479,"protocol":"tcp"}],["8kCu",{"id":"8kCu","timestamp":1556038701740,"type":"remote-candidate","address":"10.7.205.35","candidateType":"prflx","port":49770,"priority":1853817087,"protocol":"udp"}]] for video track {b26d5f51-8e21-46ca-80d8-7aea905b7763}retry number 2
16:58:25 INFO - 249 INFO Should have RTP stats for track {b26d5f51-8e21-46ca-80d8-7aea905b7763}
16:58:25 INFO - 250 INFO RTP stats: {"id":"inbound_rtp_video_0","timestamp":1556038701740,"type":"inbound-rtp","kind":"video","mediaType":"video","ssrc":1284178038,"discardedPackets":0,"jitter":0,"packetsLost":0,"packetsReceived":28,"bitrateMean":318011,"bitrateStdDev":0,"bytesReceived":7251,"firCount":0,"framerateMean":0,"framerateStdDev":0,"framesDecoded":1,"nackCount":0,"pliCount":0,"remoteId":"inbound_rtcp_video_0"}
16:58:25 INFO - 251 INFO Track {b26d5f51-8e21-46ca-80d8-7aea905b7763} has 28 inbound-rtp RTP packets.
16:58:25 INFO - 252 INFO TEST-PASS | dom/media/tests/mochitest/test_peerConnection_basicVideoVerifyRtpHeaderExtensions.html | RTP flowing for video track {b26d5f51-8e21-46ca-80d8-7aea905b7763}
16:58:25 INFO - 253 INFO Run step 40: PC_REMOTE_CHECK_RTP_HEADER_EXTS_AGAINST_SDP
16:58:25 INFO - Buffered messages finished
16:58:25 INFO - 254 INFO TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_basicVideoVerifyRtpHeaderExtensions.html | number of received ids match sdp ids - got 3, expected 2
16:58:25 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:320:16
16:58:25 INFO - PC_REMOTE_CHECK_RTP_HEADER_EXTS_AGAINST_SDP@dom/media/tests/mochitest/test_peerConnection_basicVideoVerifyRtpHeaderExtensions.html:47:9
16:58:25 INFO - 255 INFO TEST-PASS | dom/media/tests/mochitest/test_peerConnection_basicVideoVerifyRtpHeaderExtensions.html | extension id arrays equivalent
16:58:25 INFO - 256 INFO Run step 41: PC_REMOTE_WAIT_FOR_MEDIA_FLOW
16:58:25 INFO - 257 INFO Checking data flow for element: local{84ed6f2a-60da-4ebd-a280-806e04aa1cc8}
16:58:25 INFO - 258 INFO TEST-PASS | dom/media/tests/mochitest/test_peerConnection_basicVideoVerifyRtpHeaderExtensions.html | Element ended should be the inverse of the MediaStream's active state
Description
•