Closed Bug 1606052 Opened 6 years ago Closed 6 years ago

Intermittent dom/media/tests/mochitest/test_dataChannel_basicAudioVideoCombined.html | Error in test execution: Error: Element _local_{99b98d6f-38dd-4bd4-9ede-843ca0310631} 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)

Filed by: dvarga [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=282707283&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/GKFjIq-RRAOezXrO6tgu7g/runs/0/artifacts/public/logs/live_backing.log

[task 2019-12-27T02:16:20.418Z] 02:16:20     INFO - TEST-PASS | dom/media/tests/mochitest/test_dataChannel_basicAudioVideoCombined.html | RTP flowing for video track {5f759e3a-8f14-49de-b2a8-8c9eb1b36543} 
[task 2019-12-27T02:16:20.419Z] 02:16:20     INFO - Buffered messages finished
[task 2019-12-27T02:16:20.419Z] 02:16:20     INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_basicAudioVideoCombined.html | Error in test execution: Error: Element _local_{99b98d6f-38dd-4bd4-9ede-843ca0310631} should progress currentTime timeout/<@https://example.com/tests/dom/media/tests/mochitest/head.js:652:42 ... promise callback*timeout@https://example.com/tests/dom/media/tests/mochitest/head.js:652:16 ... waitForMediaElementFlow@https://example.com/tests/dom/media/tests/mochitest/pc.js:1782:35 ... waitForMediaFlow/<@https://example.com/tests/dom/media/tests/mochitest/pc.js:1897:16 ... waitForMediaFlow@https://example.com/tests/dom/media/tests/mochitest/pc.js:1896:33 ... 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 ... promise callback*execute/<@https://example.com/tests/dom/media/tests/mochitest/head.js:942:21 ... execute@https://example.com/tests/dom/media/tests/mochitest/head.js:937:8 ... PeerConnectionTest.prototype.run@https://example.com/tests/dom/media/tests/mochitest/pc.js:574:6 ... @https://example.com/tests/dom/media/tests/mochitest/test_dataChannel_basicAudioVideoCombined.html:20:10 ... runNetworkTest/<@https://example.com/tests/dom/media/tests/mochitest/pc.js:2556:11 ... async*runTestWhenReady/<@https://example.com/tests/dom/media/tests/mochitest/head.js:492:30 ... promise callback*runTestWhenReady@https://example.com/tests/dom/media/tests/mochitest/head.js:492:6 ... runNetworkTest@https://example.com/tests/dom/media/tests/mochitest/pc.js:2537:9 ... async*@https://example.com/tests/dom/media/tests/mochitest/test_dataChannel_basicAudioVideoCombined.html:15:17 ...  
[task 2019-12-27T02:16:20.419Z] 02:16:20     INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:277:18
[task 2019-12-27T02:16:20.419Z] 02:16:20     INFO - execute/<@https://example.com/tests/dom/media/tests/mochitest/head.js:948:11
[task 2019-12-27T02:16:20.419Z] 02:16:20     INFO - promise callback*execute@https://example.com/tests/dom/media/tests/mochitest/head.js:947:8
[task 2019-12-27T02:16:20.419Z] 02:16:20     INFO - PeerConnectionTest.prototype.run@https://example.com/tests/dom/media/tests/mochitest/pc.js:574:6
[task 2019-12-27T02:16:20.419Z] 02:16:20     INFO - @https://example.com/tests/dom/media/tests/mochitest/test_dataChannel_basicAudioVideoCombined.html:20:10
[task 2019-12-27T02:16:20.419Z] 02:16:20     INFO - runNetworkTest/<@https://example.com/tests/dom/media/tests/mochitest/pc.js:2556:11
[task 2019-12-27T02:16:20.419Z] 02:16:20     INFO - async*runTestWhenReady/<@https://example.com/tests/dom/media/tests/mochitest/head.js:492:30
[task 2019-12-27T02:16:20.419Z] 02:16:20     INFO - promise callback*runTestWhenReady@https://example.com/tests/dom/media/tests/mochitest/head.js:492:6
[task 2019-12-27T02:16:20.419Z] 02:16:20     INFO - runNetworkTest@https://example.com/tests/dom/media/tests/mochitest/pc.js:2537:9
[task 2019-12-27T02:16:20.419Z] 02:16:20     INFO - async*@https://example.com/tests/dom/media/tests/mochitest/test_dataChannel_basicAudioVideoCombined.html:15:17
[task 2019-12-27T02:16:20.419Z] 02:16:20     INFO - closeDataChannels called with index: 0
[task 2019-12-27T02:16:20.419Z] 02:16:20     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 
[task 2019-12-27T02:16:20.419Z] 02:16:20     INFO - DataChannelWrapper (pcRemote_channel_0): Closing channel
[task 2019-12-27T02:16:20.420Z] 02:16:20     INFO - GECKO(6520) | [Child 10332: Main Thread]: D/DataChannel Connection 0000027D15E5FC00/Channel 0000027D15C1B3A0: Closing stream 1
[task 2019-12-27T02:16:20.420Z] 02:16:20     INFO - GECKO(6520) | [Child 10332: Main Thread]: D/DataChannel Connection 0000027D15E5FC00: Resetting outgoing stream 1
[task 2019-12-27T02:16:20.420Z] 02:16:20     INFO - GECKO(6520) | [Child 10332: Main Thread]: D/DataChannel Connection 0000027D15E5FC00: Sending outgoing stream reset for 1 streams
[task 2019-12-27T02:16:20.420Z] 02:16:20     INFO - GECKO(6520) | [Child 10332: Main Thread]: D/DataChannel DataChannelConnection labeled channel_0(0000027D15C1B3A0) (stream 1) changing ready state OPEN -> CLOSING
[task 2019-12-27T02:16:20.420Z] 02:16:20     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 
[task 2019-12-27T02:16:20.420Z] 02:16:20     INFO - GECKO(6520) | [Child 10332: Socket Thread]: D/DataChannel Incoming: Channel 1  closed
[task 2019-12-27T02:16:20.420Z] 02:16:20     INFO - GECKO(6520) | [Child 10332: Socket Thread]: D/DataChannel Removing channel 1 : 0000027D15C75790
[task 2019-12-27T02:16:20.420Z] 02:16:20     INFO - GECKO(6520) | [Child 10332: Socket Thread]: D/DataChannel Connection 0000027D15E57C00: Resetting outgoing stream 1
[task 2019-12-27T02:16:20.420Z] 02:16:20     INFO - GECKO(6520) | [Child 10332: Socket Thread]: D/DataChannel Disconnected DataChannel 0000027D15C75790 from connection 0000027D15E57C00
[task 2019-12-27T02:16:20.420Z] 02:16:20     INFO - GECKO(6520) | [Child 10332: Socket Thread]: D/DataChannel Destroying Data channel 1
[task 2019-12-27T02:16:20.421Z] 02:16:20     INFO - GECKO(6520) | [Child 10332: Socket Thread]: D/DataChannel Sending 1 pending resets
[task 2019-12-27T02:16:20.421Z] 02:16:20     INFO - GECKO(6520) | [Child 10332: Socket Thread]: D/DataChannel Connection 0000027D15E57C00: Sending outgoing stream reset for 1 streams
[task 2019-12-27T02:16:20.421Z] 02:16:20     INFO - GECKO(6520) | [Child 10332: Socket Thread]: D/DataChannel Incoming: Channel 1  closed
[task 2019-12-27T02:16:20.421Z] 02:16:20     INFO - GECKO(6520) | [Child 10332: Socket Thread]: D/DataChannel Removing channel 1 : 0000027D15C1B3A0

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.