1484228 - Intermittent dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Error in test execution: Error: Timeout checking for stats for track {a5ad03eb-1cc4-4fe3-a835-cb3ea34d867c} after at least30000ms waitForRtpFlow

Status: RESOLVED INCOMPLETE

(Core :: WebRTC, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ebalazs [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=194508859&repo=mozilla-central

https://queue.taskcluster.net/v1/task/QKQHZMhVSEidiCyH3Bz6KA/runs/0/artifacts/public/logs/live_backing.log

 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts 
11:23:29     INFO - Checking for stats in {"outbound_rtcp_video_0":{"id":"outbound_rtcp_video_0","timestamp":1577091,"type":"inboundrtp","isRemote":true,"mediaType":"video","remoteId":"outbound_rtp_video_0","ssrc":3265358468,"bytesReceived":0,"jitter":0,"packetsLost":0,"packetsReceived":0},"outbound_rtp_video_0":{"id":"outbound_rtp_video_0","timestamp":1534505008651,"type":"outboundrtp","bitrateMean":0,"bitrateStdDev":0,"firCount":0,"framerateMean":0,"framerateStdDev":0,"isRemote":false,"mediaType":"video","nackCount":0,"pliCount":0,"remoteId":"outbound_rtcp_video_0","ssrc":3265358468,"bytesSent":0,"droppedFrames":1,"framesEncoded":0,"packetsSent":0},"fXGp":{"id":"fXGp","timestamp":1534505008651,"type":"candidatepair","bytesReceived":1794,"bytesSent":1811,"componentId":1,"lastPacketReceivedTimestamp":294076690355,"lastPacketSentTimestamp":294076690353,"localCandidateId":"nY9W","nominated":true,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"Vu80","selected":true,"state":"succeeded","transportId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","writable":true},"Mzzr":{"id":"Mzzr","timestamp":1534505008651,"type":"candidatepair","bytesReceived":1460,"bytesSent":1500,"componentId":2,"lastPacketReceivedTimestamp":294076717272,"lastPacketSentTimestamp":294076716110,"localCandidateId":"bhZl","nominated":true,"priority":9115005265987371000,"readable":true,"remoteCandidateId":"Z1ky","selected":true,"state":"succeeded","transportId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","writable":true},"3XhK":{"id":"3XhK","timestamp":1534505008651,"type":"candidatepair","bytesReceived":0,"bytesSent":0,"componentId":1,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"DJEm","nominated":true,"priority":9114723795305497000,"readable":true,"remoteCandidateId":"bfx2","selected":false,"state":"succeeded","transportId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","writable":true},"nY9W":{"id":"nY9W","timestamp":1534505008651,"type":"localcandidate","candidateType":"host","componentId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","ipAddress":"10.147.45.151","mozLocalTransport":"udp","portNumber":64468,"transport":"udp"},"DJEm":{"id":"DJEm","timestamp":1534505008651,"type":"localcandidate","candidateType":"host","componentId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","ipAddress":"2001:0:9d38:78cf:2465:1ee0:fc87:dfed","mozLocalTransport":"udp","portNumber":64467,"transport":"udp"},"w9QL":{"id":"w9QL","timestamp":1534505008651,"type":"localcandidate","candidateType":"host","componentId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","ipAddress":"10.147.45.151","mozLocalTransport":"tcp","portNumber":56607,"transport":"tcp"},"dOa+":{"id":"dOa+","timestamp":1534505008651,"type":"localcandidate","candidateType":"host","componentId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","ipAddress":"2001:0:9d38:78cf:2465:1ee0:fc87:dfed","mozLocalTransport":"tcp","portNumber":55007,"transport":"tcp"},"bhZl":{"id":"bhZl","timestamp":1534505008651,"type":"localcandidate","candidateType":"host","componentId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","ipAddress":"10.147.45.151","mozLocalTransport":"udp","portNumber":64469,"transport":"udp"},"vRDb":{"id":"vRDb","timestamp":1534505008651,"type":"localcandidate","candidateType":"host","componentId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","ipAddress":"10.147.45.151","mozLocalTransport":"tcp","portNumber":54297,"transport":"tcp"},"Qt5c":{"id":"Qt5c","timestamp":1534505008651,"type":"localcandidate","candidateType":"host","componentId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","ipAddress":"2001:0:9d38:78cf:2465:1ee0:fc87:dfed","mozLocalTransport":"tcp","portNumber":57640,"transport":"tcp"},"Vu80":{"id":"Vu80","timestamp":1534505008651,"type":"remotecandidate","candidateType":"host","componentId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","ipAddress":"10.147.45.151","portNumber":64474,"transport":"udp"},"bfx2":{"id":"bfx2","timestamp":1534505008651,"type":"remotecandidate","candidateType":"host","componentId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","ipAddress":"2001:0:9d38:78cf:2465:1ee0:fc87:dfed","portNumber":64473,"transport":"udp"},"+weg":{"id":"+weg","timestamp":1534505008651,"type":"remotecandidate","candidateType":"host","componentId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","ipAddress":"10.147.45.151","portNumber":9,"transport":"tcp"},"I5wx":{"id":"I5wx","timestamp":1534505008651,"type":"remotecandidate","candidateType":"host","componentId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","ipAddress":"2001:0:9d38:78cf:2465:1ee0:fc87:dfed","portNumber":9,"transport":"tcp"},"Z1ky":{"id":"Z1ky","timestamp":1534505008651,"type":"remotecandidate","candidateType":"host","componentId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","ipAddress":"10.147.45.151","portNumber":64475,"transport":"udp"},"ufVm":{"id":"ufVm","timestamp":1534505008651,"type":"remotecandidate","candidateType":"host","componentId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","ipAddress":"10.147.45.151","portNumber":9,"transport":"tcp"},"+maz":{"id":"+maz","timestamp":1534505008651,"type":"remotecandidate","candidateType":"host","componentId":"0-1534504977960000 (id=6442451426 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund aLevel=0","ipAddress":"2001:0:9d38:78cf:2465:1ee0:fc87:dfed","portNumber":9,"transport":"tcp"}} for video track {a5ad03eb-1cc4-4fe3-a835-cb3ea34d867c}, retry number 60
11:23:29     INFO - Should have RTP stats for track {a5ad03eb-1cc4-4fe3-a835-cb3ea34d867c}
11:23:29     INFO - RTP stats: {"id":"outbound_rtp_video_0","timestamp":1534505008651,"type":"outbound-rtp","bitrateMean":0,"bitrateStdDev":0,"firCount":0,"framerateMean":0,"framerateStdDev":0,"isRemote":false,"mediaType":"video","nackCount":0,"pliCount":0,"remoteId":"outbound_rtcp_video_0","ssrc":3265358468,"bytesSent":0,"droppedFrames":1,"framesEncoded":0,"packetsSent":0}
11:23:29     INFO - Track {a5ad03eb-1cc4-4fe3-a835-cb3ea34d867c} has 0 outbound-rtp RTP packets.
11:23:29     INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts 
11:23:29     INFO - Buffered messages finished
11:23:29     INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Error in test execution: Error: Timeout checking for stats for track {a5ad03eb-1cc4-4fe3-a835-cb3ea34d867c} after at least30000ms waitForRtpFlow@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:1555:11 ... async*waitForMediaFlow/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:1608:49 ... waitForMediaFlow@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:1608:36 ... PC_LOCAL_WAIT_FOR_MEDIA_FLOW@http://mochi.test:8888/tests/dom/media/tests/mochitest/templates.js:374:12 ... execute/</<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:855:31 ...  
11:23:29     INFO -     execute/<@dom/media/tests/mochitest/head.js:859:14
11:23:29     INFO -     promise callback*execute@dom/media/tests/mochitest/head.js:858:8
11:23:29     INFO -     PeerConnectionTest.prototype.run@dom/media/tests/mochitest/pc.js:485:10
11:23:29     INFO -     @dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html:38:5
11:23:29     INFO -     runNetworkTest/<@dom/media/tests/mochitest/pc.js:2212:13
11:23:29     INFO -     async*runTestWhenReady/<@dom/media/tests/mochitest/head.js:476:41
11:23:29     INFO -     promise callback*runTestWhenReady@dom/media/tests/mochitest/head.js:476:10
11:23:29     INFO -     runNetworkTest@dom/media/tests/mochitest/pc.js:2194:9
11:23:29     INFO -     async*@dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html:15:3
11:23:29     INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts 
11:23:29     INFO - Closing peer connections
11:23:29     INFO - Waiting for track {eb9f7a19-f7f7-4357-b850-133a5c9090ca} (audio) to end.
11:23:29     INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts 
11:23:29     INFO - Waiting for track {813350de-0230-4758-ab4e-64f86e09ecd5} (video) to end.
11:23:29     INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts 
11:23:29     INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | iceConnectionState should not be undefined

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-central: 1

Platform breakdown:
* windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1484228&startday=2018-08-13&endday=2018-08-19&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1484228&startday=2018-08-20&endday=2018-08-26&tree=all

Comment 3

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 4

[Cristian Brindusan [:cbrindusan]]

Recent log failure:
https://treeherder.mozilla.org/logviewer.html#?job_id=203342338&repo=autoland&lineNumber=32706

Comment 5

[Intermittent Failures Robot]

2 failures in 2708 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* autoland: 2

Platform breakdown:
* windows10-64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1484228&startday=2018-10-01&endday=2018-10-07&tree=all

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1484228&startday=2018-10-08&endday=2018-10-14&tree=all

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1484228&startday=2018-11-12&endday=2018-11-18&tree=all

Comment 8

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup