Closed Bug 1576209 Opened 5 years ago Closed 5 years ago

Intermittent dom/media/tests/mochitest/test_peerConnection_noTrickleOfferAnswer.html | Error in test execution: Error: Element _remote_{17d49e7e-dac5-40ab-be15-2fe039868b60} should progress currentTime timeout

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, regression)

Filed by: ncsoregi [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=263189322&repo=mozilla-inbound
Full log: https://queue.taskcluster.net/v1/task/CRhGu6avSU-51j0qScuJmQ/runs/0/artifacts/public/logs/live_backing.log

[task 2019-08-23T15:19:58.972Z] 15:19:58 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_noTrickleOfferAnswer.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-08-23T15:19:58.973Z] 15:19:58 INFO - Buffered messages logged at 15:18:59
[task 2019-08-23T15:19:58.973Z] 15:19:58 INFO - Checking for stats in [["inbound_rtp_audio_0",{"id":"inbound_rtp_audio_0","timestamp":1566573539333,"type":"inbound-rtp","kind":"audio","mediaType":"audio","ssrc":3961052406,"jitter":0.001,"packetsLost":0,"packetsReceived":20,"bytesReceived":3780,"nackCount":0}],["4r08",{"id":"4r08","timestamp":1566573539333,"type":"candidate-pair","bytesReceived":4937,"bytesSent":5009,"componentId":1,"lastPacketReceivedTimestamp":326145251020,"lastPacketSentTimestamp":326145251020,"localCandidateId":"l2+w","nominated":true,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"5xmE","selected":true,"state":"succeeded","transportId":"transport_0","writable":true}],["l2+w",{"id":"l2+w","timestamp":1566573539333,"type":"local-candidate","address":"10.145.73.216","candidateType":"host","port":62935,"priority":2122252543,"protocol":"udp","proxied":"non-proxied"}],["1PwR",{"id":"1PwR","timestamp":1566573539333,"type":"local-candidate","address":"10.145.73.216","candidateType":"host","port":54532,"priority":2105524479,"protocol":"tcp","proxied":"non-proxied"}],["5xmE",{"id":"5xmE","timestamp":1566573539333,"type":"remote-candidate","address":"10.145.73.216","candidateType":"host","port":62938,"priority":2122252543,"protocol":"udp","proxied":"non-proxied"}],["koH8",{"id":"koH8","timestamp":1566573539333,"type":"remote-candidate","address":"10.145.73.216","candidateType":"host","port":9,"priority":2105524479,"protocol":"tcp","proxied":"non-proxied"}]] for audio track {17d49e7e-dac5-40ab-be15-2fe039868b60}retry number 1
[task 2019-08-23T15:19:58.973Z] 15:19:58 INFO - Should have RTP stats for track {17d49e7e-dac5-40ab-be15-2fe039868b60}
[task 2019-08-23T15:19:58.974Z] 15:19:58 INFO - RTP stats: {"id":"inbound_rtp_audio_0","timestamp":1566573539333,"type":"inbound-rtp","kind":"audio","mediaType":"audio","ssrc":3961052406,"jitter":0.001,"packetsLost":0,"packetsReceived":20,"bytesReceived":3780,"nackCount":0}
[task 2019-08-23T15:19:58.974Z] 15:19:58 INFO - Track {17d49e7e-dac5-40ab-be15-2fe039868b60} has 20 inbound-rtp RTP packets.
[task 2019-08-23T15:19:58.975Z] 15:19:58 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_noTrickleOfferAnswer.html | RTP flowing for audio track {17d49e7e-dac5-40ab-be15-2fe039868b60}
[task 2019-08-23T15:19:58.975Z] 15:19:58 INFO - Checking for stats in [["outbound_rtp_audio_0",{"id":"outbound_rtp_audio_0","timestamp":1566573539336,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":3841911253,"bytesSent":3780,"packetsSent":20,"nackCount":0,"remoteId":""}],["4r08",{"id":"4r08","timestamp":1566573539336,"type":"candidate-pair","bytesReceived":4937,"bytesSent":5009,"componentId":1,"lastPacketReceivedTimestamp":326145251020,"lastPacketSentTimestamp":326145251020,"localCandidateId":"l2+w","nominated":true,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"5xmE","selected":true,"state":"succeeded","transportId":"transport_0","writable":true}],["l2+w",{"id":"l2+w","timestamp":1566573539336,"type":"local-candidate","address":"10.145.73.216","candidateType":"host","port":62935,"priority":2122252543,"protocol":"udp","proxied":"non-proxied"}],["1PwR",{"id":"1PwR","timestamp":1566573539336,"type":"local-candidate","address":"10.145.73.216","candidateType":"host","port":54532,"priority":2105524479,"protocol":"tcp","proxied":"non-proxied"}],["5xmE",{"id":"5xmE","timestamp":1566573539336,"type":"remote-candidate","address":"10.145.73.216","candidateType":"host","port":62938,"priority":2122252543,"protocol":"udp","proxied":"non-proxied"}],["koH8",{"id":"koH8","timestamp":1566573539336,"type":"remote-candidate","address":"10.145.73.216","candidateType":"host","port":9,"priority":2105524479,"protocol":"tcp","proxied":"non-proxied"}]] for audio track {6a02510e-295f-47bd-a42f-2031293e2450}retry number 1
[task 2019-08-23T15:19:58.976Z] 15:19:58 INFO - Should have RTP stats for track {6a02510e-295f-47bd-a42f-2031293e2450}
[task 2019-08-23T15:19:58.976Z] 15:19:58 INFO - RTP stats: {"id":"outbound_rtp_audio_0","timestamp":1566573539336,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":3841911253,"bytesSent":3780,"packetsSent":20,"nackCount":0,"remoteId":""}
[task 2019-08-23T15:19:58.976Z] 15:19:58 INFO - Track {6a02510e-295f-47bd-a42f-2031293e2450} has 20 outbound-rtp RTP packets.
[task 2019-08-23T15:19:58.977Z] 15:19:58 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_noTrickleOfferAnswer.html | RTP flowing for audio track {6a02510e-295f-47bd-a42f-2031293e2450}
[task 2019-08-23T15:19:58.977Z] 15:19:58 INFO - Buffered messages finished
[task 2019-08-23T15:19:58.978Z] 15:19:58 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_noTrickleOfferAnswer.html | Error in test execution: Error: Element remote{17d49e7e-dac5-40ab-be15-2fe039868b60} should progress currentTime timeout/<@https://example.com/tests/dom/media/tests/mochitest/head.js:652:42 ... promise callbacktimeout@https://example.com/tests/dom/media/tests/mochitest/head.js:652:16 ... waitForMediaElementFlow@https://example.com/tests/dom/media/tests/mochitest/pc.js:1790:35 ... waitForMediaFlow/<@https://example.com/tests/dom/media/tests/mochitest/pc.js:1913:29 ... waitForMediaFlow@https://example.com/tests/dom/media/tests/mochitest/pc.js:1913:12 ... PC_LOCAL_WAIT_FOR_MEDIA_FLOW@https://example.com/tests/dom/media/tests/mochitest/templates.js:513:25 ... execute/</<@https://example.com/tests/dom/media/tests/mochitest/head.js:944:32 ...
[task 2019-08-23T15:19:58.978Z] 15:19:58 INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:277:18
[task 2019-08-23T15:19:58.978Z] 15:19:58 INFO - execute/<@https://example.com/tests/dom/media/tests/mochitest/head.js:948:11
[task 2019-08-23T15:19:58.978Z] 15:19:58 INFO - promise callbackexecute@https://example.com/tests/dom/media/tests/mochitest/head.js:947:8
[task 2019-08-23T15:19:58.978Z] 15:19:58 INFO - PeerConnectionTest.prototype.run@https://example.com/tests/dom/media/tests/mochitest/pc.js:574:6
[task 2019-08-23T15:19:58.979Z] 15:19:58 INFO - @https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_noTrickleOfferAnswer.html:21:10
[task 2019-08-23T15:19:58.979Z] 15:19:58 INFO - runNetworkTest/<@https://example.com/tests/dom/media/tests/mochitest/pc.js:2564:11
[task 2019-08-23T15:19:58.979Z] 15:19:58 INFO - asyncrunTestWhenReady/<@https://example.com/tests/dom/media/tests/mochitest/head.js:492:30
[task 2019-08-23T15:19:58.979Z] 15:19:58 INFO - promise callbackrunTestWhenReady@https://example.com/tests/dom/media/tests/mochitest/head.js:492:6
[task 2019-08-23T15:19:58.980Z] 15:19:58 INFO - runNetworkTest@https://example.com/tests/dom/media/tests/mochitest/pc.js:2545:9
[task 2019-08-23T15:19:58.980Z] 15:19:58 INFO - async*@https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_noTrickleOfferAnswer.html:16:17
[task 2019-08-23T15:19:58.980Z] 15:19:58 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_noTrickleOfferAnswer.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-08-23T15:19:58.981Z] 15:19:58 INFO - Closing peer connections

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

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.