Intermittent dom/media/tests/mochitest/test_peerConnection_localRollback.html | Error in test execution: Error: Timeout checking for stats for track {095a4fb7-a122-4792-85ac-73990a4f7912}
Categories
(Core :: WebRTC, defect, P5)
Tracking
()
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•6 years ago
|
||
|
||
Comment 3•5 years ago
|
||
This is still happening.
Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=249114051&repo=mozilla-central&lineNumber=41451
05:43:41 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_localRollback.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
05:43:41 INFO - Checking for stats in [["outbound_rtp_audio_0",{"id":"outbound_rtp_audio_0","timestamp":1559195020679,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":2788905113,"bytesSent":0,"packetsSent":0,"nackCount":0,"remoteId":""}],["XBn6",{"id":"XBn6","timestamp":1559195020679,"type":"candidate-pair","bytesReceived":705,"bytesSent":215,"componentId":1,"lastPacketReceivedTimestamp":318766730162,"lastPacketSentTimestamp":318766727581,"localCandidateId":"iQP5","nominated":true,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"cOOj","selected":true,"state":"succeeded","transportId":"transport_0","writable":true}],["KSUj",{"id":"KSUj","timestamp":1559195020679,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"componentId":1,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"iQP5","nominated":true,"priority":7962083765675491000,"readable":true,"remoteCandidateId":"Pfng","selected":false,"state":"succeeded","transportId":"transport_0","writable":true}],["iQP5",{"id":"iQP5","timestamp":1559195020679,"type":"local-candidate","address":"10.144.63.123","candidateType":"host","port":49779,"priority":2122252543,"protocol":"udp"}],["TeYZ",{"id":"TeYZ","timestamp":1559195020679,"type":"local-candidate","address":"10.144.63.123","candidateType":"host","port":58588,"priority":2105524479,"protocol":"tcp"}],["Pfng",{"id":"Pfng","timestamp":1559195020679,"type":"remote-candidate","address":"10.144.63.123","candidateType":"prflx","port":49781,"priority":1853817087,"protocol":"udp"}],["cOOj",{"id":"cOOj","timestamp":1559195020679,"type":"remote-candidate","address":"10.144.63.123","candidateType":"host","port":49781,"priority":2122252543,"protocol":"udp"}],["E4oW",{"id":"E4oW","timestamp":1559195020679,"type":"remote-candidate","address":"10.144.63.123","candidateType":"host","port":9,"priority":2105524479,"protocol":"tcp"}]] for audio track {322819ab-2d1c-49f5-966d-1c5bb79a83c2}retry number 60
05:43:41 INFO - Should have RTP stats for track {322819ab-2d1c-49f5-966d-1c5bb79a83c2}
05:43:41 INFO - RTP stats: {"id":"outbound_rtp_audio_0","timestamp":1559195020679,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":2788905113,"bytesSent":0,"packetsSent":0,"nackCount":0,"remoteId":""}
05:43:41 INFO - Track {322819ab-2d1c-49f5-966d-1c5bb79a83c2} has 0 outbound-rtp RTP packets.
05:43:41 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_localRollback.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
05:43:41 INFO - Buffered messages finished
05:43:41 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_localRollback.html | Error in test execution: Error: Timeout checking for stats for track {bea3be5f-6068-4f1c-9b3c-63d876d91f72} after at least30000ms waitForRtpFlow@https://example.com/tests/dom/media/tests/mochitest/pc.js:1612:11 ... asyncwaitForMediaFlow/<@https://example.com/tests/dom/media/tests/mochitest/pc.js:1652:63 ... waitForMediaFlow@https://example.com/tests/dom/media/tests/mochitest/pc.js:1652:45 ... PC_LOCAL_WAIT_FOR_MEDIA_FLOW@https://example.com/tests/dom/media/tests/mochitest/templates.js:356:25 ... execute/</<@https://example.com/tests/dom/media/tests/mochitest/head.js:851:31 ...
05:43:41 INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:275:18
05:43:41 INFO - execute/<@https://example.com/tests/dom/media/tests/mochitest/head.js:855:14
05:43:41 INFO - promise callbackexecute@https://example.com/tests/dom/media/tests/mochitest/head.js:854:8
05:43:41 INFO - PeerConnectionTest.prototype.run@https://example.com/tests/dom/media/tests/mochitest/pc.js:498:21
05:43:41 INFO - @https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_localRollback.html:48:10
05:43:41 INFO - runNetworkTest/<@https://example.com/tests/dom/media/tests/mochitest/pc.js:2197:13
05:43:41 INFO - asyncrunTestWhenReady/<@https://example.com/tests/dom/media/tests/mochitest/head.js:472:41
05:43:41 INFO - promise callbackrunTestWhenReady@https://example.com/tests/dom/media/tests/mochitest/head.js:472:25
05:43:41 INFO - runNetworkTest@https://example.com/tests/dom/media/tests/mochitest/pc.js:2179:9
05:43:41 INFO - async*@https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_localRollback.html:15:3
05:43:41 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_localRollback.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
05:43:41 INFO - Closing peer connections
05:43:41 INFO - Waiting for track {bea3be5f-6068-4f1c-9b3c-63d876d91f72} (audio) to end.
05:43:41 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_localRollback.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
05:43:41 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_localRollback.html | iceConnectionState should not be undefined
05:43:41 INFO - PeerConnectionWrapper (pcLocal): oniceconnectionstatechange fired, new state is: closed
05:43:41 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_localRollback.html | PeerConnectionWrapper (pcLocal): legal ICE state transition from connected to closed
05:43:41 INFO - GECKO(1916) | [Child 8712: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2196: CloseInt: Closing PeerConnectionImpl 73d69ef6d26cf5ba; ending call
05:43:41 INFO - GECKO(1916) | [Child 8712: Main Thread]: I/jsep [1559194989736000 (id=2147484059 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_localRollback.html)]: stable -> closed
05:43:41 INFO - PeerConnectionWrapper (pcLocal): "onsignalingstatechange" event fired
05:43:41 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_localRollback.html | signalingState is closed
05:43:41 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_localRollback.html | PeerConnectionWrapper (pcLocal): legal signaling state transition from stable to closed
05:43:41 INFO - PeerConnectionWrapper (pcLocal): Closed connection.
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 6•5 years ago
|
||
|
||
Comment 7•5 years ago
|
||
Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=259821233&repo=autoland&lineNumber=34122
[task 2019-08-04T01:18:24.193Z] 01:18:24 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_localRollback.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-08-04T01:18:24.197Z] 01:18:24 INFO - Checking for stats in [["outbound_rtp_audio_0",{"id":"outbound_rtp_audio_0","timestamp":1564881503980,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":101141617,"bytesSent":0,"packetsSent":0,"nackCount":0,"remoteId":""}],["L6X5",{"id":"L6X5","timestamp":1564881503980,"type":"candidate-pair","bytesReceived":0,"bytesSent":0,"componentId":1,"lastPacketReceivedTimestamp":0,"lastPacketSentTimestamp":0,"localCandidateId":"yWMS","nominated":false,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"Fs3U","selected":false,"state":"cancelled","transportId":"transport_0","writable":true}],["T/eO",{"id":"T/eO","timestamp":1564881503980,"type":"candidate-pair","bytesReceived":946,"bytesSent":991,"componentId":1,"lastPacketReceivedTimestamp":324453212977,"lastPacketSentTimestamp":324453211974,"localCandidateId":"yWMS","nominated":true,"priority":7962083765675491000,"readable":true,"remoteCandidateId":"NT28","selected":true,"state":"succeeded","transportId":"transport_0","writable":true}],["yWMS",{"id":"yWMS","timestamp":1564881503980,"type":"local-candidate","address":"10.145.52.171","candidateType":"host","port":57719,"priority":2122252543,"protocol":"udp"}],["gJcr",{"id":"gJcr","timestamp":1564881503980,"type":"local-candidate","address":"10.145.52.171","candidateType":"host","port":49935,"priority":2105524479,"protocol":"tcp"}],["NT28",{"id":"NT28","timestamp":1564881503980,"type":"remote-candidate","address":"10.145.52.171","candidateType":"prflx","port":57721,"priority":1853817087,"protocol":"udp"}],["Fs3U",{"id":"Fs3U","timestamp":1564881503980,"type":"remote-candidate","address":"10.145.52.171","candidateType":"host","port":57721,"priority":2122252543,"protocol":"udp"}],["39Q0",{"id":"39Q0","timestamp":1564881503980,"type":"remote-candidate","address":"10.145.52.171","candidateType":"host","port":9,"priority":2105524479,"protocol":"tcp"}]] for audio track {6aad7e34-c15e-4382-a377-a26885e910db}retry number 60
[task 2019-08-04T01:18:24.198Z] 01:18:24 INFO - Should have RTP stats for track {6aad7e34-c15e-4382-a377-a26885e910db}
[task 2019-08-04T01:18:24.200Z] 01:18:24 INFO - RTP stats: {"id":"outbound_rtp_audio_0","timestamp":1564881503980,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":101141617,"bytesSent":0,"packetsSent":0,"nackCount":0,"remoteId":""}
[task 2019-08-04T01:18:24.200Z] 01:18:24 INFO - Track {6aad7e34-c15e-4382-a377-a26885e910db} has 0 outbound-rtp RTP packets.
[task 2019-08-04T01:18:24.201Z] 01:18:24 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_localRollback.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-08-04T01:18:24.203Z] 01:18:24 INFO - Buffered messages finished
[task 2019-08-04T01:18:24.204Z] 01:18:24 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_localRollback.html | Error in test execution: Error: Timeout checking for stats for track {ae4db35a-5623-42cc-8f3f-a26bdf69fb62} after at least30000ms waitForRtpFlow@https://example.com/tests/dom/media/tests/mochitest/pc.js:1851:11 ... asyncwaitForMediaFlow/<@https://example.com/tests/dom/media/tests/mochitest/pc.js:1915:16 ... waitForMediaFlow@https://example.com/tests/dom/media/tests/mochitest/pc.js:1914:47 ... 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-08-04T01:18:24.204Z] 01:18:24 INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:275:18
[task 2019-08-04T01:18:24.205Z] 01:18:24 INFO - execute/<@https://example.com/tests/dom/media/tests/mochitest/head.js:948:11
[task 2019-08-04T01:18:24.205Z] 01:18:24 INFO - promise callbackexecute@https://example.com/tests/dom/media/tests/mochitest/head.js:947:8
[task 2019-08-04T01:18:24.206Z] 01:18:24 INFO - PeerConnectionTest.prototype.run@https://example.com/tests/dom/media/tests/mochitest/pc.js:574:6
[task 2019-08-04T01:18:24.207Z] 01:18:24 INFO - @https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_localRollback.html:48:10
[task 2019-08-04T01:18:24.207Z] 01:18:24 INFO - runNetworkTest/<@https://example.com/tests/dom/media/tests/mochitest/pc.js:2564:11
[task 2019-08-04T01:18:24.208Z] 01:18:24 INFO - asyncrunTestWhenReady/<@https://example.com/tests/dom/media/tests/mochitest/head.js:492:30
[task 2019-08-04T01:18:24.208Z] 01:18:24 INFO - promise callbackrunTestWhenReady@https://example.com/tests/dom/media/tests/mochitest/head.js:492:6
[task 2019-08-04T01:18:24.208Z] 01:18:24 INFO - runNetworkTest@https://example.com/tests/dom/media/tests/mochitest/pc.js:2545:9
[task 2019-08-04T01:18:24.209Z] 01:18:24 INFO - async*@https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_localRollback.html:15:17
[task 2019-08-04T01:18:24.210Z] 01:18:24 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_localRollback.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-08-04T01:18:24.211Z] 01:18:24 INFO - Closing peer connections
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 11•5 years ago
|
||
Description
•