Closed Bug 1607594 Opened 6 years ago Closed 6 years ago

Intermittent dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | Error in test execution: Error: Timeout checking for stats for track {3c3aabff-da92-4f94-866d-a666e9946f45} after at least30000ms waitForRtpFlow@https

Categories

(Core :: WebRTC, defect, P5)

Product:
Core
Component:
WebRTC
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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

...
[task 2020-01-07T19:09:37.349Z] 19:09:37 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2020-01-07T19:09:37.349Z] 19:09:37 INFO - Buffered messages logged at 19:09:36
[task 2020-01-07T19:09:37.351Z] 19:09:37 INFO - Checking for stats in [["763a1044",{"id":"763a1044","timestamp":1578424176036,"type":"candidate-pair","bytesReceived":1389167,"bytesSent":1405758,"lastPacketReceivedTimestamp":337995888054,"lastPacketSentTimestamp":337995888044,"localCandidateId":"d0b2c4bb","nominated":true,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"2769c239","selected":true,"state":"succeeded","transportId":"transport_0","writable":true}],["d0b2c4bb",{"id":"d0b2c4bb","timestamp":1578424176036,"type":"local-candidate","address":"3ae6310b-b259-4c42-a2f7-9548db9c1c73.local","candidateType":"host","port":56340,"priority":2122252543,"protocol":"udp"}],["dfb32121",{"id":"dfb32121","timestamp":1578424176036,"type":"local-candidate","address":"3ae6310b-b259-4c42-a2f7-9548db9c1c73.local","candidateType":"host","port":52269,"priority":2105524479,"protocol":"tcp"}],["2769c239",{"id":"2769c239","timestamp":1578424176036,"type":"remote-candidate","address":"9e95580b-6cb4-4ce8-af3a-1da7756cb105.local","candidateType":"host","port":56345,"priority":2122252543,"protocol":"udp"}],["58b2a6ba",{"id":"58b2a6ba","timestamp":1578424176036,"type":"remote-candidate","address":"9e95580b-6cb4-4ce8-af3a-1da7756cb105.local","candidateType":"host","port":9,"priority":2105524479,"protocol":"tcp"}],["a2b73110",{"id":"a2b73110","timestamp":1578424176037,"type":"inbound-rtp","kind":"audio","mediaType":"audio","ssrc":3174585582,"jitter":0,"packetsLost":0,"packetsReceived":0,"bytesReceived":0,"nackCount":0}]] for audio track {3c3aabff-da92-4f94-866d-a666e9946f45}retry number 59
[task 2020-01-07T19:09:37.352Z] 19:09:37 INFO - Should have RTP stats for track {3c3aabff-da92-4f94-866d-a666e9946f45}
[task 2020-01-07T19:09:37.352Z] 19:09:37 INFO - RTP stats: {"id":"a2b73110","timestamp":1578424176037,"type":"inbound-rtp","kind":"audio","mediaType":"audio","ssrc":3174585582,"jitter":0,"packetsLost":0,"packetsReceived":0,"bytesReceived":0,"nackCount":0}
[task 2020-01-07T19:09:37.352Z] 19:09:37 INFO - Track {3c3aabff-da92-4f94-866d-a666e9946f45} has 0 inbound-rtp RTP packets.
[task 2020-01-07T19:09:37.353Z] 19:09:37 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2020-01-07T19:09:37.353Z] 19:09:37 INFO - Checking for stats in [["763a1044",{"id":"763a1044","timestamp":1578424176040,"type":"candidate-pair","bytesReceived":1389167,"bytesSent":1405758,"lastPacketReceivedTimestamp":337995888054,"lastPacketSentTimestamp":337995888044,"localCandidateId":"d0b2c4bb","nominated":true,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"2769c239","selected":true,"state":"succeeded","transportId":"transport_0","writable":true}],["d0b2c4bb",{"id":"d0b2c4bb","timestamp":1578424176040,"type":"local-candidate","address":"3ae6310b-b259-4c42-a2f7-9548db9c1c73.local","candidateType":"host","port":56340,"priority":2122252543,"protocol":"udp"}],["dfb32121",{"id":"dfb32121","timestamp":1578424176040,"type":"local-candidate","address":"3ae6310b-b259-4c42-a2f7-9548db9c1c73.local","candidateType":"host","port":52269,"priority":2105524479,"protocol":"tcp"}],["2769c239",{"id":"2769c239","timestamp":1578424176040,"type":"remote-candidate","address":"9e95580b-6cb4-4ce8-af3a-1da7756cb105.local","candidateType":"host","port":56345,"priority":2122252543,"protocol":"udp"}],["58b2a6ba",{"id":"58b2a6ba","timestamp":1578424176040,"type":"remote-candidate","address":"9e95580b-6cb4-4ce8-af3a-1da7756cb105.local","candidateType":"host","port":9,"priority":2105524479,"protocol":"tcp"}],["542bd200",{"id":"542bd200","timestamp":1578424176040,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":2295973264,"bytesSent":0,"packetsSent":0,"nackCount":0}]] for audio track {fbcd649c-1327-400e-b03d-05cba34035ea}retry number 59
[task 2020-01-07T19:09:37.354Z] 19:09:37 INFO - Should have RTP stats for track {fbcd649c-1327-400e-b03d-05cba34035ea}
[task 2020-01-07T19:09:37.354Z] 19:09:37 INFO - RTP stats: {"id":"542bd200","timestamp":1578424176040,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":2295973264,"bytesSent":0,"packetsSent":0,"nackCount":0}
[task 2020-01-07T19:09:37.355Z] 19:09:37 INFO - Track {fbcd649c-1327-400e-b03d-05cba34035ea} has 0 outbound-rtp RTP packets.
[task 2020-01-07T19:09:37.355Z] 19:09:37 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2020-01-07T19:09:37.356Z] 19:09:37 INFO - Checking for stats in [["763a1044",{"id":"763a1044","timestamp":1578424176540,"type":"candidate-pair","bytesReceived":1414988,"bytesSent":1433230,"lastPacketReceivedTimestamp":337995888546,"lastPacketSentTimestamp":337995888566,"localCandidateId":"d0b2c4bb","nominated":true,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"2769c239","selected":true,"state":"succeeded","transportId":"transport_0","writable":true}],["d0b2c4bb",{"id":"d0b2c4bb","timestamp":1578424176540,"type":"local-candidate","address":"3ae6310b-b259-4c42-a2f7-9548db9c1c73.local","candidateType":"host","port":56340,"priority":2122252543,"protocol":"udp"}],["dfb32121",{"id":"dfb32121","timestamp":1578424176540,"type":"local-candidate","address":"3ae6310b-b259-4c42-a2f7-9548db9c1c73.local","candidateType":"host","port":52269,"priority":2105524479,"protocol":"tcp"}],["2769c239",{"id":"2769c239","timestamp":1578424176540,"type":"remote-candidate","address":"9e95580b-6cb4-4ce8-af3a-1da7756cb105.local","candidateType":"host","port":56345,"priority":2122252543,"protocol":"udp"}],["58b2a6ba",{"id":"58b2a6ba","timestamp":1578424176540,"type":"remote-candidate","address":"9e95580b-6cb4-4ce8-af3a-1da7756cb105.local","candidateType":"host","port":9,"priority":2105524479,"protocol":"tcp"}],["a2b73110",{"id":"a2b73110","timestamp":1578424176541,"type":"inbound-rtp","kind":"audio","mediaType":"audio","ssrc":3174585582,"jitter":0,"packetsLost":0,"packetsReceived":0,"bytesReceived":0,"nackCount":0}]] for audio track {3c3aabff-da92-4f94-866d-a666e9946f45}retry number 60
[task 2020-01-07T19:09:37.356Z] 19:09:37 INFO - Should have RTP stats for track {3c3aabff-da92-4f94-866d-a666e9946f45}
[task 2020-01-07T19:09:37.356Z] 19:09:37 INFO - RTP stats: {"id":"a2b73110","timestamp":1578424176541,"type":"inbound-rtp","kind":"audio","mediaType":"audio","ssrc":3174585582,"jitter":0,"packetsLost":0,"packetsReceived":0,"bytesReceived":0,"nackCount":0}
[task 2020-01-07T19:09:37.357Z] 19:09:37 INFO - Track {3c3aabff-da92-4f94-866d-a666e9946f45} has 0 inbound-rtp RTP packets.
[task 2020-01-07T19:09:37.357Z] 19:09:37 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2020-01-07T19:09:37.357Z] 19:09:37 INFO - Checking for stats in [["763a1044",{"id":"763a1044","timestamp":1578424176545,"type":"candidate-pair","bytesReceived":1414988,"bytesSent":1433230,"lastPacketReceivedTimestamp":337995888546,"lastPacketSentTimestamp":337995888566,"localCandidateId":"d0b2c4bb","nominated":true,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"2769c239","selected":true,"state":"succeeded","transportId":"transport_0","writable":true}],["d0b2c4bb",{"id":"d0b2c4bb","timestamp":1578424176545,"type":"local-candidate","address":"3ae6310b-b259-4c42-a2f7-9548db9c1c73.local","candidateType":"host","port":56340,"priority":2122252543,"protocol":"udp"}],["dfb32121",{"id":"dfb32121","timestamp":1578424176545,"type":"local-candidate","address":"3ae6310b-b259-4c42-a2f7-9548db9c1c73.local","candidateType":"host","port":52269,"priority":2105524479,"protocol":"tcp"}],["2769c239",{"id":"2769c239","timestamp":1578424176545,"type":"remote-candidate","address":"9e95580b-6cb4-4ce8-af3a-1da7756cb105.local","candidateType":"host","port":56345,"priority":2122252543,"protocol":"udp"}],["58b2a6ba",{"id":"58b2a6ba","timestamp":1578424176545,"type":"remote-candidate","address":"9e95580b-6cb4-4ce8-af3a-1da7756cb105.local","candidateType":"host","port":9,"priority":2105524479,"protocol":"tcp"}],["542bd200",{"id":"542bd200","timestamp":1578424176545,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":2295973264,"bytesSent":0,"packetsSent":0,"nackCount":0}]] for audio track {fbcd649c-1327-400e-b03d-05cba34035ea}retry number 60
[task 2020-01-07T19:09:37.358Z] 19:09:37 INFO - Should have RTP stats for track {fbcd649c-1327-400e-b03d-05cba34035ea}
[task 2020-01-07T19:09:37.358Z] 19:09:37 INFO - RTP stats: {"id":"542bd200","timestamp":1578424176545,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":2295973264,"bytesSent":0,"packetsSent":0,"nackCount":0}
[task 2020-01-07T19:09:37.359Z] 19:09:37 INFO - Track {fbcd649c-1327-400e-b03d-05cba34035ea} has 0 outbound-rtp RTP packets.
[task 2020-01-07T19:09:37.359Z] 19:09:37 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2020-01-07T19:09:37.359Z] 19:09:37 INFO - Buffered messages finished
[task 2020-01-07T19:09:37.360Z] 19:09:37 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | Error in test execution: Error: Timeout checking for stats for track {3c3aabff-da92-4f94-866d-a666e9946f45} 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:1907:16 ... waitForMediaFlow@https://example.com/tests/dom/media/tests/mochitest/pc.js:1906: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:949:32 ...
[task 2020-01-07T19:09:37.360Z] 19:09:37 INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:277:18
[task 2020-01-07T19:09:37.360Z] 19:09:37 INFO - execute/<@https://example.com/tests/dom/media/tests/mochitest/head.js:953:11
[task 2020-01-07T19:09:37.361Z] 19:09:37 INFO - promise callbackexecute@https://example.com/tests/dom/media/tests/mochitest/head.js:952:8
[task 2020-01-07T19:09:37.361Z] 19:09:37 INFO - PeerConnectionTest.prototype.run@https://example.com/tests/dom/media/tests/mochitest/pc.js:574:6
[task 2020-01-07T19:09:37.361Z] 19:09:37 INFO - @https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html:26:10
[task 2020-01-07T19:09:37.361Z] 19:09:37 INFO - runNetworkTest/<@https://example.com/tests/dom/media/tests/mochitest/pc.js:2556:11
[task 2020-01-07T19:09:37.362Z] 19:09:37 INFO - asyncrunTestWhenReady/<@https://example.com/tests/dom/media/tests/mochitest/head.js:497:30
[task 2020-01-07T19:09:37.362Z] 19:09:37 INFO - promise callbackrunTestWhenReady@https://example.com/tests/dom/media/tests/mochitest/head.js:497:6
[task 2020-01-07T19:09:37.362Z] 19:09:37 INFO - runNetworkTest@https://example.com/tests/dom/media/tests/mochitest/pc.js:2537:9
[task 2020-01-07T19:09:37.362Z] 19:09:37 INFO - async*@https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html:15:17
[task 2020-01-07T19:09:37.363Z] 19:09:37 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2020-01-07T19:09:37.363Z] 19:09:37 INFO - Closing peer connections
[task 2020-01-07T19:09:37.364Z] 19:09:37 INFO - Waiting for track {3c3aabff-da92-4f94-866d-a666e9946f45} (audio) to end.
[task 2020-01-07T19:09:37.364Z] 19:09:37 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2020-01-07T19:09:37.364Z] 19:09:37 INFO - Waiting for track {f9d0c3cf-1244-4e1f-9512-eefabfff5053} (video) to end.
[task 2020-01-07T19:09:37.364Z] 19:09:37 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2020-01-07T19:09:37.365Z] 19:09:37 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | iceConnectionState should not be undefined
[task 2020-01-07T19:09:37.365Z] 19:09:37 INFO - PeerConnectionWrapper (pcLocal): oniceconnectionstatechange fired, new state is: closed
[task 2020-01-07T19:09:37.365Z] 19:09:37 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | PeerConnectionWrapper (pcLocal): legal ICE state transition from connected to closed
[task 2020-01-07T19:09:37.366Z] 19:09:37 INFO - GECKO(480) | [Child 6080: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2192: CloseInt: Closing PeerConnectionImpl a25bdc968bb42063; ending call
[task 2020-01-07T19:09:37.366Z] 19:09:37 INFO - GECKO(480) | [Child 6080: Main Thread]: I/jsep [1578424145565000 (id=2147483969 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTran]: stable -> closed
[task 2020-01-07T19:09:37.367Z] 19:09:37 INFO - PeerConnectionWrapper (pcLocal): "onsignalingstatechange" event fired
[task 2020-01-07T19:09:37.367Z] 19:09:37 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | signalingState is closed
[task 2020-01-07T19:09:37.368Z] 19:09:37 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | PeerConnectionWrapper (pcLocal): legal signaling state transition from stable to closed
[task 2020-01-07T19:09:37.368Z] 19:09:37 INFO - PeerConnectionWrapper (pcLocal): Closed connection.
[task 2020-01-07T19:09:37.369Z] 19:09:37 INFO - Waiting for track {97b3fb25-c883-4e7e-b7f6-5a198aaafa55} (audio) to end.
[task 2020-01-07T19:09:37.369Z] 19:09:37 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2020-01-07T19:09:37.369Z] 19:09:37 INFO - Waiting for track {513c6c35-3e2d-42e1-bffe-151f57cdfc43} (video) to end.
[task 2020-01-07T19:09:37.369Z] 19:09:37 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2020-01-07T19:09:37.370Z] 19:09:37 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_basicAudioVideoTransceivers.html | iceConnectionState should not be undefined
[task 2020-01-07T19:09:37.370Z] 19:09:37 INFO - PeerConnectionWrapper (pcRemote): oniceconnectionstatechange fired, new state is: closed
...

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.