1485659 - Intermittent dom/media/tests/mochitest/test_peerConnection_restartIceLocalRollback.html | Error in test execution: Error: Element _local_{b8622f88-284b-4978-8e0d-4bb117b9ddb3} should progress currentTime timeout

Status: RESOLVED INCOMPLETE

(Core :: WebRTC, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ncsoregi [at] mozilla.com

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

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

12:12:55     INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_restartIceLocalRollback.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts 
12:12:55     INFO - Found transceiver that should be receiving RTP: mid=0 currentDirection=sendrecv kind=audio track-id={7b7d8cf4-e40f-4fc0-97b6-c4740c72bdd7}
12:12:55     INFO - waitForRtpFlow({7b7d8cf4-e40f-4fc0-97b6-c4740c72bdd7})
12:12:55     INFO - waitForRtpFlow({b8622f88-284b-4978-8e0d-4bb117b9ddb3})
12:12:55     INFO - Element _local_{b8622f88-284b-4978-8e0d-4bb117b9ddb3} has enough data.
12:12:55     INFO - Element _remote_{7b7d8cf4-e40f-4fc0-97b6-c4740c72bdd7} has enough data.
12:12:55     INFO - Checking for stats in {"inbound_rtp_audio_0":{"id":"inbound_rtp_audio_0","timestamp":1535026315462,"type":"inboundrtp","isRemote":false,"kind":"audio","mediaType":"audio","nackCount":0,"ssrc":2223411031,"bytesReceived":2520,"jitter":0,"packetsLost":0,"packetsReceived":20},"l9Vy":{"id":"l9Vy","timestamp":1535026315462,"type":"candidatepair","bytesReceived":3609,"bytesSent":3485,"componentId":1,"lastPacketReceivedTimestamp":294598027461,"lastPacketSentTimestamp":294598027460,"localCandidateId":"Kynk","nominated":true,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"xfSU","selected":true,"state":"succeeded","transportId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","writable":true},"bS31":{"id":"bS31","timestamp":1535026315462,"type":"candidatepair","bytesReceived":0,"bytesSent":0,"componentId":1,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"kIDR","nominated":false,"priority":9114723795305497000,"readable":true,"remoteCandidateId":"o2eX","selected":false,"state":"inprogress","transportId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","writable":true},"Kynk":{"id":"Kynk","timestamp":1535026315462,"type":"localcandidate","candidateType":"host","componentId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","ipAddress":"10.145.109.62","mozLocalTransport":"udp","portNumber":51255,"transport":"udp"},"kIDR":{"id":"kIDR","timestamp":1535026315462,"type":"localcandidate","candidateType":"host","componentId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","ipAddress":"2001:0:4137:9e76:30a1:1519:ed31:2927","mozLocalTransport":"udp","portNumber":51254,"transport":"udp"},"droQ":{"id":"droQ","timestamp":1535026315462,"type":"localcandidate","candidateType":"host","componentId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","ipAddress":"10.145.109.62","mozLocalTransport":"tcp","portNumber":65163,"transport":"tcp"},"bdb+":{"id":"bdb+","timestamp":1535026315462,"type":"localcandidate","candidateType":"host","componentId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","ipAddress":"2001:0:4137:9e76:30a1:1519:ed31:2927","mozLocalTransport":"tcp","portNumber":59429,"transport":"tcp"},"xfSU":{"id":"xfSU","timestamp":1535026315462,"type":"remotecandidate","candidateType":"host","componentId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","ipAddress":"10.145.109.62","portNumber":51251,"transport":"udp"},"o2eX":{"id":"o2eX","timestamp":1535026315462,"type":"remotecandidate","candidateType":"host","componentId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","ipAddress":"2001:0:4137:9e76:30a1:1519:ed31:2927","portNumber":51250,"transport":"udp"},"YfXK":{"id":"YfXK","timestamp":1535026315462,"type":"remotecandidate","candidateType":"host","componentId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","ipAddress":"10.145.109.62","portNumber":9,"transport":"tcp"},"XaQD":{"id":"XaQD","timestamp":1535026315462,"type":"remotecandidate","candidateType":"host","componentId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","ipAddress":"2001:0:4137:9e76:30a1:1519:ed31:2927","portNumber":9,"transport":"tcp"}} for audio track {7b7d8cf4-e40f-4fc0-97b6-c4740c72bdd7}, retry number 0
12:12:55     INFO - Should have RTP stats for track {7b7d8cf4-e40f-4fc0-97b6-c4740c72bdd7}
12:12:55     INFO - RTP stats: {"id":"inbound_rtp_audio_0","timestamp":1535026315462,"type":"inbound-rtp","isRemote":false,"kind":"audio","mediaType":"audio","nackCount":0,"ssrc":2223411031,"bytesReceived":2520,"jitter":0,"packetsLost":0,"packetsReceived":20,"_isRemote":false}
12:12:55     INFO - Track {7b7d8cf4-e40f-4fc0-97b6-c4740c72bdd7} has 20 inbound-rtp RTP packets.
12:12:55     INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceLocalRollback.html | RTP flowing for audio track {7b7d8cf4-e40f-4fc0-97b6-c4740c72bdd7} 
12:12:55     INFO - Checking for stats in {"outbound_rtp_audio_0":{"id":"outbound_rtp_audio_0","timestamp":1535026315464,"type":"outboundrtp","isRemote":false,"kind":"audio","mediaType":"audio","nackCount":0,"remoteId":"","ssrc":3467054212,"bytesSent":2399,"packetsSent":19},"l9Vy":{"id":"l9Vy","timestamp":1535026315464,"type":"candidatepair","bytesReceived":3609,"bytesSent":3485,"componentId":1,"lastPacketReceivedTimestamp":294598027461,"lastPacketSentTimestamp":294598027460,"localCandidateId":"Kynk","nominated":true,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"xfSU","selected":true,"state":"succeeded","transportId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","writable":true},"bS31":{"id":"bS31","timestamp":1535026315464,"type":"candidatepair","bytesReceived":0,"bytesSent":0,"componentId":1,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"kIDR","nominated":false,"priority":9114723795305497000,"readable":true,"remoteCandidateId":"o2eX","selected":false,"state":"inprogress","transportId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","writable":true},"Kynk":{"id":"Kynk","timestamp":1535026315464,"type":"localcandidate","candidateType":"host","componentId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","ipAddress":"10.145.109.62","mozLocalTransport":"udp","portNumber":51255,"transport":"udp"},"kIDR":{"id":"kIDR","timestamp":1535026315464,"type":"localcandidate","candidateType":"host","componentId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","ipAddress":"2001:0:4137:9e76:30a1:1519:ed31:2927","mozLocalTransport":"udp","portNumber":51254,"transport":"udp"},"droQ":{"id":"droQ","timestamp":1535026315464,"type":"localcandidate","candidateType":"host","componentId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","ipAddress":"10.145.109.62","mozLocalTransport":"tcp","portNumber":65163,"transport":"tcp"},"bdb+":{"id":"bdb+","timestamp":1535026315464,"type":"localcandidate","candidateType":"host","componentId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","ipAddress":"2001:0:4137:9e76:30a1:1519:ed31:2927","mozLocalTransport":"tcp","portNumber":59429,"transport":"tcp"},"xfSU":{"id":"xfSU","timestamp":1535026315464,"type":"remotecandidate","candidateType":"host","componentId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","ipAddress":"10.145.109.62","portNumber":51251,"transport":"udp"},"o2eX":{"id":"o2eX","timestamp":1535026315464,"type":"remotecandidate","candidateType":"host","componentId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","ipAddress":"2001:0:4137:9e76:30a1:1519:ed31:2927","portNumber":51250,"transport":"udp"},"YfXK":{"id":"YfXK","timestamp":1535026315464,"type":"remotecandidate","candidateType":"host","componentId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","ipAddress":"10.145.109.62","portNumber":9,"transport":"tcp"},"XaQD":{"id":"XaQD","timestamp":1535026315464,"type":"remotecandidate","candidateType":"host","componentId":"0-1535026315086000 (id=6442451418 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalR aLevel=0","ipAddress":"2001:0:4137:9e76:30a1:1519:ed31:2927","portNumber":9,"transport":"tcp"}} for audio track {b8622f88-284b-4978-8e0d-4bb117b9ddb3}, retry number 0
12:12:55     INFO - Should have RTP stats for track {b8622f88-284b-4978-8e0d-4bb117b9ddb3}
12:12:55     INFO - RTP stats: {"id":"outbound_rtp_audio_0","timestamp":1535026315464,"type":"outbound-rtp","isRemote":false,"kind":"audio","mediaType":"audio","nackCount":0,"remoteId":"","ssrc":3467054212,"bytesSent":2399,"packetsSent":19,"_isRemote":false}
12:12:55     INFO - Track {b8622f88-284b-4978-8e0d-4bb117b9ddb3} has 19 outbound-rtp RTP packets.
12:12:55     INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceLocalRollback.html | RTP flowing for audio track {b8622f88-284b-4978-8e0d-4bb117b9ddb3} 
12:12:55     INFO - Buffered messages finished
12:12:55     INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_restartIceLocalRollback.html | Error in test execution: Error: Element _local_{b8622f88-284b-4978-8e0d-4bb117b9ddb3} should progress currentTime timeout/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:590:63 ... promise callback*timeout@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:590:37 ... waitForMediaElementFlow@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:1506:28 ... waitForMediaFlow/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:1601:46 ... waitForMediaFlow@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:1601:7 ... PC_REMOTE_WAIT_FOR_MEDIA_FLOW@http://mochi.test:8888/tests/dom/media/tests/mochitest/templates.js:378:12 ... execute/</<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:849:31 ... promise callback*execute/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:847:14 ... execute@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:842:12 ... PeerConnectionTest.prototype.run@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:485:10 ... @http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalRollback.html:66:5 ... runNetworkTest/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:2212:13 ... async*runTestWhenReady/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:470:41 ... promise callback*runTestWhenReady@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:470:10 ... runNetworkTest@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:2194:9 ... async*@http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceLocalRollback.html:15:3 ...  
12:12:55     INFO -     execute/<@dom/media/tests/mochitest/head.js:853:14
12:12:55     INFO -     promise callback*execute@dom/media/tests/mochitest/head.js:852:8
12:12:55     INFO -     PeerConnectionTest.prototype.run@dom/media/tests/mochitest/pc.js:485:10
12:12:55     INFO -     @dom/media/tests/mochitest/test_peerConnection_restartIceLocalRollback.html:66:5
12:12:55     INFO -     runNetworkTest/<@dom/media/tests/mochitest/pc.js:2212:13
12:12:55     INFO -     async*runTestWhenReady/<@dom/media/tests/mochitest/head.js:470:41
12:12:55     INFO -     promise callback*runTestWhenReady@dom/media/tests/mochitest/head.js:470:10
12:12:55     INFO -     runNetworkTest@dom/media/tests/mochitest/pc.js:2194:9
12:12:55     INFO -     async*@dom/media/tests/mochitest/test_peerConnection_restartIceLocalRollback.html:15:3
12:12:55     INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_restartIceLocalRollback.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts 
12:12:55     INFO - Closing peer connections
12:12:55     INFO - Waiting for track {01e10063-7826-4f29-8dbd-e7c4c47dc259} (audio) to end.
12:12:55     INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_restartIceLocalRollback.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts 
12:12:55     INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceLocalRollback.html | iceConnectionState should not be undefined

Comment 1

[Intermittent Failures Robot]

1 failures in 2568 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=1485659&startday=2018-08-20&endday=2018-08-26&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-beta: 1

Platform breakdown:
* windows10-64-nightly: 1

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

Comment 3

[Intermittent Failures Robot]

1 failures in 2902 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=1485659&startday=2018-09-17&endday=2018-09-23&tree=all

Comment 4

[Intermittent Failures Robot]

1 failures in 2773 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=1485659&startday=2018-09-24&endday=2018-09-30&tree=all

Comment 5

[Intermittent Failures Robot]

1 failures in 2978 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=1485659&startday=2018-10-29&endday=2018-11-04&tree=all

Comment 6

[Firefox Bug Husbandry Bot]

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

Comment 7

[Firefox Bug Husbandry Bot]

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

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr60: 1

Platform breakdown:

• windows10-64-nightly: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1485659&startday=2019-06-10&endday=2019-06-16&tree=all