Closed Bug 1433750 Opened 8 years ago Closed 7 years ago

Intermittent dom/media/tests/mochitest/test_dataChannel_basicAudio.html | Error in test execution: Error: Element... should progress currentTime timeout/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:568:63

Categories

(Core :: WebRTC: Audio/Video, defect, P5)

Product:
Core
Component:
WebRTC: Audio/Video
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Component: Audio/Video → WebRTC: Audio/Video
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 8 years ago
Resolution: --- → INCOMPLETE
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=189196643&repo=autoland&lineNumber=12207 14:52:16 INFO - 1076 INFO TEST-FAIL | dom/media/tests/mochitest/test_dataChannel_basicAudio.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts 14:52:16 INFO - 1077 INFO Found transceiver that should be receiving RTP: mid=sdparta_0 currentDirection=sendrecv kind=audio track-id={8a6de1fa-1a25-455e-9eef-4451adbd0fba} 14:52:16 INFO - 1078 INFO waitForRtpFlow({8a6de1fa-1a25-455e-9eef-4451adbd0fba}) 14:52:16 INFO - 1079 INFO waitForRtpFlow({4a80c705-bb19-4969-9439-e878fdf6beeb}) 14:52:16 INFO - 1080 INFO Element _local_{4a80c705-bb19-4969-9439-e878fdf6beeb} has enough data. 14:52:16 INFO - 1081 INFO Element _remote_{8a6de1fa-1a25-455e-9eef-4451adbd0fba} has enough data. 14:52:16 INFO - 1082 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_basicAudio.html | data channel is 'open' after 'onopen' 14:52:16 INFO - 1083 INFO Checking for stats in {"inbound_rtp_audio_0":{"id":"inbound_rtp_audio_0","timestamp":1532098276606,"type":"inboundrtp","isRemote":false,"mediaType":"audio","nackCount":0,"ssrc":2453845269,"bytesReceived":616,"jitter":0,"packetsLost":0,"packetsReceived":4},"HjW4":{"id":"HjW4","timestamp":1532098276606,"type":"candidatepair","bytesReceived":2647,"bytesSent":2634,"componentId":1,"lastPacketReceivedTimestamp":291669988602,"lastPacketSentTimestamp":291669988601,"localCandidateId":"bfGt","nominated":true,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"VzPi","selected":true,"state":"succeeded","transportId":"0-1532098276259000 (id=4294967306 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudio.html) aLevel=0","writable":true},"bfGt":{"id":"bfGt","timestamp":1532098276606,"type":"localcandidate","candidateType":"host","componentId":"0-1532098276259000 (id=4294967306 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudio.html) aLevel=0","ipAddress":"10.143.23.245","mozLocalTransport":"udp","portNumber":54556,"transport":"udp"},"Xu3e":{"id":"Xu3e","timestamp":1532098276606,"type":"localcandidate","candidateType":"host","componentId":"0-1532098276259000 (id=4294967306 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudio.html) aLevel=0","ipAddress":"2001:0:9d38:90d7:18f7:1157:c948:6366","mozLocalTransport":"udp","portNumber":54555,"transport":"udp"},"u59H":{"id":"u59H","timestamp":1532098276606,"type":"localcandidate","candidateType":"host","componentId":"0-1532098276259000 (id=4294967306 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudio.html) aLevel=0","ipAddress":"10.143.23.245","mozLocalTransport":"tcp","portNumber":52410,"transport":"tcp"},"LO/m":{"id":"LO/m","timestamp":1532098276606,"type":"localcandidate","candidateType":"host","componentId":"0-1532098276259000 (id=4294967306 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudio.html) aLevel=0","ipAddress":"2001:0:9d38:90d7:18f7:1157:c948:6366","mozLocalTransport":"tcp","portNumber":55571,"transport":"tcp"},"VzPi":{"id":"VzPi","timestamp":1532098276606,"type":"remotecandidate","candidateType":"host","componentId":"0-1532098276259000 (id=4294967306 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudio.html) aLevel=0","ipAddress":"10.143.23.245","portNumber":54560,"transport":"udp"}} for audio track {8a6de1fa-1a25-455e-9eef-4451adbd0fba}, retry number 0 14:52:16 INFO - 1084 INFO Should have RTP stats for track {8a6de1fa-1a25-455e-9eef-4451adbd0fba} 14:52:16 INFO - 1085 INFO RTP stats: {"id":"inbound_rtp_audio_0","timestamp":1532098276606,"type":"inbound-rtp","isRemote":false,"mediaType":"audio","nackCount":0,"ssrc":2453845269,"bytesReceived":616,"jitter":0,"packetsLost":0,"packetsReceived":4} 14:52:16 INFO - 1086 INFO Track {8a6de1fa-1a25-455e-9eef-4451adbd0fba} has 4 inbound-rtp RTP packets. 14:52:16 INFO - 1087 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_basicAudio.html | RTP flowing for audio track {8a6de1fa-1a25-455e-9eef-4451adbd0fba} 14:52:16 INFO - 1088 INFO Checking for stats in {"outbound_rtp_audio_0":{"id":"outbound_rtp_audio_0","timestamp":1532098276612,"type":"outboundrtp","isRemote":false,"mediaType":"audio","nackCount":0,"remoteId":"","ssrc":3568935031,"bytesSent":745,"packetsSent":5},"HjW4":{"id":"HjW4","timestamp":1532098276612,"type":"candidatepair","bytesReceived":2786,"bytesSent":2773,"componentId":1,"lastPacketReceivedTimestamp":291669988612,"lastPacketSentTimestamp":291669988610,"localCandidateId":"bfGt","nominated":true,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"VzPi","selected":true,"state":"succeeded","transportId":"0-1532098276259000 (id=4294967306 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudio.html) aLevel=0","writable":true},"bfGt":{"id":"bfGt","timestamp":1532098276612,"type":"localcandidate","candidateType":"host","componentId":"0-1532098276259000 (id=4294967306 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudio.html) aLevel=0","ipAddress":"10.143.23.245","mozLocalTransport":"udp","portNumber":54556,"transport":"udp"},"Xu3e":{"id":"Xu3e","timestamp":1532098276612,"type":"localcandidate","candidateType":"host","componentId":"0-1532098276259000 (id=4294967306 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudio.html) aLevel=0","ipAddress":"2001:0:9d38:90d7:18f7:1157:c948:6366","mozLocalTransport":"udp","portNumber":54555,"transport":"udp"},"u59H":{"id":"u59H","timestamp":1532098276612,"type":"localcandidate","candidateType":"host","componentId":"0-1532098276259000 (id=4294967306 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudio.html) aLevel=0","ipAddress":"10.143.23.245","mozLocalTransport":"tcp","portNumber":52410,"transport":"tcp"},"LO/m":{"id":"LO/m","timestamp":1532098276612,"type":"localcandidate","candidateType":"host","componentId":"0-1532098276259000 (id=4294967306 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudio.html) aLevel=0","ipAddress":"2001:0:9d38:90d7:18f7:1157:c948:6366","mozLocalTransport":"tcp","portNumber":55571,"transport":"tcp"},"VzPi":{"id":"VzPi","timestamp":1532098276612,"type":"remotecandidate","candidateType":"host","componentId":"0-1532098276259000 (id=4294967306 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudio.html) aLevel=0","ipAddress":"10.143.23.245","portNumber":54560,"transport":"udp"}} for audio track {4a80c705-bb19-4969-9439-e878fdf6beeb}, retry number 0 14:52:16 INFO - 1089 INFO Should have RTP stats for track {4a80c705-bb19-4969-9439-e878fdf6beeb} 14:52:16 INFO - 1090 INFO RTP stats: {"id":"outbound_rtp_audio_0","timestamp":1532098276612,"type":"outbound-rtp","isRemote":false,"mediaType":"audio","nackCount":0,"remoteId":"","ssrc":3568935031,"bytesSent":745,"packetsSent":5} 14:52:16 INFO - 1091 INFO Track {4a80c705-bb19-4969-9439-e878fdf6beeb} has 5 outbound-rtp RTP packets. 14:52:16 INFO - 1092 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_basicAudio.html | RTP flowing for audio track {4a80c705-bb19-4969-9439-e878fdf6beeb} 14:52:16 INFO - Buffered messages finished 14:52:16 ERROR - 1093 INFO TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_basicAudio.html | Error in test execution: Error: Element _remote_{8a6de1fa-1a25-455e-9eef-4451adbd0fba} should progress currentTime timeout/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:596:63 ... promise callback*timeout@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:596:26 ... 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:1606:22 ... waitForMediaFlow@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:1602:7 ... 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 ... promise callback*execute/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:853:14 ... execute@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:848: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_dataChannel_basicAudio.html:19: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:476:41 ... promise callback*runTestWhenReady@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:476: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_dataChannel_basicAudio.html:15:3 ... 14:52:16 INFO - execute/<@dom/media/tests/mochitest/head.js:859:14 14:52:16 INFO - promise callback*execute@dom/media/tests/mochitest/head.js:848:12 14:52:16 INFO - PeerConnectionTest.prototype.run@dom/media/tests/mochitest/pc.js:485:10 14:52:16 INFO - @dom/media/tests/mochitest/test_dataChannel_basicAudio.html:19:5 14:52:16 INFO - runNetworkTest/<@dom/media/tests/mochitest/pc.js:2212:13 14:52:16 INFO - async*runTestWhenReady/<@dom/media/tests/mochitest/head.js:476:41 14:52:16 INFO - promise callback*runTestWhenReady@dom/media/tests/mochitest/head.js:476:10 14:52:16 INFO - runNetworkTest@dom/media/tests/mochitest/pc.js:2194:9 14:52:16 INFO - async*@dom/media/tests/mochitest/test_dataChannel_basicAudio.html:15:3 14:52:16 INFO - 1094 INFO closeDataChannels called with index: 0 14:52:16 INFO - 1095 INFO TEST-FAIL | dom/media/tests/mochitest/test_dataChannel_basicAudio.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 8 years ago → 7 years ago
Resolution: --- → INCOMPLETE
This failure reoccurred: https://treeherder.mozilla.org/logviewer.html#?job_id=208160552&repo=mozilla-inbound&lineNumber=5511 09:45:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_dataChannel_basicAudio.html | RTP flowing for audio track {b441067f-55d8-4b2f-b268-9125908d6dcb} 09:45:01 INFO - Buffered messages finished 09:45:01 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_basicAudio.html | Error in test execution: Error: Element _remote_{37897d2e-d95b-4301-bf91-947da48296d6} should progress currentTime timeout/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:591:63 ... promise callback*timeout@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:591:37 ... waitForMediaElementFlow@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:1507:28 ... waitForMediaFlow/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:1607:22 ... waitForMediaFlow@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:1607:10 ... 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:850:31 ... promise callback*execute/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:848:14 ... execute@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:843:12 ... PeerConnectionTest.prototype.run@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:486:10 ... @http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudio.html:19:5 ... runNetworkTest/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:2211:13 ... async*runTestWhenReady/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:471:41 ... promise callback*runTestWhenReady@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:471:10 ... runNetworkTest@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:2193:9 ... async*@http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudio.html:15:3 ... 09:45:01 INFO - execute/<@dom/media/tests/mochitest/head.js:854:14 09:45:01 INFO - promise callback*execute@dom/media/tests/mochitest/head.js:853:8 09:45:01 INFO - PeerConnectionTest.prototype.run@dom/media/tests/mochitest/pc.js:486:10 09:45:01 INFO - @dom/media/tests/mochitest/test_dataChannel_basicAudio.html:19:5 09:45:01 INFO - runNetworkTest/<@dom/media/tests/mochitest/pc.js:2211:13 09:45:01 INFO - async*runTestWhenReady/<@dom/media/tests/mochitest/head.js:471:41 09:45:01 INFO - promise callback*runTestWhenReady@dom/media/tests/mochitest/head.js:471:10 09:45:01 INFO - runNetworkTest@dom/media/tests/mochitest/pc.js:2193:9 09:45:01 INFO - async*@dom/media/tests/mochitest/test_dataChannel_basicAudio.html:15:3 09:45:01 INFO - closeDataChannels called with index: 0
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 7 years ago → 7 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.