Closed Bug 1531016 Opened 7 years ago Closed 6 years ago

Intermittent dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | iceconnectionstate event 'failed' matches expected state 'checking' - got "failed", expected "checking"

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)

#[markdown(off)]
Filed by: opoprus [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=230734366&repo=autoland

https://queue.taskcluster.net/v1/task/ZM8rQE3vQCSTi81iTaOBBw/runs/0/artifacts/public/logs/live_backing.log

[task 2019-02-27T11:05:09.859Z] 11:05:09 INFO - TEST-OK | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundle.html | took 36259ms
[task 2019-02-27T11:05:10.383Z] 11:05:10 INFO - GECKO(1882) | [Child 1998, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x80004005: file /builds/worker/workspace/build/src/docshell/shistory/nsSHistory.cpp, line 1204
[task 2019-02-27T11:05:10.384Z] 11:05:10 INFO - GECKO(1882) | [Child 1998, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x80004005: file /builds/worker/workspace/build/src/docshell/shistory/nsSHistory.cpp, line 1204
[task 2019-02-27T11:05:10.424Z] 11:05:10 INFO - GECKO(1882) | ++DOMWINDOW == 10 (0x7fdc8a9ee400) [pid = 1998] [serial = 469] [outer = 0x7fdc98a61800]
[task 2019-02-27T11:05:10.543Z] 11:05:10 INFO - GECKO(1882) | JavaScript error: , line 0: InvalidStateError: An attempt was made to use an object that is not, or is no longer, usable
[task 2019-02-27T11:05:10.612Z] 11:05:10 INFO - TEST-START | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html

[task 2019-02-27T11:07:10.248Z] 11:07:10 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | iceconnectionstate event 'failed' matches expected state 'checking' - got "failed", expected "checking"
[task 2019-02-27T11:07:10.249Z] 11:07:10 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:320:16
[task 2019-02-27T11:07:10.251Z] 11:07:10 INFO - logIceConnectionState/this.ice_connection_callbacks.logIceStatus@dom/media/tests/mochitest/pc.js:1367:11
[task 2019-02-27T11:07:10.253Z] 11:07:10 INFO - PeerConnectionWrapper/this._pc.oniceconnectionstatechange/<@dom/media/tests/mochitest/pc.js:822:42
[task 2019-02-27T11:07:10.255Z] 11:07:10 INFO - PeerConnectionWrapper/this._pc.oniceconnectionstatechange@dom/media/tests/mochitest/pc.js:821:48
[task 2019-02-27T11:07:10.259Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/INFO) ICE-PEER(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default)/STREAM(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - e517b205:e3e69291c9a55e9deb6782f0dc6ba2af)/COMP(1)/CAND-PAIR(qp9P): nominated pair is qp9P|IP4:172.17.0.5:54560/UDP|IP4:172.17.0.5:52194/UDP(host(IP4:172.17.0.5:54560/UDP)|candidate:0 1 UDP 2122187007 172.17.0.5 52194 typ host)
[task 2019-02-27T11:07:10.261Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/INFO) ICE-PEER(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default)/STREAM(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - e517b205:e3e69291c9a55e9deb6782f0dc6ba2af)/COMP(1)/CAND-PAIR(qp9P): cancelling all pairs but qp9P|IP4:172.17.0.5:54560/UDP|IP4:172.17.0.5:52194/UDP(host(IP4:172.17.0.5:54560/UDP)|candidate:0 1 UDP 2122187007 172.17.0.5 52194 typ host)
[task 2019-02-27T11:07:10.271Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/INFO) ICE-PEER(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default)/STREAM(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - e517b205:e3e69291c9a55e9deb6782f0dc6ba2af)/COMP(1)/CAND-PAIR(qp9P): cancelling FROZEN/WAITING pair qp9P|IP4:172.17.0.5:54560/UDP|IP4:172.17.0.5:52194/UDP(host(IP4:172.17.0.5:54560/UDP)|candidate:0 1 UDP 2122187007 172.17.0.5 52194 typ host) in trigger check queue because CAND-PAIR(qp9P) was nominated.
[task 2019-02-27T11:07:10.273Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/INFO) ICE-PEER(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default)/CAND-PAIR(qp9P): setting pair to state CANCELLED: qp9P|IP4:172.17.0.5:54560/UDP|IP4:172.17.0.5:52194/UDP(host(IP4:172.17.0.5:54560/UDP)|candidate:0 1 UDP 2122187007 172.17.0.5 52194 typ host)
[task 2019-02-27T11:07:10.298Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/INFO) ICE-PEER(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default)/ICE-STREAM(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - e517b205:e3e69291c9a55e9deb6782f0dc6ba2af): all active components have nominated candidate pairs
[task 2019-02-27T11:07:10.309Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/INFO) ICE-PEER(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default): all checks completed success=1 fail=0
[task 2019-02-27T11:07:10.330Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - e517b205:e3e69291c9a55e9deb6782f0dc6ba2af) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.336Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - e517b205:e3e69291c9a55e9deb6782f0dc6ba2af) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.337Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - e517b205:e3e69291c9a55e9deb6782f0dc6ba2af) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.358Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - e517b205:e3e69291c9a55e9deb6782f0dc6ba2af) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.376Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - e517b205:e3e69291c9a55e9deb6782f0dc6ba2af) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.376Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - e517b205:e3e69291c9a55e9deb6782f0dc6ba2af) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.393Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - e517b205:e3e69291c9a55e9deb6782f0dc6ba2af) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.414Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - e517b205:e3e69291c9a55e9deb6782f0dc6ba2af) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.423Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - e517b205:e3e69291c9a55e9deb6782f0dc6ba2af) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.424Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - e517b205:e3e69291c9a55e9deb6782f0dc6ba2af) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.445Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - e517b205:e3e69291c9a55e9deb6782f0dc6ba2af) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.445Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - 0581f9bd:16552bad9e31c52ee7c61b235dd9c388) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.446Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - 0581f9bd:16552bad9e31c52ee7c61b235dd9c388) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.466Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - 0581f9bd:16552bad9e31c52ee7c61b235dd9c388) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.483Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - 0581f9bd:16552bad9e31c52ee7c61b235dd9c388) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.500Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - 0581f9bd:16552bad9e31c52ee7c61b235dd9c388) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.500Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/ERR) ICE(PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH): peer (PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH:default), stream(PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - 0581f9bd:16552bad9e31c52ee7c61b235dd9c388) tried to trickle ICE in inappropriate state 4
[task 2019-02-27T11:07:10.501Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (stun/INFO) STUN-CLIENT(consent): Received response; processing
[task 2019-02-27T11:07:10.522Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/INFO) ICE(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH)/STREAM(PC:1551264986092130 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - e517b205:e3e69291c9a55e9deb6782f0dc6ba2af)/COMP(1): Consent refreshed
[task 2019-02-27T11:07:10.530Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (stun/INFO) STUN-CLIENT(consent): Received response; processing
[task 2019-02-27T11:07:10.535Z] 11:07:10 INFO - GECKO(1882) | [Socket 1909: Socket Thread]: D/mtransport (ice/INFO) ICE(PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH)/STREAM(PC:1551264986075083 (id=4294967662 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH transport-id=transport_0 - 0581f9bd:16552bad9e31c52ee7c61b235dd9c388)/COMP(1): Consent refreshed

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

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

Fail reappeared on autoland.
Log link: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=237601039&repo=autoland&lineNumber=48175

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

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

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