Closed Bug 1533566 Opened 7 years ago Closed 7 years ago

Intermittent dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Local candidate type is what we expected for selected pair - got "prflx", expected "host"

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: cbrindusan [at] mozilla.com

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

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

[task 2019-03-07T21:34:54.752Z] 21:34:54 INFO - GECKO(1853) | [Child 1953: Unnamed thread 0xe41e3a00]: I/signaling [|WebrtcVideoSessionConduit] VideoStreamFactory.cpp:195: CreateEncoderStreams Input frame 320x240, RID scaling to 320x240
[task 2019-03-07T21:34:57.379Z] 21:34:57 INFO - GECKO(1853) | (stun/INFO) STUN-CLIENT(consent): Received response; processing
[task 2019-03-07T21:34:57.396Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund)/STREAM(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund transport-id=transport_1 - a63327a1:3d58f11c040f97c33b48ce6611cc3966)/COMP(1): Consent refreshed
[task 2019-03-07T21:34:57.653Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/CAND-PAIR(zt3U): triggered check on zt3U|IP4:172.17.0.5:53950/UDP|IP4:172.17.0.5:46598/UDP(host(IP4:172.17.0.5:53950/UDP)|candidate:0 1 UDP 2122187007 172.17.0.5 46598 typ host)
[task 2019-03-07T21:34:57.656Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/CAND-PAIR(zt3U): Inserting pair to trigger check queue: zt3U|IP4:172.17.0.5:53950/UDP|IP4:172.17.0.5:46598/UDP(host(IP4:172.17.0.5:53950/UDP)|candidate:0 1 UDP 2122187007 172.17.0.5 46598 typ host)
[task 2019-03-07T21:34:57.658Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/ICE-STREAM(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund transport-id=transport_1 - a63327a1:3d58f11c040f97c33b48ce6611cc3966): Starting check timer for stream.
[task 2019-03-07T21:34:57.660Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/CAND-PAIR(zt3U): setting pair to state IN_PROGRESS: zt3U|IP4:172.17.0.5:53950/UDP|IP4:172.17.0.5:46598/UDP(host(IP4:172.17.0.5:53950/UDP)|candidate:0 1 UDP 2122187007 172.17.0.5 46598 typ host)
[task 2019-03-07T21:34:57.661Z] 21:34:57 INFO - GECKO(1853) | (stun/INFO) STUN-CLIENT(zt3U|IP4:172.17.0.5:53950/UDP|IP4:172.17.0.5:46598/UDP(host(IP4:172.17.0.5:53950/UDP)|candidate:0 1 UDP 2122187007 172.17.0.5 46598 typ host)): Received response; processing
[task 2019-03-07T21:34:57.673Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/CAND-PAIR(zt3U): setting pair to state SUCCEEDED: zt3U|IP4:172.17.0.5:53950/UDP|IP4:172.17.0.5:46598/UDP(host(IP4:172.17.0.5:53950/UDP)|candidate:0 1 UDP 2122187007 172.17.0.5 46598 typ host)
[task 2019-03-07T21:34:57.678Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/STREAM(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund transport-id=transport_1 - a63327a1:3d58f11c040f97c33b48ce6611cc3966)/COMP(1)/CAND-PAIR(C0OW): replacing pair C0OW|IP4:172.17.0.5:53950/UDP|IP4:172.17.0.5:46598/UDP(host(IP4:172.17.0.5:53950/UDP)|prflx) with CAND-PAIR(zt3U)
[task 2019-03-07T21:34:57.684Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/STREAM(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund transport-id=transport_1 - a63327a1:3d58f11c040f97c33b48ce6611cc3966)/COMP(1)/CAND-PAIR(zt3U): nominated pair is zt3U|IP4:172.17.0.5:53950/UDP|IP4:172.17.0.5:46598/UDP(host(IP4:172.17.0.5:53950/UDP)|candidate:0 1 UDP 2122187007 172.17.0.5 46598 typ host)
[task 2019-03-07T21:34:57.694Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/STREAM(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund transport-id=transport_1 - a63327a1:3d58f11c040f97c33b48ce6611cc3966)/COMP(1)/CAND-PAIR(zt3U): cancelling all pairs but zt3U|IP4:172.17.0.5:53950/UDP|IP4:172.17.0.5:46598/UDP(host(IP4:172.17.0.5:53950/UDP)|candidate:0 1 UDP 2122187007 172.17.0.5 46598 typ host)
[task 2019-03-07T21:34:57.696Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/STREAM(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund transport-id=transport_1 - a63327a1:3d58f11c040f97c33b48ce6611cc3966)/COMP(1)/CAND-PAIR(C0OW): cancelling FROZEN/WAITING pair C0OW|IP4:172.17.0.5:53950/UDP|IP4:172.17.0.5:46598/UDP(host(IP4:172.17.0.5:53950/UDP)|prflx) in trigger check queue because CAND-PAIR(zt3U) was nominated.
[task 2019-03-07T21:34:57.705Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/CAND-PAIR(C0OW): setting pair to state CANCELLED: C0OW|IP4:172.17.0.5:53950/UDP|IP4:172.17.0.5:46598/UDP(host(IP4:172.17.0.5:53950/UDP)|prflx)
[task 2019-03-07T21:34:57.708Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/STREAM(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund transport-id=transport_1 - a63327a1:3d58f11c040f97c33b48ce6611cc3966)/COMP(1)/CAND-PAIR(6VqA): cancelling FROZEN/WAITING pair 6VqA|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:5]:52715/UDP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:5]:53120/UDP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:5]:52715/UDP)|candidate:1 1 UDP 2122252543 fd15:4ba5:5a2b:100a:0:242:ac11:5 53120 typ host) because CAND-PAIR(zt3U) was nominated.
[task 2019-03-07T21:34:57.714Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/CAND-PAIR(6VqA): setting pair to state CANCELLED: 6VqA|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:5]:52715/UDP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:5]:53120/UDP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:5]:52715/UDP)|candidate:1 1 UDP 2122252543 fd15:4ba5:5a2b:100a:0:242:ac11:5 53120 typ host)
[task 2019-03-07T21:34:57.725Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/STREAM(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund transport-id=transport_1 - a63327a1:3d58f11c040f97c33b48ce6611cc3966)/COMP(1)/CAND-PAIR(RIZa): cancelling FROZEN/WAITING pair RIZa|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:5]:56663/TCP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:5]:57983/TCP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:5]:56663/TCP) active|candidate:3 1 TCP 2105508095 fd15:4ba5:5a2b:100a:0:242:ac11:5 57983 typ host tcptype passive) because CAND-PAIR(zt3U) was nominated.
[task 2019-03-07T21:34:57.727Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/CAND-PAIR(RIZa): setting pair to state CANCELLED: RIZa|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:5]:56663/TCP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:5]:57983/TCP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:5]:56663/TCP) active|candidate:3 1 TCP 2105508095 fd15:4ba5:5a2b:100a:0:242:ac11:5 57983 typ host tcptype passive)
[task 2019-03-07T21:34:57.733Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/STREAM(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund transport-id=transport_1 - a63327a1:3d58f11c040f97c33b48ce6611cc3966)/COMP(1)/CAND-PAIR(P9/j): cancelling FROZEN/WAITING pair P9/j|IP4:172.17.0.5:63654/TCP|IP4:172.17.0.5:62318/TCP(host(IP4:172.17.0.5:63654/TCP) active|candidate:2 1 TCP 2105442559 172.17.0.5 62318 typ host tcptype passive) because CAND-PAIR(zt3U) was nominated.
[task 2019-03-07T21:34:57.735Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/CAND-PAIR(P9/j): setting pair to state CANCELLED: P9/j|IP4:172.17.0.5:63654/TCP|IP4:172.17.0.5:62318/TCP(host(IP4:172.17.0.5:63654/TCP) active|candidate:2 1 TCP 2105442559 172.17.0.5 62318 typ host tcptype passive)
[task 2019-03-07T21:34:57.754Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default)/ICE-STREAM(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund transport-id=transport_1 - a63327a1:3d58f11c040f97c33b48ce6611cc3966): all active components have nominated candidate pairs
[task 2019-03-07T21:34:57.756Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE-PEER(PC:1551994482280725 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund:default): all checks completed success=2 fail=0
[task 2019-03-07T21:34:57.777Z] 21:34:57 INFO - GECKO(1853) | (stun/INFO) STUN-CLIENT(consent): Received response; processing
[task 2019-03-07T21:34:57.782Z] 21:34:57 INFO - GECKO(1853) | (ice/INFO) ICE(PC:1551994482294404 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund)/STREAM(PC:1551994482294404 (id=4294967764 url=http://mochi.test:8888/tests/dom/media/tests/mochitest/test_peerConnection_restartIceNoBund transport-id=transport_1 - cfadb427:20044376e82291979a3a4efdd0381e4f)/COMP(1): Consent refreshed
[task 2019-03-07T21:34:57.950Z] 21:34:57 INFO - TEST-INFO | started process screentopng
[task 2019-03-07T21:35:00.085Z] 21:35:00 INFO - TEST-INFO | screentopng: exit 0
[task 2019-03-07T21:35:00.088Z] 21:35:00 INFO - <snipped 1359 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2019-03-07T21:35:00.090Z] 21:35:00 INFO - Buffered messages logged at 21:34:57
[task 2019-03-07T21:35:00.092Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.095Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.098Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.101Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497634 (
[task 2019-03-07T21:35:00.104Z] 21:35:00 INFO - -804 ms)
[task 2019-03-07T21:35:00.106Z] 21:35:00 INFO - Checking stats for Mzxp : [object Object]
[task 2019-03-07T21:35:00.107Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.109Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.109Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.118Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497636 (
[task 2019-03-07T21:35:00.119Z] 21:35:00 INFO - -806 ms)
[task 2019-03-07T21:35:00.119Z] 21:35:00 INFO - Checking stats for e15m : [object Object]
[task 2019-03-07T21:35:00.121Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.122Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.123Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.124Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497636 (
[task 2019-03-07T21:35:00.126Z] 21:35:00 INFO - -806 ms)
[task 2019-03-07T21:35:00.127Z] 21:35:00 INFO - Checking stats for mpDy : [object Object]
[task 2019-03-07T21:35:00.128Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.130Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.131Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.132Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497637 (
[task 2019-03-07T21:35:00.132Z] 21:35:00 INFO - -807 ms)
[task 2019-03-07T21:35:00.135Z] 21:35:00 INFO - Checking stats for AWzJ : [object Object]
[task 2019-03-07T21:35:00.136Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.137Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.138Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.140Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497637 (
[task 2019-03-07T21:35:00.141Z] 21:35:00 INFO - -807 ms)
[task 2019-03-07T21:35:00.143Z] 21:35:00 INFO - Checking stats for jh1s : [object Object]
[task 2019-03-07T21:35:00.144Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.148Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.163Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.171Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497638 (
[task 2019-03-07T21:35:00.173Z] 21:35:00 INFO - -808 ms)
[task 2019-03-07T21:35:00.190Z] 21:35:00 INFO - Checking stats for JMtI : [object Object]
[task 2019-03-07T21:35:00.195Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.200Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.204Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.210Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497638 (
[task 2019-03-07T21:35:00.212Z] 21:35:00 INFO - -808 ms)
[task 2019-03-07T21:35:00.217Z] 21:35:00 INFO - Checking stats for ncj1 : [object Object]
[task 2019-03-07T21:35:00.219Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.223Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.229Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.231Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497640 (
[task 2019-03-07T21:35:00.234Z] 21:35:00 INFO - -810 ms)
[task 2019-03-07T21:35:00.236Z] 21:35:00 INFO - Checking stats for RCpu : [object Object]
[task 2019-03-07T21:35:00.239Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.241Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.243Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.245Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497640 (
[task 2019-03-07T21:35:00.252Z] 21:35:00 INFO - -810 ms)
[task 2019-03-07T21:35:00.256Z] 21:35:00 INFO - Checking stats for nuSw : [object Object]
[task 2019-03-07T21:35:00.258Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.264Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.267Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.270Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497641 (
[task 2019-03-07T21:35:00.275Z] 21:35:00 INFO - -811 ms)
[task 2019-03-07T21:35:00.282Z] 21:35:00 INFO - Checking stats for 6cCy : [object Object]
[task 2019-03-07T21:35:00.285Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.288Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.291Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.293Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497642 (
[task 2019-03-07T21:35:00.295Z] 21:35:00 INFO - -812 ms)
[task 2019-03-07T21:35:00.300Z] 21:35:00 INFO - Checking stats for ZQJl : [object Object]
[task 2019-03-07T21:35:00.302Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.307Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.308Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.309Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497643 (
[task 2019-03-07T21:35:00.312Z] 21:35:00 INFO - -813 ms)
[task 2019-03-07T21:35:00.313Z] 21:35:00 INFO - Checking stats for qMFB : [object Object]
[task 2019-03-07T21:35:00.314Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.317Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.317Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.319Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497647 (
[task 2019-03-07T21:35:00.320Z] 21:35:00 INFO - -817 ms)
[task 2019-03-07T21:35:00.321Z] 21:35:00 INFO - Checking stats for B6OK : [object Object]
[task 2019-03-07T21:35:00.322Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.325Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.325Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.325Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497648 (
[task 2019-03-07T21:35:00.325Z] 21:35:00 INFO - -818 ms)
[task 2019-03-07T21:35:00.325Z] 21:35:00 INFO - Checking stats for GMxx : [object Object]
[task 2019-03-07T21:35:00.325Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.325Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.325Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.325Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497650 (
[task 2019-03-07T21:35:00.325Z] 21:35:00 INFO - -820 ms)
[task 2019-03-07T21:35:00.325Z] 21:35:00 INFO - Checking stats for rOHb : [object Object]
[task 2019-03-07T21:35:00.331Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.334Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.337Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.339Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497658 (
[task 2019-03-07T21:35:00.340Z] 21:35:00 INFO - -828 ms)
[task 2019-03-07T21:35:00.342Z] 21:35:00 INFO - Checking stats for caF8 : [object Object]
[task 2019-03-07T21:35:00.344Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.346Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.347Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.350Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497660 (
[task 2019-03-07T21:35:00.351Z] 21:35:00 INFO - -830 ms)
[task 2019-03-07T21:35:00.353Z] 21:35:00 INFO - Checking stats for I11f : [object Object]
[task 2019-03-07T21:35:00.355Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.356Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.358Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.360Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497665 (
[task 2019-03-07T21:35:00.363Z] 21:35:00 INFO - -835 ms)
[task 2019-03-07T21:35:00.365Z] 21:35:00 INFO - Checking stats for zNkR : [object Object]
[task 2019-03-07T21:35:00.367Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.368Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.370Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.375Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497667 (
[task 2019-03-07T21:35:00.376Z] 21:35:00 INFO - -837 ms)
[task 2019-03-07T21:35:00.380Z] 21:35:00 INFO - Checking stats for tK6W : [object Object]
[task 2019-03-07T21:35:00.382Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.384Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.386Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.387Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497672 (
[task 2019-03-07T21:35:00.389Z] 21:35:00 INFO - -842 ms)
[task 2019-03-07T21:35:00.390Z] 21:35:00 INFO - Checking stats for AlDM : [object Object]
[task 2019-03-07T21:35:00.392Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.396Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.398Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.400Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497672 (
[task 2019-03-07T21:35:00.402Z] 21:35:00 INFO - -842 ms)
[task 2019-03-07T21:35:00.403Z] 21:35:00 INFO - Checking stats for +RwO : [object Object]
[task 2019-03-07T21:35:00.407Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.409Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.410Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.412Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497680 (
[task 2019-03-07T21:35:00.414Z] 21:35:00 INFO - -850 ms)
[task 2019-03-07T21:35:00.416Z] 21:35:00 INFO - Checking stats for fRvM : [object Object]
[task 2019-03-07T21:35:00.418Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Coherent stats id
[task 2019-03-07T21:35:00.419Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 >= 1551994482036 (
[task 2019-03-07T21:35:00.421Z] 21:35:00 INFO - 14794 ms)
[task 2019-03-07T21:35:00.423Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Valid rtp timestamp 1551994496830 <= 1551994497729 (
[task 2019-03-07T21:35:00.424Z] 21:35:00 INFO - -899 ms)
[task 2019-03-07T21:35:00.429Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Have at least 2 inbound-rtp stat(s) *
[task 2019-03-07T21:35:00.430Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Have 2 outbound-rtp stat(s)
[task 2019-03-07T21:35:00.432Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Have local-candidate stat(s)
[task 2019-03-07T21:35:00.433Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Have remote-candidate stat(s)
[task 2019-03-07T21:35:00.436Z] 21:35:00 INFO - Run step 45: PC_LOCAL_CHECK_ICE_CONNECTION_TYPE
[task 2019-03-07T21:35:00.438Z] 21:35:00 INFO - PeerConnectionWrapper (pcLocal): Got stats: {}
[task 2019-03-07T21:35:00.439Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Got local candidate ID WcSs for selected pair
[task 2019-03-07T21:35:00.441Z] 21:35:00 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Got remote candidate ID MELW for selected pair
[task 2019-03-07T21:35:00.442Z] 21:35:00 INFO - checkStatsIceConnectionType verifying: local={"id":"WcSs","timestamp":1551994497498,"type":"remote-candidate","address":"fd15:4ba5:5a2b:100a:0:242:ac11:5","candidateType":"prflx","port":51384,"priority":1853817086,"protocol":"udp"} remote={"id":"MELW","timestamp":1551994497498,"type":"remote-candidate","address":"172.17.0.5","candidateType":"host","port":55554,"priority":2122187007,"protocol":"udp"}
[task 2019-03-07T21:35:00.445Z] 21:35:00 INFO - Buffered messages finished
[task 2019-03-07T21:35:00.447Z] 21:35:00 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_restartIceNoBundleNoRtcpMux.html | Local candidate type is what we expected for selected pair - got "prflx", expected "host"

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

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