1421891 - Intermittent dom/media/tests/mochitest/test_dataChannel_basicAudioVideoCombined.html | Error in test execution: Error: Element _remote_{79a4138b-2179-46b1-ad81-09d7ddce58c0} should progress currentTime timeout/<@http://mochi.test:8888/tests/dom/media/test

Status: RESOLVED INCOMPLETE

(Core :: WebRTC, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: philringnalda [at] gmail.com

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

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

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-inbound: 2

Platform breakdown:
* windows10-64: 2

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1421891&startday=2017-11-27&endday=2017-12-03&tree=all

Comment 2

[Intermittent Failures Robot]

1 failures in 423 pushes (0.002 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://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1421891&startday=2017-12-11&endday=2017-12-17&tree=all

Comment 3

[Intermittent Failures Robot]

1 failures in 590 pushes (0.002 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://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1421891&startday=2017-12-18&endday=2017-12-24&tree=all

Comment 4

[Firefox Bug Husbandry Bot]

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

Comment 5

[Intermittent Failures Robot]

1 failures in 701 pushes (0.001 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://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1421891&startday=2018-01-22&endday=2018-01-28&tree=all

Comment 6

[Natalia Csoregi [:nataliaCs]]

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

Comment 7

[Intermittent Failures Robot]

1 failures in 735 pushes (0.001 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://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1421891&startday=2018-01-29&endday=2018-02-04&tree=all

Comment 8

[Intermittent Failures Robot]

1 failures in 831 pushes (0.001 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://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1421891&startday=2018-02-26&endday=2018-03-04&tree=all

Comment 9

[Firefox Bug Husbandry Bot]

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

Comment 10

[Intermittent Failures Robot]

1 failures in 765 pushes (0.001 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=1421891&startday=2018-04-02&endday=2018-04-08&tree=trunk

Comment 11

[Intermittent Failures Robot]

1 failures in 408 pushes (0.002 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=1421891&startday=2018-06-11&endday=2018-06-17&tree=trunk

Comment 12

[Andreea Pavel [:apavel]]

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

12:12:37     INFO - TEST-PASS | dom/media/tests/mochitest/test_dataChannel_basicAudioVideoCombined.html | RTP flowing for audio track {62848a4b-6689-439e-956b-7e68e8e14c76} 
12:12:37     INFO - Checking for stats in {"outbound_rtcp_video_0":{"id":"outbound_rtcp_video_0","timestamp":1452299,"type":"inboundrtp","isRemote":true,"mediaType":"video","remoteId":"outbound_rtp_video_0","ssrc":3659222316,"bytesReceived":1553,"jitter":0.002,"packetsLost":0,"packetsReceived":30},"outbound_rtp_video_0":{"id":"outbound_rtp_video_0","timestamp":1533384697749,"type":"outboundrtp","bitrateMean":8.59408295342e-312,"bitrateStdDev":8.59408295356e-312,"firCount":0,"framerateMean":1452299,"framerateStdDev":7.673e-321,"isRemote":false,"mediaType":"video","nackCount":0,"pliCount":0,"remoteId":"outbound_rtcp_video_0","ssrc":3659222316,"bytesSent":8113,"droppedFrames":0,"framesEncoded":0,"packetsSent":30},"JtPo":{"id":"JtPo","timestamp":1533384697749,"type":"candidatepair","bytesReceived":13300,"bytesSent":12739,"componentId":1,"lastPacketReceivedTimestamp":292956409748,"lastPacketSentTimestamp":292956409726,"localCandidateId":"IW7Z","nominated":true,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"xWPV","selected":true,"state":"succeeded","transportId":"0-1533384697328000 (id=4294967314 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudioVideoComb aLevel=0","writable":true},"JD4y":{"id":"JD4y","timestamp":1533384697749,"type":"candidatepair","bytesReceived":0,"bytesSent":0,"componentId":1,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"TnZE","nominated":false,"priority":9114723795305497000,"readable":true,"remoteCandidateId":"xLVl","selected":false,"state":"inprogress","transportId":"0-1533384697328000 (id=4294967314 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudioVideoComb aLevel=0","writable":true},"IW7Z":{"id":"IW7Z","timestamp":1533384697749,"type":"localcandidate","candidateType":"host","componentId":"0-1533384697328000 (id=4294967314 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudioVideoComb aLevel=0","ipAddress":"10.144.28.85","mozLocalTransport":"udp","portNumber":62459,"transport":"udp"},"TnZE":{"id":"TnZE","timestamp":1533384697749,"type":"localcandidate","candidateType":"host","componentId":"0-1533384697328000 (id=4294967314 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudioVideoComb aLevel=0","ipAddress":"2001:0:9d38:953c:2c03:21cf:c937:c5be","mozLocalTransport":"udp","portNumber":62458,"transport":"udp"},"1QHT":{"id":"1QHT","timestamp":1533384697749,"type":"localcandidate","candidateType":"host","componentId":"0-1533384697328000 (id=4294967314 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudioVideoComb aLevel=0","ipAddress":"10.144.28.85","mozLocalTransport":"tcp","portNumber":51138,"transport":"tcp"},"IA/d":{"id":"IA/d","timestamp":1533384697749,"type":"localcandidate","candidateType":"host","componentId":"0-1533384697328000 (id=4294967314 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudioVideoComb aLevel=0","ipAddress":"2001:0:9d38:953c:2c03:21cf:c937:c5be","mozLocalTransport":"tcp","portNumber":65358,"transport":"tcp"},"xWPV":{"id":"xWPV","timestamp":1533384697749,"type":"remotecandidate","candidateType":"host","componentId":"0-1533384697328000 (id=4294967314 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudioVideoComb aLevel=0","ipAddress":"10.144.28.85","portNumber":62452,"transport":"udp"},"xLVl":{"id":"xLVl","timestamp":1533384697749,"type":"remotecandidate","candidateType":"host","componentId":"0-1533384697328000 (id=4294967314 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudioVideoComb aLevel=0","ipAddress":"2001:0:9d38:953c:2c03:21cf:c937:c5be","portNumber":62451,"transport":"udp"},"U4j7":{"id":"U4j7","timestamp":1533384697749,"type":"remotecandidate","candidateType":"host","componentId":"0-1533384697328000 (id=4294967314 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudioVideoComb aLevel=0","ipAddress":"10.144.28.85","portNumber":9,"transport":"tcp"},"428X":{"id":"428X","timestamp":1533384697749,"type":"remotecandidate","candidateType":"host","componentId":"0-1533384697328000 (id=4294967314 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_basicAudioVideoComb aLevel=0","ipAddress":"2001:0:9d38:953c:2c03:21cf:c937:c5be","portNumber":9,"transport":"tcp"}} for video track {43b582b1-3a71-4140-a2b9-526b1bd4b3e5}, retry number 0
12:12:37     INFO - Should have RTP stats for track {43b582b1-3a71-4140-a2b9-526b1bd4b3e5}
12:12:37     INFO - RTP stats: {"id":"outbound_rtp_video_0","timestamp":1533384697749,"type":"outbound-rtp","bitrateMean":8.59408295342e-312,"bitrateStdDev":8.59408295356e-312,"firCount":0,"framerateMean":1452299,"framerateStdDev":7.673e-321,"isRemote":false,"mediaType":"video","nackCount":0,"pliCount":0,"remoteId":"outbound_rtcp_video_0","ssrc":3659222316,"bytesSent":8113,"droppedFrames":0,"framesEncoded":0,"packetsSent":30}
12:12:37     INFO - Track {43b582b1-3a71-4140-a2b9-526b1bd4b3e5} has 30 outbound-rtp RTP packets.
12:12:37     INFO - TEST-PASS | dom/media/tests/mochitest/test_dataChannel_basicAudioVideoCombined.html | RTP flowing for video track {43b582b1-3a71-4140-a2b9-526b1bd4b3e5} 
12:12:37     INFO - Buffered messages finished
12:12:37     INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_basicAudioVideoCombined.html | Error in test execution: Error: Element _local_{62848a4b-6689-439e-956b-7e68e8e14c76} 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: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: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_basicAudioVideoCombined.html:20: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_basicAudioVideoCombined.html:15:3 ...  
12:12:37     INFO -     execute/<@dom/media/tests/mochitest/head.js:859:14
12:12:37     INFO -     promise callback*execute@dom/media/tests/mochitest/head.js:848:12
12:12:37     INFO -     PeerConnectionTest.prototype.run@dom/media/tests/mochitest/pc.js:485:10
12:12:37     INFO -     @dom/media/tests/mochitest/test_dataChannel_basicAudioVideoCombined.html:20:5
12:12:37     INFO -     runNetworkTest/<@dom/media/tests/mochitest/pc.js:2212:13
12:12:37     INFO -     async*runTestWhenReady/<@dom/media/tests/mochitest/head.js:476:41
12:12:37     INFO -     promise callback*runTestWhenReady@dom/media/tests/mochitest/head.js:476:10
12:12:37     INFO -     runNetworkTest@dom/media/tests/mochitest/pc.js:2194:9
12:12:37     INFO -     async*@dom/media/tests/mochitest/test_dataChannel_basicAudioVideoCombined.html:15:3
12:12:37     INFO - closeDataChannels called with index: 0
12:12:37     INFO - TEST-FAIL | dom/media/tests/mochitest/test_dataChannel_basicAudioVideoCombined.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts 
12:12:37     INFO - DataChannelWrapper (pcRemote_channel_0): Closing channel
12:12:37     INFO - GECKO(4816) | [Child 4556: Main Thread]: D/DataChannel Connection 000001951B504400/Channel 00000195194B1400: Closing stream 1
12:12:37     INFO - GECKO(4816) | [Child 4556: Main Thread]: D/DataChannel Connection 000001951B504400: Resetting outgoing stream 1
12:12:37     INFO - GECKO(4816) | [Child 4556: Main Thread]: D/DataChannel Connection 000001951B504400: Sending outgoing stream reset for 1 streams
12:12:37     INFO - TEST-FAIL | dom/media/tests/mochitest/test_dataChannel_basicAudioVideoCombined.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts 
12:12:37     INFO - GECKO(4816) | [Child 4556: Socket Thread]: D/DataChannel Incoming: Channel 1  closed, state 1
12:12:37     INFO - GECKO(4816) | [Child 4556: Socket Thread]: D/DataChannel Connection 000001951945BC00: Resetting outgoing stream 1
12:12:37     INFO - GECKO(4816) | [Child 4556: Socket Thread]: D/DataChannel Disconnected DataChannel 0000019518C241C0 from connection 000001951945BC00
12:12:37     INFO - GECKO(4816) | [Child 4556: Socket Thread]: D/DataChannel Destroying Data channel 1
12:12:37     INFO - GECKO(4816) | [Child 4556: Socket Thread]: D/DataChannel Sending 1 pending resets
12:12:37     INFO - GECKO(4816) | [Child 4556: Socket Thread]: D/DataChannel Connection 000001951945BC00: Sending outgoing stream reset for 1 streams
12:12:37     INFO - GECKO(4816) | [Child 4556: Main Thread]: D/DataChannel 0000019518CCBB30(0000019518C241C0): OnChannelClosed - Dispatching
12:12:37     INFO - DataChannelWrapper (pcLocal_channel_0): "onclose" event fired

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-central: 1

Platform breakdown:
* windows10-64-qr: 1

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

Comment 14

[Firefox Bug Husbandry Bot]

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