Closed Bug 1549597 Opened 7 years ago Closed 6 years ago

Intermittent dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953124 <= 1557167951755 (

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, regression)

Filed by: dluca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=244920044&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/brqzW9K-SpaItDrapSB5Bw/runs/0/artifacts/public/logs/live_backing.log

5 INFO TEST-START | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html
18:39:23 INFO - <snipped 164 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
18:39:23 INFO - Buffered messages logged at 18:39:11
18:39:23 INFO - 6 INFO Received: {"candidate":"candidate:1 1 TCP 2105508095 10.7.205.38 49966 typ host tcptype passive","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"f1ab270d"} from pcLocal
18:39:23 INFO - 7 INFO PeerConnectionWrapper (pcRemote): adding ICE candidate {"candidate":"candidate:1 1 TCP 2105508095 10.7.205.38 49966 typ host tcptype passive","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"f1ab270d"}
18:39:23 INFO - 8 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | PeerConnectionWrapper (pcRemote) successfully added an ICE candidate
18:39:23 INFO - 9 INFO pcLocal: iceCandidate = {"candidate":"candidate:1 1 TCP 2105524479 10.7.205.38 9 typ host tcptype active","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"f1ab270d"}
18:39:23 INFO - 10 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | SDP mid not empty
18:39:23 INFO - 11 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | usernameFragment not empty
18:39:23 INFO - 12 INFO CLINE-NO-DEFAULT-ADDR-SDP: "application 41909 UDP/DTLS/SCTP webrtc-datachannel\r\nc=IN IP4 10.7.205.38\r\na=candidate:0 1 UDP 2122252543 10.7.205.38 41909 typ host\r\na=candidate:1 1 TCP 2105508095 10.7.205.38 49966 typ host tcptype passive\r\na=candidate:1 1 TCP 2105524479 10.7.205.38 9 typ host tcptype active\r\na=sendrecv\r\na=end-of-candidates\r\na=ice-pwd:8cbf3905f8b3dc8125f769c0ce56b4c5\r\na=ice-ufrag:f1ab270d\r\na=mid:0\r\na=setup:actpass\r\na=sctp-port:5000\r\na=max-message-size:1073741823\r\n"
18:39:23 INFO - 13 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | pcLocal: SDP contains non-zero IP c line
18:39:23 INFO - 14 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | SDP MLine Index needs to exist
18:39:23 INFO - 15 INFO Received: {"candidate":"candidate:1 1 TCP 2105524479 10.7.205.38 9 typ host tcptype active","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"f1ab270d"} from pcLocal
18:39:23 INFO - 16 INFO PeerConnectionWrapper (pcRemote): adding ICE candidate {"candidate":"candidate:1 1 TCP 2105524479 10.7.205.38 9 typ host tcptype active","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"f1ab270d"}
18:39:23 INFO - 17 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | PeerConnectionWrapper (pcRemote) successfully added an ICE candidate
18:39:23 INFO - 18 INFO pcLocal: iceCandidate = {"candidate":"","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"f1ab270d"}
18:39:23 INFO - 19 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | SDP mid not empty
18:39:23 INFO - 20 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | usernameFragment not empty
18:39:23 INFO - 21 INFO CLINE-NO-DEFAULT-ADDR-SDP: "application 41909 UDP/DTLS/SCTP webrtc-datachannel\r\nc=IN IP4 10.7.205.38\r\na=candidate:0 1 UDP 2122252543 10.7.205.38 41909 typ host\r\na=candidate:1 1 TCP 2105508095 10.7.205.38 49966 typ host tcptype passive\r\na=candidate:1 1 TCP 2105524479 10.7.205.38 9 typ host tcptype active\r\na=sendrecv\r\na=end-of-candidates\r\na=ice-pwd:8cbf3905f8b3dc8125f769c0ce56b4c5\r\na=ice-ufrag:f1ab270d\r\na=mid:0\r\na=setup:actpass\r\na=sctp-port:5000\r\na=max-message-size:1073741823\r\n"
18:39:23 INFO - 22 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | pcLocal: SDP contains non-zero IP c line
18:39:23 INFO - 23 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | SDP MLine Index needs to exist
18:39:23 INFO - 24 INFO Received: {"candidate":"","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"f1ab270d"} from pcLocal
18:39:23 INFO - 25 INFO PeerConnectionWrapper (pcRemote): adding ICE candidate {"candidate":"","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"f1ab270d"}
18:39:23 INFO - 26 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | PeerConnectionWrapper (pcRemote) successfully added an ICE candidate
18:39:23 INFO - 27 INFO pcLocal: received end of trickle ICE event
18:39:23 INFO - 28 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | ICE gathering state has reached complete
18:39:23 INFO - 29 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | iceGetheringState should not be undefined
18:39:23 INFO - 30 INFO PeerConnectionWrapper (pcRemote): onicegatheringstatechange fired, new state is: complete
18:39:23 INFO - 31 INFO pcRemote: iceCandidate = {"candidate":"candidate:0 1 UDP 2122252543 10.7.205.38 42490 typ host","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"605592c7"}
18:39:23 INFO - 32 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | SDP mid not empty
18:39:23 INFO - 33 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | usernameFragment not empty
18:39:23 INFO - 34 INFO CLINE-NO-DEFAULT-ADDR-SDP: "application 42490 UDP/DTLS/SCTP webrtc-datachannel\r\nc=IN IP4 10.7.205.38\r\na=candidate:0 1 UDP 2122252543 10.7.205.38 42490 typ host\r\na=candidate:1 1 TCP 2105508095 10.7.205.38 64638 typ host tcptype passive\r\na=candidate:1 1 TCP 2105524479 10.7.205.38 9 typ host tcptype active\r\na=sendrecv\r\na=end-of-candidates\r\na=ice-pwd:3a65b9aa8e54910a43ce08455630368d\r\na=ice-ufrag:605592c7\r\na=mid:0\r\na=setup:active\r\na=sctp-port:5000\r\na=max-message-size:1073741823\r\n"
18:39:23 INFO - 35 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | pcRemote: SDP contains non-zero IP c line
18:39:23 INFO - 36 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | SDP MLine Index needs to exist
18:39:23 INFO - 37 INFO Received: {"candidate":"candidate:0 1 UDP 2122252543 10.7.205.38 42490 typ host","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"605592c7"} from pcRemote
18:39:23 INFO - 38 INFO PeerConnectionWrapper (pcLocal): adding ICE candidate {"candidate":"candidate:0 1 UDP 2122252543 10.7.205.38 42490 typ host","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"605592c7"}
18:39:23 INFO - 39 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | PeerConnectionWrapper (pcLocal) successfully added an ICE candidate
18:39:23 INFO - 40 INFO pcRemote: iceCandidate = {"candidate":"candidate:1 1 TCP 2105508095 10.7.205.38 64638 typ host tcptype passive","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"605592c7"}
18:39:23 INFO - 41 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | SDP mid not empty
18:39:23 INFO - 42 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | usernameFragment not empty
18:39:23 INFO - 43 INFO CLINE-NO-DEFAULT-ADDR-SDP: "application 42490 UDP/DTLS/SCTP webrtc-datachannel\r\nc=IN IP4 10.7.205.38\r\na=candidate:0 1 UDP 2122252543 10.7.205.38 42490 typ host\r\na=candidate:1 1 TCP 2105508095 10.7.205.38 64638 typ host tcptype passive\r\na=candidate:1 1 TCP 2105524479 10.7.205.38 9 typ host tcptype active\r\na=sendrecv\r\na=end-of-candidates\r\na=ice-pwd:3a65b9aa8e54910a43ce08455630368d\r\na=ice-ufrag:605592c7\r\na=mid:0\r\na=setup:active\r\na=sctp-port:5000\r\na=max-message-size:1073741823\r\n"
18:39:23 INFO - 44 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | pcRemote: SDP contains non-zero IP c line
18:39:23 INFO - 45 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | SDP MLine Index needs to exist
18:39:23 INFO - 46 INFO Received: {"candidate":"candidate:1 1 TCP 2105508095 10.7.205.38 64638 typ host tcptype passive","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"605592c7"} from pcRemote
18:39:23 INFO - 47 INFO PeerConnectionWrapper (pcLocal): adding ICE candidate {"candidate":"candidate:1 1 TCP 2105508095 10.7.205.38 64638 typ host tcptype passive","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"605592c7"}
18:39:23 INFO - 48 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | PeerConnectionWrapper (pcLocal) successfully added an ICE candidate
18:39:23 INFO - 49 INFO pcRemote: iceCandidate = {"candidate":"candidate:1 1 TCP 2105524479 10.7.205.38 9 typ host tcptype active","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"605592c7"}
18:39:23 INFO - 50 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | SDP mid not empty
18:39:23 INFO - 51 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | usernameFragment not empty
18:39:23 INFO - 52 INFO CLINE-NO-DEFAULT-ADDR-SDP: "application 42490 UDP/DTLS/SCTP webrtc-datachannel\r\nc=IN IP4 10.7.205.38\r\na=candidate:0 1 UDP 2122252543 10.7.205.38 42490 typ host\r\na=candidate:1 1 TCP 2105508095 10.7.205.38 64638 typ host tcptype passive\r\na=candidate:1 1 TCP 2105524479 10.7.205.38 9 typ host tcptype active\r\na=sendrecv\r\na=end-of-candidates\r\na=ice-pwd:3a65b9aa8e54910a43ce08455630368d\r\na=ice-ufrag:605592c7\r\na=mid:0\r\na=setup:active\r\na=sctp-port:5000\r\na=max-message-size:1073741823\r\n"
18:39:23 INFO - 53 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | pcRemote: SDP contains non-zero IP c line
18:39:23 INFO - 54 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | SDP MLine Index needs to exist
18:39:23 INFO - 55 INFO Received: {"candidate":"candidate:1 1 TCP 2105524479 10.7.205.38 9 typ host tcptype active","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"605592c7"} from pcRemote
18:39:23 INFO - 56 INFO PeerConnectionWrapper (pcLocal): adding ICE candidate {"candidate":"candidate:1 1 TCP 2105524479 10.7.205.38 9 typ host tcptype active","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"605592c7"}
18:39:23 INFO - 57 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | PeerConnectionWrapper (pcLocal) successfully added an ICE candidate
18:39:23 INFO - 58 INFO pcRemote: iceCandidate = {"candidate":"","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"605592c7"}
18:39:23 INFO - 59 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | SDP mid not empty
18:39:23 INFO - 60 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | usernameFragment not empty
18:39:23 INFO - 61 INFO CLINE-NO-DEFAULT-ADDR-SDP: "application 42490 UDP/DTLS/SCTP webrtc-datachannel\r\nc=IN IP4 10.7.205.38\r\na=candidate:0 1 UDP 2122252543 10.7.205.38 42490 typ host\r\na=candidate:1 1 TCP 2105508095 10.7.205.38 64638 typ host tcptype passive\r\na=candidate:1 1 TCP 2105524479 10.7.205.38 9 typ host tcptype active\r\na=sendrecv\r\na=end-of-candidates\r\na=ice-pwd:3a65b9aa8e54910a43ce08455630368d\r\na=ice-ufrag:605592c7\r\na=mid:0\r\na=setup:active\r\na=sctp-port:5000\r\na=max-message-size:1073741823\r\n"
18:39:23 INFO - 62 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | pcRemote: SDP contains non-zero IP c line
18:39:23 INFO - 63 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | SDP MLine Index needs to exist
18:39:23 INFO - 64 INFO Received: {"candidate":"","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"605592c7"} from pcRemote
18:39:23 INFO - 65 INFO PeerConnectionWrapper (pcLocal): adding ICE candidate {"candidate":"","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"605592c7"}
18:39:23 INFO - 66 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | PeerConnectionWrapper (pcLocal) successfully added an ICE candidate
18:39:23 INFO - 67 INFO pcRemote: received end of trickle ICE event
18:39:23 INFO - 68 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | ICE gathering state has reached complete
18:39:23 INFO - 69 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | data channel is 'open' after 'onopen'
18:39:23 INFO - 70 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | iceConnectionState should not be undefined
18:39:23 INFO - 71 INFO PeerConnectionWrapper (pcRemote): oniceconnectionstatechange fired, new state is: checking
18:39:23 INFO - 72 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | PeerConnectionWrapper (pcRemote): legal ICE state transition from new to checking
18:39:23 INFO - 73 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | iceConnectionState should not be undefined
18:39:23 INFO - 74 INFO PeerConnectionWrapper (pcLocal): oniceconnectionstatechange fired, new state is: checking
18:39:23 INFO - 75 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | PeerConnectionWrapper (pcLocal): legal ICE state transition from new to checking
18:39:23 INFO - 76 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | iceConnectionState should not be undefined
18:39:23 INFO - 77 INFO PeerConnectionWrapper (pcLocal): oniceconnectionstatechange fired, new state is: connected
18:39:23 INFO - 78 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | PeerConnectionWrapper (pcLocal): legal ICE state transition from checking to connected
18:39:23 INFO - 79 INFO PeerConnectionWrapper (pcLocal): ICE connection state log: new,checking,connected
18:39:23 INFO - 80 INFO Run step 36: PC_REMOTE_WAIT_FOR_ICE_CONNECTED
18:39:23 INFO - 81 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | iceConnectionState should not be undefined
18:39:23 INFO - 82 INFO PeerConnectionWrapper (pcRemote): oniceconnectionstatechange fired, new state is: connected
18:39:23 INFO - 83 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | PeerConnectionWrapper (pcRemote): legal ICE state transition from checking to connected
18:39:23 INFO - 84 INFO PeerConnectionWrapper (pcRemote): ICE connection state log: new,checking,connected
18:39:23 INFO - 85 INFO Run step 37: PC_LOCAL_VERIFY_ICE_GATHERING
18:39:23 INFO - 86 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | PeerConnectionWrapper (pcLocal) received local trickle ICE candidates
18:39:23 INFO - 87 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | PeerConnectionWrapper (pcLocal) ICE gathering state is not 'new'
18:39:23 INFO - 88 INFO Run step 38: PC_REMOTE_VERIFY_ICE_GATHERING
18:39:23 INFO - 89 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | PeerConnectionWrapper (pcRemote) received local trickle ICE candidates
18:39:23 INFO - 90 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | PeerConnectionWrapper (pcRemote) ICE gathering state is not 'new'
18:39:23 INFO - 91 INFO Run step 39: PC_LOCAL_VERIFY_DATA_CHANNEL_STATE
18:39:23 INFO - 92 INFO Run step 40: PC_REMOTE_VERIFY_DATA_CHANNEL_STATE
18:39:23 INFO - 93 INFO PeerConnectionWrapper (pcRemote): "ondatachannel" event fired
18:39:23 INFO - 94 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | undefined assertion name
18:39:23 INFO - 95 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | data channel in 'connecting after 'ondatachannel''
18:39:23 INFO - 96 INFO Creating DataChannelWrapper (pcRemote_channel_0)
18:39:23 INFO - 97 INFO TEST-FAIL | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
18:39:23 INFO - 98 INFO Run step 41: PC_LOCAL_WAIT_FOR_MEDIA_FLOW
18:39:23 INFO - 99 INFO Run step 42: PC_REMOTE_WAIT_FOR_MEDIA_FLOW
18:39:23 INFO - 100 INFO Run step 43: PC_LOCAL_CHECK_STATS
18:39:23 INFO - 101 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | data channel is 'open' after 'onopen'
18:39:23 INFO - 102 INFO PeerConnectionWrapper (pcLocal): Got stats: {}
18:39:23 INFO - 103 INFO Checking stats for nO6z : [object Object]
18:39:23 INFO - 104 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Coherent stats id
18:39:23 INFO - 105 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953124 >= 1557167950969 (
18:39:23 INFO - 2155 ms)
18:39:23 INFO - Buffered messages finished
18:39:23 INFO - 106 INFO TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953124 <= 1557167951755 (
18:39:23 INFO - 1369 ms)
18:39:23 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
18:39:23 INFO - checkStats@dom/media/tests/mochitest/pc.js:1809:9
18:39:23 INFO - PC_LOCAL_CHECK_STATS/<@dom/media/tests/mochitest/templates.js:365:20
18:39:23 INFO - 107 INFO Checking stats for Sa+H : [object Object]
18:39:23 INFO - 108 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Coherent stats id
18:39:23 INFO - 109 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953124 >= 1557167950969 (
18:39:23 INFO - 2155 ms)
18:39:23 INFO - 110 INFO TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953124 <= 1557167951760 (
18:39:23 INFO - 1364 ms)
18:39:23 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
18:39:23 INFO - checkStats@dom/media/tests/mochitest/pc.js:1809:9
18:39:23 INFO - PC_LOCAL_CHECK_STATS/<@dom/media/tests/mochitest/templates.js:365:20
18:39:23 INFO - 111 INFO Checking stats for 94S5 : [object Object]
18:39:23 INFO - 112 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Coherent stats id
18:39:23 INFO - 113 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953124 >= 1557167950969 (
18:39:23 INFO - 2155 ms)
18:39:23 INFO - 114 INFO TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953124 <= 1557167951764 (
18:39:23 INFO - 1360 ms)
18:39:23 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
18:39:23 INFO - checkStats@dom/media/tests/mochitest/pc.js:1809:9
18:39:23 INFO - PC_LOCAL_CHECK_STATS/<@dom/media/tests/mochitest/templates.js:365:20
18:39:23 INFO - 115 INFO Checking stats for f+sY : [object Object]
18:39:23 INFO - 116 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Coherent stats id
18:39:23 INFO - 117 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953124 >= 1557167950969 (
18:39:23 INFO - 2155 ms)
18:39:23 INFO - 118 INFO TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953124 <= 1557167951766 (
18:39:23 INFO - 1358 ms)
18:39:23 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
18:39:23 INFO - checkStats@dom/media/tests/mochitest/pc.js:1809:9
18:39:23 INFO - PC_LOCAL_CHECK_STATS/<@dom/media/tests/mochitest/templates.js:365:20
18:39:23 INFO - 119 INFO Checking stats for jAB2 : [object Object]
18:39:23 INFO - 120 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Coherent stats id
18:39:23 INFO - 121 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953124 >= 1557167950969 (
18:39:23 INFO - 2155 ms)
18:39:23 INFO - 122 INFO TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953124 <= 1557167951769 (
18:39:23 INFO - 1355 ms)
18:39:23 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
18:39:23 INFO - checkStats@dom/media/tests/mochitest/pc.js:1809:9
18:39:23 INFO - PC_LOCAL_CHECK_STATS/<@dom/media/tests/mochitest/templates.js:365:20
18:39:23 INFO - 123 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Have at least 0 inbound-rtp stat(s) *
18:39:23 INFO - 124 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Have 0 outbound-rtp stat(s)
18:39:23 INFO - 125 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Have local-candidate stat(s)
18:39:23 INFO - 126 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Have remote-candidate stat(s)
18:39:23 INFO - 127 INFO Run step 44: PC_REMOTE_CHECK_STATS
18:39:23 INFO - 128 INFO PeerConnectionWrapper (pcRemote): Got stats: {}
18:39:23 INFO - 129 INFO Checking stats for jmkA : [object Object]
18:39:23 INFO - 130 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Coherent stats id
18:39:23 INFO - 131 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953151 >= 1557167950982 (
18:39:23 INFO - 2169 ms)
18:39:23 INFO - 132 INFO TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953151 <= 1557167951780 (
18:39:23 INFO - 1371 ms)
18:39:23 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
18:39:23 INFO - checkStats@dom/media/tests/mochitest/pc.js:1809:9
18:39:23 INFO - PC_REMOTE_CHECK_STATS/<@dom/media/tests/mochitest/templates.js:371:21
18:39:23 INFO - 133 INFO Checking stats for dhFs : [object Object]
18:39:23 INFO - 134 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Coherent stats id
18:39:23 INFO - 135 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953151 >= 1557167950982 (
18:39:23 INFO - 2169 ms)
18:39:23 INFO - 136 INFO TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953151 <= 1557167951783 (
18:39:23 INFO - 1368 ms)
18:39:23 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
18:39:23 INFO - checkStats@dom/media/tests/mochitest/pc.js:1809:9
18:39:23 INFO - PC_REMOTE_CHECK_STATS/<@dom/media/tests/mochitest/templates.js:371:21
18:39:23 INFO - 137 INFO Checking stats for TjM8 : [object Object]
18:39:23 INFO - 138 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Coherent stats id
18:39:23 INFO - 139 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953151 >= 1557167950982 (
18:39:23 INFO - 2169 ms)
18:39:23 INFO - 140 INFO TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953151 <= 1557167951787 (
18:39:23 INFO - 1364 ms)
18:39:23 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
18:39:23 INFO - checkStats@dom/media/tests/mochitest/pc.js:1809:9
18:39:23 INFO - PC_REMOTE_CHECK_STATS/<@dom/media/tests/mochitest/templates.js:371:21
18:39:23 INFO - 141 INFO Checking stats for nElN : [object Object]
18:39:23 INFO - 142 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Coherent stats id
18:39:23 INFO - 143 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953151 >= 1557167950982 (
18:39:23 INFO - 2169 ms)
18:39:23 INFO - 144 INFO TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953151 <= 1557167951791 (
18:39:23 INFO - 1360 ms)
18:39:23 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
18:39:23 INFO - checkStats@dom/media/tests/mochitest/pc.js:1809:9
18:39:23 INFO - PC_REMOTE_CHECK_STATS/<@dom/media/tests/mochitest/templates.js:371:21
18:39:23 INFO - 145 INFO Checking stats for PCnA : [object Object]
18:39:23 INFO - 146 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Coherent stats id
18:39:23 INFO - 147 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953151 >= 1557167950982 (
18:39:23 INFO - 2169 ms)
18:39:23 INFO - 148 INFO TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1557167953151 <= 1557167951792 (
18:39:23 INFO - 1359 ms)
18:39:23 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
18:39:23 INFO - checkStats@dom/media/tests/mochitest/pc.js:1809:9
18:39:23 INFO - PC_REMOTE_CHECK_STATS/<@dom/media/tests/mochitest/templates.js:371:21
18:39:23 INFO - 149 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Have at least 0 inbound-rtp stat(s) *
18:39:23 INFO - 150 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Have 0 outbound-rtp stat(s)
18:39:23 INFO - 151 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Have local-candidate stat(s)
18:39:23 INFO - 152 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Have remote-candidate stat(s)
18:39:23 INFO - 153 INFO Run step 45: PC_LOCAL_CHECK_ICE_CONNECTION_TYPE
18:39:23 INFO - 154 INFO PeerConnectionWrapper (pcLocal): Got stats: {}
18:39:23 INFO - 155 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Got local candidate ID Sa+H for selected pair
18:39:23 INFO - 156 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Got remote candidate ID jAB2 for selected pair
18:39:23 INFO - 157 INFO checkStatsIceConnectionType verifying: local={"id":"Sa+H","timestamp":1557167953173,"type":"local-candidate","address":"10.7.205.38","candidateType":"host","port":41909,"priority":2122252543,"protocol":"udp"} remote={"id":"jAB2","timestamp":1557167953173,"type":"remote-candidate","address":"10.7.205.38","candidateType":"prflx","port":42490,"priority":1853817087,"protocol":"udp"}
18:39:23 INFO - 158 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Local candidate type is what we expected for selected pair
18:39:23 INFO - 159 INFO Run step 46: PC_REMOTE_CHECK_ICE_CONNECTION_TYPE
18:39:23 INFO - 160 INFO PeerConnectionWrapper (pcRemote): Got stats: {}
18:39:23 INFO - 161 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Got local candidate ID dhFs for selected pair
18:39:23 INFO - 162 INFO TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Got remote candidate ID PCnA for selected pair

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE

This is still happening.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=272831244&repo=mozilla-beta&lineNumber=5537

[task 2019-10-24T19:56:00.411Z] 19:56:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | PeerConnectionWrapper (pcRemote) ICE gathering state is not 'new'
[task 2019-10-24T19:56:00.411Z] 19:56:00 INFO - Run step 39: PC_LOCAL_VERIFY_DATA_CHANNEL_STATE
[task 2019-10-24T19:56:00.411Z] 19:56:00 INFO - Run step 40: PC_REMOTE_VERIFY_DATA_CHANNEL_STATE
[task 2019-10-24T19:56:00.411Z] 19:56:00 INFO - Run step 41: PC_LOCAL_WAIT_FOR_MEDIA_FLOW
[task 2019-10-24T19:56:00.411Z] 19:56:00 INFO - Run step 42: PC_REMOTE_WAIT_FOR_MEDIA_FLOW
[task 2019-10-24T19:56:00.412Z] 19:56:00 INFO - Run step 43: PC_LOCAL_CHECK_STATS
[task 2019-10-24T19:56:00.412Z] 19:56:00 INFO - PeerConnectionWrapper (pcLocal): Got stats: {}
[task 2019-10-24T19:56:00.412Z] 19:56:00 INFO - Checking stats for p5y7 : [object Object]
[task 2019-10-24T19:56:00.412Z] 19:56:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Coherent stats id
[task 2019-10-24T19:56:00.412Z] 19:56:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1571946960455 >= 1571946959691 (
[task 2019-10-24T19:56:00.412Z] 19:56:00 INFO - 764 ms)
[task 2019-10-24T19:56:00.413Z] 19:56:00 INFO - Buffered messages finished
[task 2019-10-24T19:56:00.414Z] 19:56:00 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1571946960455 <= 1571946960259 (
[task 2019-10-24T19:56:00.415Z] 19:56:00 INFO - 196 ms)
[task 2019-10-24T19:56:00.415Z] 19:56:00 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-10-24T19:56:00.415Z] 19:56:00 INFO - checkStats@dom/media/tests/mochitest/pc.js:2106:11
[task 2019-10-24T19:56:00.415Z] 19:56:00 INFO - PC_LOCAL_CHECK_STATS/<@dom/media/tests/mochitest/templates.js:522:20
[task 2019-10-24T19:56:00.415Z] 19:56:00 INFO - Checking stats for Zs0c : [object Object]
[task 2019-10-24T19:56:00.416Z] 19:56:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Coherent stats id
[task 2019-10-24T19:56:00.423Z] 19:56:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1571946960455 >= 1571946959691 (
[task 2019-10-24T19:56:00.423Z] 19:56:00 INFO - 764 ms)
[task 2019-10-24T19:56:00.423Z] 19:56:00 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-24T19:56:00.423Z] 19:56:00 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1571946960455 <= 1571946960261 (
[task 2019-10-24T19:56:00.424Z] 19:56:00 INFO - 194 ms)
[task 2019-10-24T19:56:00.424Z] 19:56:00 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-10-24T19:56:00.424Z] 19:56:00 INFO - checkStats@dom/media/tests/mochitest/pc.js:2106:11
[task 2019-10-24T19:56:00.424Z] 19:56:00 INFO - PC_LOCAL_CHECK_STATS/<@dom/media/tests/mochitest/templates.js:522:20
[task 2019-10-24T19:56:00.424Z] 19:56:00 INFO - Checking stats for h3wU : [object Object]
[task 2019-10-24T19:56:00.424Z] 19:56:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Coherent stats id
[task 2019-10-24T19:56:00.424Z] 19:56:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1571946960455 >= 1571946959691 (
[task 2019-10-24T19:56:00.424Z] 19:56:00 INFO - 764 ms)
[task 2019-10-24T19:56:00.425Z] 19:56:00 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-24T19:56:00.425Z] 19:56:00 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_dataChannel_dataOnlyBufferedAmountLow.html | Valid rtp timestamp 1571946960455 <= 1571946960262 (
[task 2019-10-24T19:56:00.425Z] 19:56:00 INFO - 193 ms)
[task 2019-10-24T19:56:00.426Z] 19:56:00 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

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