Intermittent TV dom/media/tests/mochitest/test_peerConnection_bug1512281.html | called finish() multiple times
Categories
(Core :: WebRTC, defect, P5)
Tracking
()
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure, regression)
#[markdown(off)]
Filed by: ccoroiu [at] mozilla.com
https://treeherder.mozilla.org/logviewer.html#?job_id=236266666&repo=autoland
[task 2019-03-27T00:43:43.278Z] 00:43:43 INFO - 1 INFO TEST-START | dom/media/tests/mochitest/test_peerConnection_bug1512281.html
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - <snipped 272 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - Buffered messages logged at 00:47:28
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - 2 INFO PeerConnectionWrapper (pcRemote): adding ICE candidate {"candidate":"candidate:1 2 TCP 2105524478 10.0.2.15 9 typ host tcptype active","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":null}
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - Buffered messages logged at 00:47:29
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - 3 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | PeerConnectionWrapper (pcRemote) successfully added an ICE candidate
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - Buffered messages logged at 00:47:30
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - 4 INFO pcLocal: received end of trickle ICE event
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - Buffered messages logged at 00:47:32
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - 5 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | ICE gathering state has reached complete
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - Buffered messages logged at 00:47:39
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - 6 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | iceGetheringState should not be undefined
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - 7 INFO PeerConnectionWrapper (pcRemote): onicegatheringstatechange fired, new state is: complete
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - Buffered messages logged at 00:47:41
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - 8 INFO pcRemote: iceCandidate = {"candidate":"candidate:0 1 UDP 2122252543 10.0.2.15 38640 typ host","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":null}
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - 9 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | ICE candidate contains candidate
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - 10 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | SDP mid not empty
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - Buffered messages logged at 00:47:42
[task 2019-03-27T00:55:26.535Z] 00:55:26 INFO - 11 INFO CLINE-NO-DEFAULT-ADDR-SDP: "audio 38640 UDP/TLS/RTP/SAVPF 109 101\r\nc=IN IP4 10.0.2.15\r\na=candidate:0 1 UDP 2122252543 10.0.2.15 38640 typ host\r\na=candidate:1 1 TCP 2105524479 10.0.2.15 9 typ host tcptype active\r\na=sendrecv\r\na=end-of-candidates\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=fmtp:109 maxplaybackrate=48000;stereo=1;useinbandfec=1\r\na=fmtp:101 0-15\r\na=ice-pwd:48e33e7923255e882ac3c24b5afb4b8a\r\na=ice-ufrag:2ad028de\r\na=mid:0\r\na=msid:{88616bd8-90d5-4538-8c17-960c408af8b0} {6427083c-577e-47c7-89c3-a2d9ca6fb413}\r\na=rtcp-mux\r\na=rtpmap:109 opus/48000/2\r\na=rtpmap:101 telephone-event/8000\r\na=setup:active\r\na=ssrc:3922317456 cname:{e63722db-9cef-490e-9265-47d4de31d28d}"
[task 2019-03-27T00:55:26.536Z] 00:55:26 INFO - Buffered messages logged at 00:47:43
[task 2019-03-27T00:55:26.536Z] 00:55:26 INFO - 12 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | pcRemote: SDP contains non-zero IP c line
[task 2019-03-27T00:55:26.536Z] 00:55:26 INFO - 13 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | SDP MLine Index needs to exist
[task 2019-03-27T00:55:26.536Z] 00:55:26 INFO - 14 INFO Received: {"candidate":"candidate:0 1 UDP 2122252543 10.0.2.15 38640 typ host","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":null} from pcRemote
[task 2019-03-27T00:55:26.536Z] 00:55:26 INFO - Buffered messages logged at 00:47:44
[task 2019-03-27T00:55:26.536Z] 00:55:26 INFO - 15 INFO PeerConnectionWrapper (pcLocal): adding ICE candidate {"candidate":"candidate:0 1 UDP 2122252543 10.0.2.15 38640 typ host","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":null}
[task 2019-03-27T00:55:26.536Z] 00:55:26 INFO - Buffered messages logged at 00:47:46
[task 2019-03-27T00:55:26.536Z] 00:55:26 INFO - 16 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | PeerConnectionWrapper (pcLocal) successfully added an ICE candidate
[task 2019-03-27T00:55:26.536Z] 00:55:26 INFO - 17 INFO pcRemote: iceCandidate = {"candidate":"candidate:1 1 TCP 2105524479 10.0.2.15 9 typ host tcptype active","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":null}
[task 2019-03-27T00:55:26.536Z] 00:55:26 INFO - Buffered messages logged at 00:47:47
[task 2019-03-27T00:55:26.536Z] 00:55:26 INFO - 18 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | ICE candidate contains candidate
[task 2019-03-27T00:55:26.537Z] 00:55:26 INFO - 19 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | SDP mid not empty
[task 2019-03-27T00:55:26.537Z] 00:55:26 INFO - Buffered messages logged at 00:47:48
[task 2019-03-27T00:55:26.539Z] 00:55:26 INFO - 20 INFO CLINE-NO-DEFAULT-ADDR-SDP: "audio 38640 UDP/TLS/RTP/SAVPF 109 101\r\nc=IN IP4 10.0.2.15\r\na=candidate:0 1 UDP 2122252543 10.0.2.15 38640 typ host\r\na=candidate:1 1 TCP 2105524479 10.0.2.15 9 typ host tcptype active\r\na=sendrecv\r\na=end-of-candidates\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=fmtp:109 maxplaybackrate=48000;stereo=1;useinbandfec=1\r\na=fmtp:101 0-15\r\na=ice-pwd:48e33e7923255e882ac3c24b5afb4b8a\r\na=ice-ufrag:2ad028de\r\na=mid:0\r\na=msid:{88616bd8-90d5-4538-8c17-960c408af8b0} {6427083c-577e-47c7-89c3-a2d9ca6fb413}\r\na=rtcp-mux\r\na=rtpmap:109 opus/48000/2\r\na=rtpmap:101 telephone-event/8000\r\na=setup:active\r\na=ssrc:3922317456 cname:{e63722db-9cef-490e-9265-47d4de31d28d}"
[task 2019-03-27T00:55:26.540Z] 00:55:26 INFO - 21 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | pcRemote: SDP contains non-zero IP c line
[task 2019-03-27T00:55:26.541Z] 00:55:26 INFO - 22 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | SDP MLine Index needs to exist
[task 2019-03-27T00:55:26.541Z] 00:55:26 INFO - Buffered messages logged at 00:47:49
[task 2019-03-27T00:55:26.542Z] 00:55:26 INFO - 23 INFO Received: {"candidate":"candidate:1 1 TCP 2105524479 10.0.2.15 9 typ host tcptype active","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":null} from pcRemote
[task 2019-03-27T00:55:26.543Z] 00:55:26 INFO - 24 INFO PeerConnectionWrapper (pcLocal): adding ICE candidate {"candidate":"candidate:1 1 TCP 2105524479 10.0.2.15 9 typ host tcptype active","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":null}
[task 2019-03-27T00:55:26.543Z] 00:55:26 INFO - Buffered messages logged at 00:47:51
[task 2019-03-27T00:55:26.544Z] 00:55:26 INFO - 25 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | PeerConnectionWrapper (pcLocal) successfully added an ICE candidate
[task 2019-03-27T00:55:26.544Z] 00:55:26 INFO - 26 INFO pcRemote: received end of trickle ICE event
[task 2019-03-27T00:55:26.545Z] 00:55:26 INFO - Buffered messages logged at 00:47:52
[task 2019-03-27T00:55:26.545Z] 00:55:26 INFO - 27 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | ICE gathering state has reached complete
[task 2019-03-27T00:55:26.546Z] 00:55:26 INFO - Buffered messages logged at 00:49:10
[task 2019-03-27T00:55:26.546Z] 00:55:26 INFO - 28 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | Test timed out.
[task 2019-03-27T00:55:26.547Z] 00:55:26 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
[task 2019-03-27T00:55:26.547Z] 00:55:26 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2019-03-27T00:55:26.548Z] 00:55:26 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
[task 2019-03-27T00:55:26.548Z] 00:55:26 INFO - Buffered messages logged at 00:49:12
[task 2019-03-27T00:55:26.549Z] 00:55:26 INFO - 29 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | iceConnectionState should not be undefined
[task 2019-03-27T00:55:26.549Z] 00:55:26 INFO - Buffered messages logged at 00:49:13
[task 2019-03-27T00:55:26.550Z] 00:55:26 INFO - 30 INFO PeerConnectionWrapper (pcRemote): oniceconnectionstatechange fired, new state is: checking
[task 2019-03-27T00:55:26.550Z] 00:55:26 INFO - Buffered messages logged at 00:49:14
[task 2019-03-27T00:55:26.551Z] 00:55:26 INFO - 31 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | PeerConnectionWrapper (pcRemote): legal ICE state transition from new to checking
[task 2019-03-27T00:55:26.551Z] 00:55:26 INFO - Buffered messages logged at 00:49:16
[task 2019-03-27T00:55:26.552Z] 00:55:26 INFO - 32 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | iceConnectionState should not be undefined
[task 2019-03-27T00:55:26.552Z] 00:55:26 INFO - Buffered messages logged at 00:49:17
[task 2019-03-27T00:55:26.553Z] 00:55:26 INFO - 33 INFO PeerConnectionWrapper (pcLocal): oniceconnectionstatechange fired, new state is: checking
[task 2019-03-27T00:55:26.554Z] 00:55:26 INFO - Buffered messages logged at 00:49:18
[task 2019-03-27T00:55:26.555Z] 00:55:26 INFO - 34 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | PeerConnectionWrapper (pcLocal): legal ICE state transition from new to checking
[task 2019-03-27T00:55:26.556Z] 00:55:26 INFO - Buffered messages logged at 00:49:19
[task 2019-03-27T00:55:26.556Z] 00:55:26 INFO - 35 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | iceConnectionState should not be undefined
[task 2019-03-27T00:55:26.558Z] 00:55:26 INFO - Buffered messages logged at 00:49:20
[task 2019-03-27T00:55:26.559Z] 00:55:26 INFO - 36 INFO PeerConnectionWrapper (pcLocal): oniceconnectionstatechange fired, new state is: connected
[task 2019-03-27T00:55:26.562Z] 00:55:26 INFO - Buffered messages logged at 00:49:21
[task 2019-03-27T00:55:26.563Z] 00:55:26 INFO - 37 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | PeerConnectionWrapper (pcLocal): legal ICE state transition from checking to connected
[task 2019-03-27T00:55:26.565Z] 00:55:26 INFO - 38 INFO PeerConnectionWrapper (pcLocal): ICE connection state log: new,checking,connected
[task 2019-03-27T00:55:26.566Z] 00:55:26 INFO - Buffered messages logged at 00:49:24
[task 2019-03-27T00:55:26.568Z] 00:55:26 INFO - 39 INFO Run step 36: PC_REMOTE_WAIT_FOR_ICE_CONNECTED
[task 2019-03-27T00:55:26.569Z] 00:55:26 INFO - Buffered messages logged at 00:49:26
[task 2019-03-27T00:55:26.571Z] 00:55:26 INFO - 40 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | iceConnectionState should not be undefined
[task 2019-03-27T00:55:26.572Z] 00:55:26 INFO - 41 INFO PeerConnectionWrapper (pcRemote): oniceconnectionstatechange fired, new state is: connected
[task 2019-03-27T00:55:26.573Z] 00:55:26 INFO - Buffered messages logged at 00:49:27
[task 2019-03-27T00:55:26.574Z] 00:55:26 INFO - 42 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | PeerConnectionWrapper (pcRemote): legal ICE state transition from checking to connected
[task 2019-03-27T00:55:26.575Z] 00:55:26 INFO - Buffered messages logged at 00:49:28
[task 2019-03-27T00:55:26.576Z] 00:55:26 INFO - 43 INFO PeerConnectionWrapper (pcRemote): ICE connection state log: new,checking,connected
[task 2019-03-27T00:55:26.578Z] 00:55:26 INFO - 44 INFO Run step 37: PC_LOCAL_VERIFY_ICE_GATHERING
[task 2019-03-27T00:55:26.579Z] 00:55:26 INFO - Buffered messages logged at 00:49:29
[task 2019-03-27T00:55:26.580Z] 00:55:26 INFO - 45 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | PeerConnectionWrapper (pcLocal) received local trickle ICE candidates
[task 2019-03-27T00:55:26.581Z] 00:55:26 INFO - Buffered messages logged at 00:49:31
[task 2019-03-27T00:55:26.582Z] 00:55:26 INFO - 46 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | PeerConnectionWrapper (pcLocal) ICE gathering state is not 'new'
[task 2019-03-27T00:55:26.583Z] 00:55:26 INFO - 47 INFO Run step 38: PC_REMOTE_VERIFY_ICE_GATHERING
[task 2019-03-27T00:55:26.584Z] 00:55:26 INFO - Buffered messages logged at 00:49:32
[task 2019-03-27T00:55:26.585Z] 00:55:26 INFO - 48 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | PeerConnectionWrapper (pcRemote) received local trickle ICE candidates
[task 2019-03-27T00:55:26.586Z] 00:55:26 INFO - 49 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | PeerConnectionWrapper (pcRemote) ICE gathering state is not 'new'
[task 2019-03-27T00:55:26.586Z] 00:55:26 INFO - 50 INFO Run step 39: PC_LOCAL_WAIT_FOR_MEDIA_FLOW
[task 2019-03-27T00:55:26.587Z] 00:55:26 INFO - Buffered messages logged at 00:49:34
[task 2019-03-27T00:55:26.588Z] 00:55:26 INFO - 51 INFO Checking data flow for element: _local_{b81f9c17-73dc-41b6-821b-b44c2d6503ee}
[task 2019-03-27T00:55:26.588Z] 00:55:26 INFO - 52 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | Element ended should be the inverse of the MediaStream's active state
[task 2019-03-27T00:55:26.589Z] 00:55:26 INFO - Buffered messages logged at 00:49:36
[task 2019-03-27T00:55:26.590Z] 00:55:26 INFO - 53 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-03-27T00:55:26.590Z] 00:55:26 INFO - 54 INFO Checking data flow for element: _local_{9df45c94-607a-4c35-9b52-f3eb39596ea1}
[task 2019-03-27T00:55:26.591Z] 00:55:26 INFO - 55 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | Element ended should be the inverse of the MediaStream's active state
[task 2019-03-27T00:55:26.592Z] 00:55:26 INFO - Buffered messages logged at 00:49:37
[task 2019-03-27T00:55:26.592Z] 00:55:26 INFO - 56 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-03-27T00:55:26.592Z] 00:55:26 INFO - Buffered messages logged at 00:49:39
[task 2019-03-27T00:55:26.593Z] 00:55:26 INFO - 57 INFO Found transceiver that should be receiving RTP: mid=0 currentDirection=sendrecv kind=audio track-id={900f37ec-c993-4ad5-a308-517466d1add2}
[task 2019-03-27T00:55:26.593Z] 00:55:26 INFO - 58 INFO Found transceiver that should be receiving RTP: mid=1 currentDirection=sendrecv kind=video track-id={140d133a-ee53-48eb-9c6d-f0ad798fadc7}
[task 2019-03-27T00:55:26.593Z] 00:55:26 INFO - Buffered messages logged at 00:49:41
[task 2019-03-27T00:55:26.593Z] 00:55:26 INFO - 59 INFO Found transceiver that should be receiving RTP: mid=0 currentDirection=sendrecv kind=audio track-id={900f37ec-c993-4ad5-a308-517466d1add2}
[task 2019-03-27T00:55:26.593Z] 00:55:26 INFO - 60 INFO Found transceiver that should be receiving RTP: mid=1 currentDirection=sendrecv kind=video track-id={140d133a-ee53-48eb-9c6d-f0ad798fadc7}
[task 2019-03-27T00:55:26.593Z] 00:55:26 INFO - Buffered messages logged at 00:49:42
[task 2019-03-27T00:55:26.593Z] 00:55:26 INFO - 61 INFO Checking data flow for element: _remote_{900f37ec-c993-4ad5-a308-517466d1add2}
[task 2019-03-27T00:55:26.594Z] 00:55:26 INFO - Buffered messages logged at 00:49:43
[task 2019-03-27T00:55:26.594Z] 00:55:26 INFO - 62 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | Element ended should be the inverse of the MediaStream's active state
[task 2019-03-27T00:55:26.594Z] 00:55:26 INFO - 63 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-03-27T00:55:26.594Z] 00:55:26 INFO - Buffered messages logged at 00:49:44
[task 2019-03-27T00:55:26.594Z] 00:55:26 INFO - 64 INFO Checking data flow for element: _remote_{140d133a-ee53-48eb-9c6d-f0ad798fadc7}
[task 2019-03-27T00:55:26.594Z] 00:55:26 INFO - 65 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | Element ended should be the inverse of the MediaStream's active state
[task 2019-03-27T00:55:26.595Z] 00:55:26 INFO - 66 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-03-27T00:55:26.595Z] 00:55:26 INFO - Buffered messages logged at 00:49:45
[task 2019-03-27T00:55:26.595Z] 00:55:26 INFO - 67 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-03-27T00:55:26.595Z] 00:55:26 INFO - Buffered messages logged at 00:49:46
[task 2019-03-27T00:55:26.595Z] 00:55:26 INFO - 68 INFO Found transceiver that should be receiving RTP: mid=0 currentDirection=sendrecv kind=audio track-id={900f37ec-c993-4ad5-a308-517466d1add2}
[task 2019-03-27T00:55:26.596Z] 00:55:26 INFO - 69 INFO Found transceiver that should be receiving RTP: mid=1 currentDirection=sendrecv kind=video track-id={140d133a-ee53-48eb-9c6d-f0ad798fadc7}
[task 2019-03-27T00:55:26.596Z] 00:55:26 INFO - Buffered messages logged at 00:49:47
[task 2019-03-27T00:55:26.596Z] 00:55:26 INFO - 70 INFO waitForRtpFlow({900f37ec-c993-4ad5-a308-517466d1add2})
[task 2019-03-27T00:55:26.596Z] 00:55:26 INFO - Buffered messages logged at 00:49:50
[task 2019-03-27T00:55:26.597Z] 00:55:26 INFO - 71 INFO waitForRtpFlow({140d133a-ee53-48eb-9c6d-f0ad798fadc7})
[task 2019-03-27T00:55:26.597Z] 00:55:26 INFO - Buffered messages logged at 00:49:54
[task 2019-03-27T00:55:26.597Z] 00:55:26 INFO - 72 INFO waitForRtpFlow({b81f9c17-73dc-41b6-821b-b44c2d6503ee})
[task 2019-03-27T00:55:26.597Z] 00:55:26 INFO - Buffered messages logged at 00:49:56
[task 2019-03-27T00:55:26.597Z] 00:55:26 INFO - 73 INFO waitForRtpFlow({9df45c94-607a-4c35-9b52-f3eb39596ea1})
[task 2019-03-27T00:55:26.597Z] 00:55:26 INFO - Buffered messages logged at 00:49:58
[task 2019-03-27T00:55:26.597Z] 00:55:26 INFO - 74 INFO Element _local_{b81f9c17-73dc-41b6-821b-b44c2d6503ee} has enough data.
[task 2019-03-27T00:55:26.598Z] 00:55:26 INFO - 75 INFO Element _local_{9df45c94-607a-4c35-9b52-f3eb39596ea1} has enough data.
[task 2019-03-27T00:55:26.598Z] 00:55:26 INFO - Buffered messages logged at 00:49:59
[task 2019-03-27T00:55:26.598Z] 00:55:26 INFO - 76 INFO Element _remote_{900f37ec-c993-4ad5-a308-517466d1add2} has enough data.
[task 2019-03-27T00:55:26.598Z] 00:55:26 INFO - Buffered messages logged at 00:50:21
[task 2019-03-27T00:55:26.598Z] 00:55:26 INFO - 77 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | iceConnectionState should not be undefined
[task 2019-03-27T00:55:26.598Z] 00:55:26 INFO - 78 INFO PeerConnectionWrapper (pcLocal): oniceconnectionstatechange fired, new state is: disconnected
[task 2019-03-27T00:55:26.599Z] 00:55:26 INFO - Buffered messages logged at 00:50:22
[task 2019-03-27T00:55:26.599Z] 00:55:26 INFO - 79 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | PeerConnectionWrapper (pcLocal): legal ICE state transition from connected to disconnected
[task 2019-03-27T00:55:26.599Z] 00:55:26 INFO - Buffered messages logged at 00:50:23
[task 2019-03-27T00:55:26.599Z] 00:55:26 INFO - 80 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | iceConnectionState should not be undefined
[task 2019-03-27T00:55:26.599Z] 00:55:26 INFO - Buffered messages logged at 00:50:24
[task 2019-03-27T00:55:26.599Z] 00:55:26 INFO - 81 INFO PeerConnectionWrapper (pcRemote): oniceconnectionstatechange fired, new state is: disconnected
[task 2019-03-27T00:55:26.599Z] 00:55:26 INFO - 82 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | PeerConnectionWrapper (pcRemote): legal ICE state transition from connected to disconnected
[task 2019-03-27T00:55:26.599Z] 00:55:26 INFO - Buffered messages logged at 00:50:26
[task 2019-03-27T00:55:26.599Z] 00:55:26 INFO - 83 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | iceConnectionState should not be undefined
[task 2019-03-27T00:55:26.600Z] 00:55:26 INFO - 84 INFO PeerConnectionWrapper (pcLocal): oniceconnectionstatechange fired, new state is: failed
[task 2019-03-27T00:55:26.600Z] 00:55:26 INFO - Buffered messages logged at 00:50:27
[task 2019-03-27T00:55:26.600Z] 00:55:26 INFO - 85 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | PeerConnectionWrapper (pcLocal): legal ICE state transition from disconnected to failed
[task 2019-03-27T00:55:26.600Z] 00:55:26 INFO - Buffered messages logged at 00:50:28
[task 2019-03-27T00:55:26.600Z] 00:55:26 INFO - 86 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | iceConnectionState should not be undefined
[task 2019-03-27T00:55:26.600Z] 00:55:26 INFO - 87 INFO PeerConnectionWrapper (pcRemote): oniceconnectionstatechange fired, new state is: failed
[task 2019-03-27T00:55:26.600Z] 00:55:26 INFO - Buffered messages logged at 00:50:29
[task 2019-03-27T00:55:26.600Z] 00:55:26 INFO - 88 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | PeerConnectionWrapper (pcRemote): legal ICE state transition from disconnected to failed
[task 2019-03-27T00:55:26.601Z] 00:55:26 INFO - Buffered messages logged at 00:53:25
[task 2019-03-27T00:55:26.601Z] 00:55:26 INFO - 89 INFO Element _remote_{140d133a-ee53-48eb-9c6d-f0ad798fadc7} has enough data.
[task 2019-03-27T00:55:26.601Z] 00:55:26 INFO - Buffered messages logged at 00:54:21
[task 2019-03-27T00:55:26.601Z] 00:55:26 INFO - 90 INFO Checking for stats in [["inbound_rtp_audio_0",{"id":"inbound_rtp_audio_0","timestamp":1553647800365,"type":"inbound-rtp","kind":"audio","mediaType":"audio","ssrc":3922317456,"jitter":0.529,"packetsLost":0,"packetsReceived":53,"bytesReceived":9593,"nackCount":0}],["w0jc",{"id":"w0jc","timestamp":1553647800365,"type":"candidate-pair","bytesReceived":36599,"bytesSent":36319,"componentId":1,"lastPacketReceivedTimestamp":18446744072578994000,"lastPacketSentTimestamp":18446744072578986000,"localCandidateId":"8gve","nominated":true,"priority":7962083765675491000,"readable":false,"remoteCandidateId":"/VHY","selected":true,"state":"cancelled","transportId":"transport_0","writable":false}],["8gve",{"id":"8gve","timestamp":1553647800365,"type":"local-candidate","address":"10.0.2.15","candidateType":"host","port":43707,"priority":2122252543,"protocol":"udp"}],["1Rvi",{"id":"1Rvi","timestamp":1553647800365,"type":"local-candidate","address":"10.0.2.15","candidateType":"host","port":60008,"priority":2105524479,"protocol":"tcp"}],["/VHY",{"id":"/VHY","timestamp":1553647800365,"type":"remote-candidate","address":"10.0.2.15","candidateType":"prflx","port":38640,"priority":1853817087,"protocol":"udp"}]] for audio track {900f37ec-c993-4ad5-a308-517466d1add2}retry number 0
[task 2019-03-27T00:55:26.601Z] 00:55:26 INFO - Buffered messages logged at 00:54:23
[task 2019-03-27T00:55:26.601Z] 00:55:26 INFO - 91 INFO Should have RTP stats for track {900f37ec-c993-4ad5-a308-517466d1add2}
[task 2019-03-27T00:55:26.601Z] 00:55:26 INFO - Buffered messages logged at 00:54:24
[task 2019-03-27T00:55:26.602Z] 00:55:26 INFO - 92 INFO RTP stats: {"id":"inbound_rtp_audio_0","timestamp":1553647800365,"type":"inbound-rtp","kind":"audio","mediaType":"audio","ssrc":3922317456,"jitter":0.529,"packetsLost":0,"packetsReceived":53,"bytesReceived":9593,"nackCount":0}
[task 2019-03-27T00:55:26.602Z] 00:55:26 INFO - Buffered messages logged at 00:54:25
[task 2019-03-27T00:55:26.602Z] 00:55:26 INFO - 93 INFO Track {900f37ec-c993-4ad5-a308-517466d1add2} has 53 inbound-rtp RTP packets.
[task 2019-03-27T00:55:26.602Z] 00:55:26 INFO - 94 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | RTP flowing for audio track {900f37ec-c993-4ad5-a308-517466d1add2}
[task 2019-03-27T00:55:26.602Z] 00:55:26 INFO - Buffered messages logged at 00:54:38
[task 2019-03-27T00:55:26.603Z] 00:55:26 INFO - 95 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | Test timed out.
[task 2019-03-27T00:55:26.603Z] 00:55:26 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
[task 2019-03-27T00:55:26.603Z] 00:55:26 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2019-03-27T00:55:26.603Z] 00:55:26 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
[task 2019-03-27T00:55:26.603Z] 00:55:26 INFO - Buffered messages logged at 00:55:00
[task 2019-03-27T00:55:26.603Z] 00:55:26 INFO - 96 INFO Checking for stats in [["inbound_rtp_video_0",{"id":"inbound_rtp_video_0","timestamp":1553648060059,"type":"inbound-rtp","kind":"video","mediaType":"video","ssrc":1991783945,"discardedPackets":0,"jitter":0.647,"packetsLost":0,"packetsReceived":12,"bitrateMean":0,"bitrateStdDev":0,"bytesReceived":4140,"firCount":0,"framerateMean":0,"framerateStdDev":0,"framesDecoded":4,"nackCount":0,"pliCount":0,"remoteId":"inbound_rtcp_video_0"}],["inbound_rtcp_video_0",{"id":"inbound_rtcp_video_0","timestamp":1553648060059,"type":"remote-outbound-rtp","kind":"video","mediaType":"video","ssrc":1991783945,"bytesSent":0,"packetsSent":0,"localId":"inbound_rtp_video_0"}],["w0jc",{"id":"w0jc","timestamp":1553648060059,"type":"candidate-pair","bytesReceived":36599,"bytesSent":36319,"componentId":1,"lastPacketReceivedTimestamp":18446744072578994000,"lastPacketSentTimestamp":18446744072578986000,"localCandidateId":"8gve","nominated":true,"priority":7962083765675491000,"readable":false,"remoteCandidateId":"/VHY","selected":true,"state":"cancelled","transportId":"transport_0","writable":false}],["8gve",{"id":"8gve","timestamp":1553648060059,"type":"local-candidate","address":"10.0.2.15","candidateType":"host","port":43707,"priority":2122252543,"protocol":"udp"}],["1Rvi",{"id":"1Rvi","timestamp":1553648060059,"type":"local-candidate","address":"10.0.2.15","candidateType":"host","port":60008,"priority":2105524479,"protocol":"tcp"}],["/VHY",{"id":"/VHY","timestamp":1553648060059,"type":"remote-candidate","address":"10.0.2.15","candidateType":"prflx","port":38640,"priority":1853817087,"protocol":"udp"}]] for video track {140d133a-ee53-48eb-9c6d-f0ad798fadc7}retry number 0
[task 2019-03-27T00:55:26.603Z] 00:55:26 INFO - 97 INFO Should have RTP stats for track {140d133a-ee53-48eb-9c6d-f0ad798fadc7}
[task 2019-03-27T00:55:26.603Z] 00:55:26 INFO - Buffered messages logged at 00:55:01
[task 2019-03-27T00:55:26.604Z] 00:55:26 INFO - 98 INFO RTP stats: {"id":"inbound_rtp_video_0","timestamp":1553648060059,"type":"inbound-rtp","kind":"video","mediaType":"video","ssrc":1991783945,"discardedPackets":0,"jitter":0.647,"packetsLost":0,"packetsReceived":12,"bitrateMean":0,"bitrateStdDev":0,"bytesReceived":4140,"firCount":0,"framerateMean":0,"framerateStdDev":0,"framesDecoded":4,"nackCount":0,"pliCount":0,"remoteId":"inbound_rtcp_video_0"}
[task 2019-03-27T00:55:26.604Z] 00:55:26 INFO - 99 INFO Track {140d133a-ee53-48eb-9c6d-f0ad798fadc7} has 12 inbound-rtp RTP packets.
[task 2019-03-27T00:55:26.604Z] 00:55:26 INFO - Buffered messages logged at 00:55:04
[task 2019-03-27T00:55:26.604Z] 00:55:26 INFO - 100 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | RTP flowing for video track {140d133a-ee53-48eb-9c6d-f0ad798fadc7}
[task 2019-03-27T00:55:26.604Z] 00:55:26 INFO - Buffered messages logged at 00:55:15
[task 2019-03-27T00:55:26.605Z] 00:55:26 INFO - 101 ERROR [SimpleTest.finish()] this test already called finish!
[task 2019-03-27T00:55:26.605Z] 00:55:26 INFO - Buffered messages finished
[task 2019-03-27T00:55:26.605Z] 00:55:26 INFO - 102 INFO TEST-UNEXPECTED-ERROR | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | called finish() multiple times
[task 2019-03-27T00:55:26.605Z] 00:55:26 INFO - TEST-INFO took 700749ms
[task 2019-03-27T00:56:12.841Z] 00:56:12 INFO - 103 INFO Checking for stats in [["outbound_rtp_audio_0",{"id":"outbound_rtp_audio_0","timestamp":1553648099213,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":1835841177,"bytesSent":16114,"packetsSent":89,"nackCount":0,"remoteId":""}],["w0jc",{"id":"w0jc","timestamp":1553648099213,"type":"candidate-pair","bytesReceived":36599,"bytesSent":36319,"componentId":1,"lastPacketReceivedTimestamp":18446744072578994000,"lastPacketSentTimestamp":18446744072578986000,"localCandidateId":"8gve","nominated":true,"priority":7962083765675491000,"readable":false,"remoteCandidateId":"/VHY","selected":true,"state":"cancelled","transportId":"transport_0","writable":false}],["8gve",{"id":"8gve","timestamp":1553648099213,"type":"local-candidate","address":"10.0.2.15","candidateType":"host","port":43707,"priority":2122252543,"protocol":"udp"}],["1Rvi",{"id":"1Rvi","timestamp":1553648099213,"type":"local-candidate","address":"10.0.2.15","candidateType":"host","port":60008,"priority":2105524479,"protocol":"tcp"}],["/VHY",{"id":"/VHY","timestamp":1553648099213,"type":"remote-candidate","address":"10.0.2.15","candidateType":"prflx","port":38640,"priority":1853817087,"protocol":"udp"}]] for audio track {b81f9c17-73dc-41b6-821b-b44c2d6503ee}retry number 0
[task 2019-03-27T00:56:12.841Z] 00:56:12 INFO - 104 INFO Should have RTP stats for track {b81f9c17-73dc-41b6-821b-b44c2d6503ee}
[task 2019-03-27T00:56:12.842Z] 00:56:12 INFO - 105 INFO RTP stats: {"id":"outbound_rtp_audio_0","timestamp":1553648099213,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":1835841177,"bytesSent":16114,"packetsSent":89,"nackCount":0,"remoteId":""}
[task 2019-03-27T00:56:12.842Z] 00:56:12 INFO - 106 INFO Track {b81f9c17-73dc-41b6-821b-b44c2d6503ee} has 89 outbound-rtp RTP packets.
[task 2019-03-27T00:56:12.842Z] 00:56:12 INFO - 107 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | RTP flowing for audio track {b81f9c17-73dc-41b6-821b-b44c2d6503ee}
[task 2019-03-27T00:56:39.592Z] 00:56:39 INFO - 108 INFO Checking for stats in [["outbound_rtp_video_0",{"id":"outbound_rtp_video_0","timestamp":1553648159935,"type":"outbound-rtp","kind":"video","mediaType":"video","ssrc":3170206813,"bytesSent":11068,"packetsSent":31,"bitrateMean":832,"bitrateStdDev":2496,"droppedFrames":1191,"firCount":0,"framerateMean":0.1111111111111111,"framerateStdDev":0.3333333333333333,"framesEncoded":191,"nackCount":0,"pliCount":0,"qpSum":584,"remoteId":"outbound_rtcp_video_0"}],["outbound_rtcp_video_0",{"id":"outbound_rtcp_video_0","timestamp":1553648159935,"type":"remote-inbound-rtp","kind":"video","mediaType":"video","ssrc":3170206813,"jitter":0,"packetsLost":0,"packetsReceived":224,"bytesReceived":171369,"localId":"outbound_rtp_video_0"}],["w0jc",{"id":"w0jc","timestamp":1553648159935,"type":"candidate-pair","bytesReceived":36599,"bytesSent":36319,"componentId":1,"lastPacketReceivedTimestamp":18446744072578994000,"lastPacketSentTimestamp":18446744072578986000,"localCandidateId":"8gve","nominated":true,"priority":7962083765675491000,"readable":false,"remoteCandidateId":"/VHY","selected":true,"state":"cancelled","transportId":"transport_0","writable":false}],["8gve",{"id":"8gve","timestamp":1553648159935,"type":"local-candidate","address":"10.0.2.15","candidateType":"host","port":43707,"priority":2122252543,"protocol":"udp"}],["1Rvi",{"id":"1Rvi","timestamp":1553648159935,"type":"local-candidate","address":"10.0.2.15","candidateType":"host","port":60008,"priority":2105524479,"protocol":"tcp"}],["/VHY",{"id":"/VHY","timestamp":1553648159935,"type":"remote-candidate","address":"10.0.2.15","candidateType":"prflx","port":38640,"priority":1853817087,"protocol":"udp"}]] for video track {9df45c94-607a-4c35-9b52-f3eb39596ea1}retry number 0
[task 2019-03-27T00:56:49.809Z] 00:56:49 INFO - 109 INFO Should have RTP stats for track {9df45c94-607a-4c35-9b52-f3eb39596ea1}
[task 2019-03-27T00:56:49.809Z] 00:56:49 INFO - 110 INFO RTP stats: {"id":"outbound_rtp_video_0","timestamp":1553648159935,"type":"outbound-rtp","kind":"video","mediaType":"video","ssrc":3170206813,"bytesSent":11068,"packetsSent":31,"bitrateMean":832,"bitrateStdDev":2496,"droppedFrames":1191,"firCount":0,"framerateMean":0.1111111111111111,"framerateStdDev":0.3333333333333333,"framesEncoded":191,"nackCount":0,"pliCount":0,"qpSum":584,"remoteId":"outbound_rtcp_video_0"}
[task 2019-03-27T00:56:49.809Z] 00:56:49 INFO - 111 INFO Track {9df45c94-607a-4c35-9b52-f3eb39596ea1} has 31 outbound-rtp RTP packets.
[task 2019-03-27T00:56:49.809Z] 00:56:49 INFO - 112 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | RTP flowing for video track {9df45c94-607a-4c35-9b52-f3eb39596ea1}
[task 2019-03-27T00:56:49.809Z] 00:56:49 INFO - 113 INFO Run step 40: PC_LOCAL_TEST_FOR_MISSING_RTCP
[task 2019-03-27T00:57:00.026Z] 00:57:00 INFO - 114 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | Test timed out.
[task 2019-03-27T00:57:00.026Z] 00:57:00 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
[task 2019-03-27T00:57:00.026Z] 00:57:00 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2019-03-27T00:57:00.026Z] 00:57:00 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
[task 2019-03-27T00:57:26.070Z] 00:57:26 INFO - 115 INFO PeerConnectionWrapper (pcLocal): Got stats: {}
[task 2019-03-27T00:57:26.071Z] 00:57:26 INFO - 116 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | There are no RTCP stats when RTCP reception is turned off - got [], expected []
[task 2019-03-27T00:57:26.071Z] 00:57:26 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:320:16
[task 2019-03-27T00:57:26.071Z] 00:57:26 INFO - ensure_missing_rtcp@dom/media/tests/mochitest/test_peerConnection_bug1512281.html:18:2
[task 2019-03-27T00:57:26.071Z] 00:57:26 INFO - PC_LOCAL_TEST_FOR_MISSING_RTCP@dom/media/tests/mochitest/test_peerConnection_bug1512281.html:23:8
[task 2019-03-27T00:57:26.072Z] 00:57:26 INFO - 117 INFO Run step 41: PC_REMOTE_WAIT_FOR_MEDIA_FLOW
[task 2019-03-27T00:57:26.072Z] 00:57:26 INFO - 118 INFO Checking data flow for element: _local_{6427083c-577e-47c7-89c3-a2d9ca6fb413}
[task 2019-03-27T00:57:26.073Z] 00:57:26 INFO - 119 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | Element ended should be the inverse of the MediaStream's active state
[task 2019-03-27T00:57:26.073Z] 00:57:26 INFO - 120 INFO Checking data flow for element: _local_{eb1d9061-822c-47b7-aab1-cd9ac15f678c}
[task 2019-03-27T00:57:26.074Z] 00:57:26 INFO - 121 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | Element ended should be the inverse of the MediaStream's active state
[task 2019-03-27T00:57:26.075Z] 00:57:26 INFO - 122 INFO Found transceiver that should be receiving RTP: mid=0 currentDirection=sendrecv kind=audio track-id={3b65ab2f-17c5-4c84-ba7f-b7ed2a68074c}
[task 2019-03-27T00:57:26.076Z] 00:57:26 INFO - 123 INFO Found transceiver that should be receiving RTP: mid=1 currentDirection=sendrecv kind=video track-id={fe7f6c90-3216-4cb7-ad55-06f220468838}
[task 2019-03-27T00:57:26.076Z] 00:57:26 INFO - 124 INFO Found transceiver that should be receiving RTP: mid=0 currentDirection=sendrecv kind=audio track-id={3b65ab2f-17c5-4c84-ba7f-b7ed2a68074c}
[task 2019-03-27T00:57:26.076Z] 00:57:26 INFO - 125 INFO Found transceiver that should be receiving RTP: mid=1 currentDirection=sendrecv kind=video track-id={fe7f6c90-3216-4cb7-ad55-06f220468838}
[task 2019-03-27T00:57:26.076Z] 00:57:26 INFO - 126 INFO Checking data flow for element: _remote_{3b65ab2f-17c5-4c84-ba7f-b7ed2a68074c}
[task 2019-03-27T00:57:26.076Z] 00:57:26 INFO - 127 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | Element ended should be the inverse of the MediaStream's active state
[task 2019-03-27T00:57:26.076Z] 00:57:26 INFO - 128 INFO Checking data flow for element: _remote_{fe7f6c90-3216-4cb7-ad55-06f220468838}
[task 2019-03-27T00:57:26.076Z] 00:57:26 INFO - 129 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | Element ended should be the inverse of the MediaStream's active state
[task 2019-03-27T00:57:26.076Z] 00:57:26 INFO - 130 INFO Found transceiver that should be receiving RTP: mid=0 currentDirection=sendrecv kind=audio track-id={3b65ab2f-17c5-4c84-ba7f-b7ed2a68074c}
[task 2019-03-27T00:57:36.293Z] 00:57:36 INFO - 131 INFO Found transceiver that should be receiving RTP: mid=1 currentDirection=sendrecv kind=video track-id={fe7f6c90-3216-4cb7-ad55-06f220468838}
[task 2019-03-27T00:57:36.293Z] 00:57:36 INFO - 132 INFO waitForRtpFlow({3b65ab2f-17c5-4c84-ba7f-b7ed2a68074c})
[task 2019-03-27T00:57:36.293Z] 00:57:36 INFO - 133 INFO waitForRtpFlow({fe7f6c90-3216-4cb7-ad55-06f220468838})
[task 2019-03-27T00:57:36.294Z] 00:57:36 INFO - 134 INFO waitForRtpFlow({6427083c-577e-47c7-89c3-a2d9ca6fb413})
[task 2019-03-27T00:57:36.294Z] 00:57:36 INFO - 135 INFO waitForRtpFlow({eb1d9061-822c-47b7-aab1-cd9ac15f678c})
[task 2019-03-27T00:57:36.295Z] 00:57:36 INFO - 136 INFO Element _local_{6427083c-577e-47c7-89c3-a2d9ca6fb413} has enough data.
[task 2019-03-27T00:57:36.295Z] 00:57:36 INFO - 137 INFO Element _local_{eb1d9061-822c-47b7-aab1-cd9ac15f678c} has enough data.
[task 2019-03-27T00:57:36.295Z] 00:57:36 INFO - 138 INFO Element _remote_{3b65ab2f-17c5-4c84-ba7f-b7ed2a68074c} has enough data.
[task 2019-03-27T00:57:36.296Z] 00:57:36 INFO - 139 INFO Element _remote_{fe7f6c90-3216-4cb7-ad55-06f220468838} has enough data.
[task 2019-03-27T00:58:03.848Z] 00:58:03 INFO - 140 INFO Checking for stats in [["inbound_rtp_audio_0",{"id":"inbound_rtp_audio_0","timestamp":1553648256371,"type":"inbound-rtp","kind":"audio","mediaType":"audio","ssrc":1835841177,"jitter":0.53,"packetsLost":0,"packetsReceived":52,"bytesReceived":9412,"nackCount":0}],["wGW4",{"id":"wGW4","timestamp":1553648256371,"type":"candidate-pair","bytesReceived":36319,"bytesSent":36599,"componentId":1,"lastPacketReceivedTimestamp":18446744072578994000,"lastPacketSentTimestamp":18446744072578988000,"localCandidateId":"ovGF","nominated":true,"priority":9115005270282338000,"readable":false,"remoteCandidateId":"aYtU","selected":true,"state":"cancelled","transportId":"transport_0","writable":false}],["ovGF",{"id":"ovGF","timestamp":1553648256371,"type":"local-candidate","address":"10.0.2.15","candidateType":"host","port":38640,"priority":2122252543,"protocol":"udp"}],["XW/g",{"id":"XW/g","timestamp":1553648256371,"type":"local-candidate","address":"10.0.2.15","candidateType":"host","port":64016,"priority":2105524479,"protocol":"tcp"}],["aYtU",{"id":"aYtU","timestamp":1553648256371,"type":"remote-candidate","address":"10.0.2.15","candidateType":"host","port":43707,"priority":2122252543,"protocol":"udp"}]] for audio track {3b65ab2f-17c5-4c84-ba7f-b7ed2a68074c}retry number 0
[task 2019-03-27T00:58:03.848Z] 00:58:03 INFO - 141 INFO Should have RTP stats for track {3b65ab2f-17c5-4c84-ba7f-b7ed2a68074c}
[task 2019-03-27T00:58:03.849Z] 00:58:03 INFO - 142 INFO RTP stats: {"id":"inbound_rtp_audio_0","timestamp":1553648256371,"type":"inbound-rtp","kind":"audio","mediaType":"audio","ssrc":1835841177,"jitter":0.53,"packetsLost":0,"packetsReceived":52,"bytesReceived":9412,"nackCount":0}
[task 2019-03-27T00:58:03.849Z] 00:58:03 INFO - 143 INFO Track {3b65ab2f-17c5-4c84-ba7f-b7ed2a68074c} has 52 inbound-rtp RTP packets.
[task 2019-03-27T00:58:03.849Z] 00:58:03 INFO - 144 INFO TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | RTP flowing for audio track {3b65ab2f-17c5-4c84-ba7f-b7ed2a68074c}
[task 2019-03-27T00:58:14.065Z] 00:58:14 INFO - 145 ERROR [SimpleTest.finish()] this test already called finish!
[task 2019-03-27T00:58:14.066Z] 00:58:14 INFO - 146 INFO TEST-UNEXPECTED-ERROR | dom/media/tests/mochitest/test_peerConnection_bug1512281.html | called finish() multiple times
[task 2019-03-27T00:58:14.067Z] 00:58:14 INFO - TEST-INFO
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•6 years ago
|
||
Description
•