1496103 - Intermittent test_dataChannel_bug1013809.html | Error in test execution: Error: Element _remote_{21333a42-3038-4259-becf-1b4368801b7c} should progress currentTime timeout/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:591:63

Status: RESOLVED INCOMPLETE

(Core :: WebRTC, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: toros [at] mozilla.com

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

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

11:50:21     INFO - TEST-PASS | dom/media/tests/mochitest/test_dataChannel_bug1013809.html | RTP flowing for audio track {21333a42-3038-4259-becf-1b4368801b7c} 
11:50:21     INFO - Checking for stats in {"outbound_rtp_audio_0":{"id":"outbound_rtp_audio_0","timestamp":1538567361211,"type":"outboundrtp","isRemote":false,"kind":"audio","mediaType":"audio","nackCount":0,"remoteId":"","ssrc":3543979338,"bytesSent":584,"packetsSent":4}} for audio track {b04ac0cd-7fc1-4895-963d-8fe8f48e9b9f}, retry number 0
11:50:21     INFO - Should have RTP stats for track {b04ac0cd-7fc1-4895-963d-8fe8f48e9b9f}
11:50:21     INFO - RTP stats: {"id":"outbound_rtp_audio_0","timestamp":1538567361211,"type":"outbound-rtp","isRemote":false,"kind":"audio","mediaType":"audio","nackCount":0,"remoteId":"","ssrc":3543979338,"bytesSent":584,"packetsSent":4,"_isRemote":false}
11:50:21     INFO - Track {b04ac0cd-7fc1-4895-963d-8fe8f48e9b9f} has 4 outbound-rtp RTP packets.
11:50:21     INFO - TEST-PASS | dom/media/tests/mochitest/test_dataChannel_bug1013809.html | RTP flowing for audio track {b04ac0cd-7fc1-4895-963d-8fe8f48e9b9f} 
11:50:21     INFO - Buffered messages finished
11:50:21     INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_bug1013809.html | Error in test execution: Error: Element _remote_{21333a42-3038-4259-becf-1b4368801b7c} 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_bug1013809.html:21:5 ... runNetworkTest/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:2208: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:2190:9 ... async*@http://mochi.test:8888/tests/dom/media/tests/mochitest/test_dataChannel_bug1013809.html:15:3 ...  
11:50:21     INFO -     execute/<@dom/media/tests/mochitest/head.js:854:14
11:50:21     INFO -     promise callback*execute@dom/media/tests/mochitest/head.js:853:8
11:50:21     INFO -     PeerConnectionTest.prototype.run@dom/media/tests/mochitest/pc.js:486:10
11:50:21     INFO -     @dom/media/tests/mochitest/test_dataChannel_bug1013809.html:21:5
11:50:21     INFO -     runNetworkTest/<@dom/media/tests/mochitest/pc.js:2208:13
11:50:21     INFO -     async*runTestWhenReady/<@dom/media/tests/mochitest/head.js:471:41
11:50:21     INFO -     promise callback*runTestWhenReady@dom/media/tests/mochitest/head.js:471:10
11:50:21     INFO -     runNetworkTest@dom/media/tests/mochitest/pc.js:2190:9
11:50:21     INFO -     async*@dom/media/tests/mochitest/test_dataChannel_bug1013809.html:15:3
11:50:21     INFO - closeDataChannels called with index: 0
11:50:21     INFO - TEST-FAIL | dom/media/tests/mochitest/test_dataChannel_bug1013809.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts 
11:50:21     INFO - DataChannelWrapper (pcRemote_channel_0): Closing channel
11:50:21     INFO - GECKO(3940) | [Child 868: Main Thread]: D/DataChannel Connection 0000023671FA0400/Channel 000002364747F340: Closing stream 1
11:50:21     INFO - GECKO(3940) | [Child 868: Main Thread]: D/DataChannel Connection 0000023671FA0400: Resetting outgoing stream 1
11:50:21     INFO - GECKO(3940) | [Child 868: Main Thread]: D/DataChannel Connection 0000023671FA0400: Sending outgoing stream reset for 1 streams
11:50:21     INFO - TEST-FAIL | dom/media/tests/mochitest/test_dataChannel_bug1013809.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts 
11:50:21     INFO - GECKO(3940) | [Child 868: Socket Thread]: D/DataChannel Incoming: Channel 1  closed, state 1
11:50:21     INFO - GECKO(3940) | [Child 868: Socket Thread]: D/DataChannel Connection 0000023653FF0800: Resetting outgoing stream 1
11:50:21     INFO - GECKO(3940) | [Child 868: Socket Thread]: D/DataChannel Disconnected DataChannel 0000023646DF9A00 from connection 0000023653FF0800
11:50:21     INFO - GECKO(3940) | [Child 868: Socket Thread]: D/DataChannel Destroying Data channel 1
11:50:21     INFO - GECKO(3940) | [Child 868: Socket Thread]: D/DataChannel Sending 1 pending resets
11:50:21     INFO - GECKO(3940) | [Child 868: Socket Thread]: D/DataChannel Connection 0000023653FF0800: Sending outgoing stream reset for 1 streams
11:50:21     INFO - GECKO(3940) | [Child 868: Main Thread]: D/DataChannel 0000023646DB13A0(0000023646DF9A00): OnChannelClosed - Dispatching
11:50:21     INFO - DataChannelWrapper (pcLocal_channel_0): "onclose" event fired
11:50:21     INFO - TEST-PASS | dom/media/tests/mochitest/test_dataChannel_bug1013809.html | /tests/dom/media/tests/mochitest/test_dataChannel_bug1013809.html channel 0 closed

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-central: 1

Platform breakdown:
* windows10-64-nightly: 1

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

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 1
* mozilla-central: 1

Platform breakdown:
* windows10-64: 2

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

Comment 3

[Intermittent Failures Robot]

1 failures in 3007 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=1496103&startday=2018-11-05&endday=2018-11-11&tree=all

Comment 4

[Firefox Bug Husbandry Bot]

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