Closed Bug 1593849 Opened 6 years ago Closed 6 years ago

Intermittent dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | Error in test execution: Error: Timeout checking for stats for track {93f3fa52-7a78-f64d-b4f1-5e2adebbe8f6} after at least30000ms waitForRtpFlow@https://example.com/

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: rmaries [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=274487278&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/HAG8UAb3TjS8KuG9R7yHMw/runs/0/artifacts/public/logs/live_backing.log

[task 2019-11-04T22:31:58.662Z] 22:31:58 INFO - TEST-START | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html
[task 2019-11-04T22:32:34.070Z] 22:32:34 INFO - RTP stats: {"id":"outbound_rtp_video_0","timestamp":1572906753188,"type":"outbound-rtp","kind":"video","mediaType":"video","ssrc":1595940398,"bytesSent":0,"packetsSent":0,"bitrateMean":0,"bitrateStdDev":0,"droppedFrames":282,"firCount":0,"framerateMean":0,"framerateStdDev":0,"framesEncoded":0,"nackCount":0,"pliCount":0,"remoteId":"outbound_rtcp_video_0"}
[task 2019-11-04T22:32:34.071Z] 22:32:34 INFO - Track {93f3fa52-7a78-f64d-b4f1-5e2adebbe8f6} has 0 outbound-rtp RTP packets.
[task 2019-11-04T22:32:34.071Z] 22:32:34 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-11-04T22:32:34.071Z] 22:32:34 INFO - Drawing color 0,255,0,1
[task 2019-11-04T22:32:34.071Z] 22:32:34 INFO - Buffered messages finished
[task 2019-11-04T22:32:34.071Z] 22:32:34 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | Error in test execution: Error: Timeout checking for stats for track {93f3fa52-7a78-f64d-b4f1-5e2adebbe8f6} after at least30000ms waitForRtpFlow@https://example.com/tests/dom/media/tests/mochitest/pc.js:1843:11 ... asyncwaitForMediaFlow/<@https://example.com/tests/dom/media/tests/mochitest/pc.js:1909:56 ... waitForMediaFlow@https://example.com/tests/dom/media/tests/mochitest/pc.js:1909:38 ... 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-11-04T22:32:34.071Z] 22:32:34 INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:277:18
[task 2019-11-04T22:32:34.071Z] 22:32:34 INFO - execute/<@https://example.com/tests/dom/media/tests/mochitest/head.js:948:11
[task 2019-11-04T22:32:34.071Z] 22:32:34 INFO - promise callbackexecute@https://example.com/tests/dom/media/tests/mochitest/head.js:947:8
[task 2019-11-04T22:32:34.072Z] 22:32:34 INFO - PeerConnectionTest.prototype.run@https://example.com/tests/dom/media/tests/mochitest/pc.js:574:6
[task 2019-11-04T22:32:34.072Z] 22:32:34 INFO - @https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html:189:16
[task 2019-11-04T22:32:34.072Z] 22:32:34 INFO - asyncrunNetworkTest/<@https://example.com/tests/dom/media/tests/mochitest/pc.js:2556:11
[task 2019-11-04T22:32:34.072Z] 22:32:34 INFO - asyncrunTestWhenReady/<@https://example.com/tests/dom/media/tests/mochitest/head.js:492:30
[task 2019-11-04T22:32:34.072Z] 22:32:34 INFO - promise callbackrunTestWhenReady@https://example.com/tests/dom/media/tests/mochitest/head.js:492:6
[task 2019-11-04T22:32:34.072Z] 22:32:34 INFO - runNetworkTest@https://example.com/tests/dom/media/tests/mochitest/pc.js:2537:9
[task 2019-11-04T22:32:34.072Z] 22:32:34 INFO - async@https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html:98:17
[task 2019-11-04T22:32:34.073Z] 22:32:34 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-11-04T22:32:34.074Z] 22:32:34 INFO - Closing peer connections
[task 2019-11-04T22:32:34.074Z] 22:32:34 INFO - Waiting for track {55833b90-b24e-0e48-91b7-dd579e1f24be} (video) to end.
[task 2019-11-04T22:32:34.074Z] 22:32:34 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-11-04T22:32:34.074Z] 22:32:34 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | iceConnectionState should not be undefined
[task 2019-11-04T22:32:34.076Z] 22:32:34 INFO - PeerConnectionWrapper (pcLocal): oniceconnectionstatechange fired, new state is: closed
[task 2019-11-04T22:32:34.076Z] 22:32:34 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | PeerConnectionWrapper (pcLocal): legal ICE state transition from connected to closed
[task 2019-11-04T22:32:34.076Z] 22:32:34 INFO - GECKO(1672) | [Child 1673: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2229: CloseInt: Closing PeerConnectionImpl 5bd653f73a54207e; ending call
[task 2019-11-04T22:32:34.076Z] 22:32:34 INFO - GECKO(1672) | [Child 1673: Main Thread]: I/jsep [1572906719337876 (id=2147484102 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_simulcastOddResolut]: stable -> closed
[task 2019-11-04T22:32:34.076Z] 22:32:34 INFO - PeerConnectionWrapper (pcLocal): "onsignalingstatechange" event fired
[task 2019-11-04T22:32:34.076Z] 22:32:34 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | signalingState is closed
[task 2019-11-04T22:32:34.077Z] 22:32:34 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | PeerConnectionWrapper (pcLocal): legal signaling state transition from stable to closed
[task 2019-11-04T22:32:34.079Z] 22:32:34 INFO - PeerConnectionWrapper (pcLocal): Closed connection.
[task 2019-11-04T22:32:34.079Z] 22:32:34 INFO - Waiting for track {b89a2eea-35ba-c24a-baea-16bbc97d9d34} (video) to end.
[task 2019-11-04T22:32:34.079Z] 22:32:34 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-11-04T22:32:34.079Z] 22:32:34 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | iceConnectionState should not be undefined
[task 2019-11-04T22:32:34.079Z] 22:32:34 INFO - PeerConnectionWrapper (pcRemote): oniceconnectionstatechange fired, new state is: closed
[task 2019-11-04T22:32:34.080Z] 22:32:34 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | PeerConnectionWrapper (pcRemote): legal ICE state transition from connected to closed
[task 2019-11-04T22:32:34.080Z] 22:32:34 INFO - GECKO(1672) | [Child 1673: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2229: CloseInt: Closing PeerConnectionImpl 6cf60fde0c8a8d18; ending call
[task 2019-11-04T22:32:34.080Z] 22:32:34 INFO - GECKO(1672) | [Child 1673: Main Thread]: I/jsep [1572906719341724 (id=2147484102 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_simulcastOddResolut]: stable -> closed
[task 2019-11-04T22:32:34.080Z] 22:32:34 INFO - PeerConnectionWrapper (pcRemote): "onsignalingstatechange" event fired
[task 2019-11-04T22:32:34.080Z] 22:32:34 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | signalingState is closed
[task 2019-11-04T22:32:34.085Z] 22:32:34 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | PeerConnectionWrapper (pcRemote): legal signaling state transition from stable to closed
[task 2019-11-04T22:32:34.085Z] 22:32:34 INFO - PeerConnectionWrapper (pcRemote): Closed connection.
[task 2019-11-04T22:32:34.085Z] 22:32:34 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-11-04T22:32:34.085Z] 22:32:34 INFO - GECKO(1672) | [Parent 1672, Compositor] WARNING: Must have an opaque ImageHost if we reported CONTENT_OPAQUE: file /builds/worker/workspace/build/src/gfx/layers/composite/ImageLayerComposite.cpp, line 145
[task 2019-11-04T22:32:34.085Z] 22:32:34 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | Event target should be the correct track
[task 2019-11-04T22:32:34.085Z] 22:32:34 INFO - PeerConnectionWrapper (pcLocal) ended fired for track {55833b90-b24e-0e48-91b7-dd579e1f24be}
[task 2019-11-04T22:32:34.086Z] 22:32:34 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | Event target should be the correct track
[task 2019-11-04T22:32:34.086Z] 22:32:34 INFO - PeerConnectionWrapper (pcRemote) ended fired for track {b89a2eea-35ba-c24a-baea-16bbc97d9d34}
[task 2019-11-04T22:32:34.086Z] 22:32:34 INFO - GECKO(1672) | MEMORY STAT | vsize 7515MB | residentFast 218MB | heapAllocated 48MB
[task 2019-11-04T22:32:34.086Z] 22:32:34 INFO - GECKO(1672) | [Parent 1672, Compositor] WARNING: Must have an opaque ImageHost if we reported CONTENT_OPAQUE: file /builds/worker/workspace/build/src/gfx/layers/composite/ImageLayerComposite.cpp, line 145
[task 2019-11-04T22:32:34.086Z] 22:32:34 INFO - GECKO(1672) | JavaScript error: , line 0: AbortError: The fetching process for the media resource was aborted by the user agent at the user's request.
[task 2019-11-04T22:32:34.086Z] 22:32:34 INFO - TEST-OK | dom/media/tests/mochitest/test_peerConnection_simulcastOddResolution.html | took 35218ms
[task 2019-11-04T22:32:34.087Z] 22:32:34 INFO - GECKO(1672) | [Parent 1672, Compositor] WARNING: Must have an opaque ImageHost if we reported CONTENT_OPAQUE: file /builds/worker/workspace/build/src/gfx/layers/composite/ImageLayerComposite.cpp, line 145
[task 2019-11-04T22:32:34.087Z] 22:32:34 INFO - GECKO(1672) | [Parent 1672, Compositor] WARNING: Must have an opaque ImageHost if we reported CONTENT_OPAQUE: file /builds/worker/workspace/build/src/gfx/layers/composite/ImageLayerComposite.cpp, line 145
[task 2019-11-04T22:32:34.087Z] 22:32:34 INFO - GECKO(1672) | ++DOMWINDOW == 10 (0x145311c00) [pid = 1673] [serial = 461] [outer = 0x11a0425c0]
[task 2019-11-04T22:32:34.087Z] 22:32:34 INFO - GECKO(1672) | [Parent 1672, Compositor] WARNING: Must have an opaque ImageHost if we reported CONTENT_OPAQUE: file /builds/worker/workspace/build/src/gfx/layers/composite/ImageLayerComposite.cpp, line 145
[task 2019-11-04T22:32:34.087Z] 22:32:34 INFO - GECKO(1672) | [Parent 1672, Compositor] WARNING: Must have an opaque ImageHost if we reported CONTENT_OPAQUE: file /builds/worker/workspace/build/src/gfx/layers/composite/ImageLayerComposite.cpp, line 145

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.