Intermittent dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | Error in test execution: Error: Timeout waiting for rtp recv packet on level 0 timeout/<@https://example.com/tests/dom/media/tests/mochitest/head.js:652:42
Categories
(Core :: WebRTC, defect, P5)
Tracking
()
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure, regression)
Filed by: cbrindusan [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=261737990&repo=mozilla-inbound
Full log: https://queue.taskcluster.net/v1/task/bMJcnrKcQiag8noa27_enA/runs/0/artifacts/public/logs/live_backing.log
[task 2019-08-15T06:37:48.714Z] 06:37:48 INFO - TEST-START | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html
[task 2019-08-15T06:37:48.736Z] 06:37:48 INFO - GECKO(3112) | --DOCSHELL 000001CF1365E000 == 2 [pid = 7176] [id = {64e088a6-3335-4b03-93ea-b8bffcf5d119}] [url = about:blank]
[task 2019-08-15T06:37:48.746Z] 06:37:48 INFO - [12108, Main Thread] WARNING: No active window: file z:/task_1565840961/build/src/js/xpconnect/src/XPCJSContext.cpp, line 664
[task 2019-08-15T06:37:48.776Z] 06:37:48 INFO - GECKO(3112) | ++DOMWINDOW == 8 (000001CF137ED800) [pid = 7176] [serial = 384] [outer = 000001CF7A0895C0]
[task 2019-08-15T06:37:48.835Z] 06:37:48 INFO - GECKO(3112) | ++DOCSHELL 000001CF13660800 == 3 [pid = 7176] [id = {758870d2-b379-49ae-b70c-0a1cb0c60d9a}]
[task 2019-08-15T06:37:48.835Z] 06:37:48 INFO - GECKO(3112) | ++DOMWINDOW == 9 (000001CF133FB020) [pid = 7176] [serial = 385] [outer = 0000000000000000]
[task 2019-08-15T06:37:48.840Z] 06:37:48 INFO - GECKO(3112) | ++DOMWINDOW == 10 (000001CF137FB800) [pid = 7176] [serial = 386] [outer = 000001CF133FB020]
[task 2019-08-15T06:37:48.870Z] 06:37:48 INFO - GECKO(3112) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
[task 2019-08-15T06:37:48.870Z] 06:37:48 INFO - GECKO(3112) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
[task 2019-08-15T06:37:49.579Z] 06:37:49 INFO - GECKO(3112) | --DOMWINDOW == 9 (000001CF133FB7A0) [pid = 7176] [serial = 381] [outer = 0000000000000000] [url = about:blank]
[task 2019-08-15T06:37:49.579Z] 06:37:49 INFO - GECKO(3112) | --DOMWINDOW == 8 (000001CF7D7E9400) [pid = 7176] [serial = 382] [outer = 0000000000000000] [url = about:blank]
[task 2019-08-15T06:37:49.579Z] 06:37:49 INFO - GECKO(3112) | --DOMWINDOW == 7 (000001CF137EE400) [pid = 7176] [serial = 383] [outer = 0000000000000000] [url = https://example.com/tests/SimpleTest/iframe-between-tests.html]
[task 2019-08-15T06:37:49.579Z] 06:37:49 INFO - GECKO(3112) | --DOMWINDOW == 6 (000001CF79E2F000) [pid = 7176] [serial = 380] [outer = 0000000000000000] [url = https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_capturedVideo.html]
[task 2019-08-15T06:37:50.082Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:330: PeerConnectionImpl: PeerConnectionImpl constructor for
[task 2019-08-15T06:37:50.082Z] 06:37:50 INFO - GECKO(3112) | [Parent 10220: Socket Thread]: D/mtransport NrIceCtx static call to find local stun addresses
[task 2019-08-15T06:37:50.082Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:330: PeerConnectionImpl: PeerConnectionImpl constructor for
[task 2019-08-15T06:37:50.084Z] 06:37:50 INFO - GECKO(3112) | [Parent 10220: Socket Thread]: D/mtransport NrIceCtx static call to find local stun addresses
[task 2019-08-15T06:37:50.103Z] 06:37:50 INFO - GECKO(3112) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
[task 2019-08-15T06:37:50.103Z] 06:37:50 INFO - GECKO(3112) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
[task 2019-08-15T06:37:50.103Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Main Thread]: I/signaling [main|PeerConnectionMedia] PeerConnectionMedia.cpp:729: SetProxy: Had proxyinfo
[task 2019-08-15T06:37:50.104Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Main Thread]: I/signaling [main|PeerConnectionMedia] PeerConnectionMedia.cpp:50: OnStunAddrsAvailable: receiving (4) stun addrs
[task 2019-08-15T06:37:50.104Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Main Thread]: I/signaling [main|PeerConnectionMedia] PeerConnectionMedia.cpp:729: SetProxy: Had proxyinfo
[task 2019-08-15T06:37:50.104Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Main Thread]: I/signaling [main|PeerConnectionMedia] PeerConnectionMedia.cpp:50: OnStunAddrsAvailable: receiving (4) stun addrs
[task 2019-08-15T06:37:50.123Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: 'NS_FAILED(branch->GetBoolPref( "media.video.test_latency", &mVideoLatencyTestEnable))', file z:/task_1565840961/build/src/media/webrtc/signaling/src/media-conduit/VideoConduit.cpp, line 1280
[task 2019-08-15T06:37:50.129Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: 'NS_FAILED(branch->GetBoolPref( "media.video.test_latency", &mVideoLatencyTestEnable))', file z:/task_1565840961/build/src/media/webrtc/signaling/src/media-conduit/VideoConduit.cpp, line 1282
[task 2019-08-15T06:37:50.129Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: 'NS_FAILED(branch->GetIntPref( "media.peerconnection.video.svc.spatial", &temp))', file z:/task_1565840961/build/src/media/webrtc/signaling/src/media-conduit/VideoConduit.cpp, line 1319
[task 2019-08-15T06:37:50.129Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: 'NS_FAILED(branch->GetIntPref( "media.peerconnection.video.svc.temporal", &temp))', file z:/task_1565840961/build/src/media/webrtc/signaling/src/media-conduit/VideoConduit.cpp, line 1325
[task 2019-08-15T06:37:50.130Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: 'NS_FAILED(branch->GetBoolPref( "media.peerconnection.video.lock_scaling", &mLockScaling))', file z:/task_1565840961/build/src/media/webrtc/signaling/src/media-conduit/VideoConduit.cpp, line 1333
[task 2019-08-15T06:37:50.230Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: Can't add a range if the end is older that the start.: file z:/task_1565840961/build/src/dom/html/TimeRanges.cpp, line 73[task 2019-08-15T06:37:50.232Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: Can't add a range if the end is older that the start.: file z:/task_1565840961/build/src/dom/html/TimeRanges.cpp, line 73
[task 2019-08-15T06:37:50.251Z] 06:37:50 INFO - GECKO(3112) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
[task 2019-08-15T06:37:50.251Z] 06:37:50 INFO - GECKO(3112) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
[task 2019-08-15T06:37:50.291Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: 'NS_FAILED(branch->GetBoolPref( "media.video.test_latency", &mVideoLatencyTestEnable))', file z:/task_1565840961/build/src/media/webrtc/signaling/src/media-conduit/VideoConduit.cpp, line 1280
[task 2019-08-15T06:37:50.292Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: 'NS_FAILED(branch->GetBoolPref( "media.video.test_latency", &mVideoLatencyTestEnable))', file z:/task_1565840961/build/src/media/webrtc/signaling/src/media-conduit/VideoConduit.cpp, line 1282
[task 2019-08-15T06:37:50.294Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: 'NS_FAILED(branch->GetIntPref( "media.peerconnection.video.svc.spatial", &temp))', file z:/task_1565840961/build/src/media/webrtc/signaling/src/media-conduit/VideoConduit.cpp, line 1319
[task 2019-08-15T06:37:50.294Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: 'NS_FAILED(branch->GetIntPref( "media.peerconnection.video.svc.temporal", &temp))', file z:/task_1565840961/build/src/media/webrtc/signaling/src/media-conduit/VideoConduit.cpp, line 1325
[task 2019-08-15T06:37:50.296Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: 'NS_FAILED(branch->GetBoolPref( "media.peerconnection.video.lock_scaling", &mLockScaling))', file z:/task_1565840961/build/src/media/webrtc/signaling/src/media-conduit/VideoConduit.cpp, line 1333
[task 2019-08-15T06:37:50.336Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: Can't add a range if the end is older that the start.: file z:/task_1565840961/build/src/dom/html/TimeRanges.cpp, line 73
[task 2019-08-15T06:37:50.337Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: Can't add a range if the end is older that the start.: file z:/task_1565840961/build/src/dom/html/TimeRanges.cpp, line 73
[task 2019-08-15T06:37:50.356Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: 000001CF7D159270
[task 2019-08-15T06:37:50.356Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Main Thread]: I/jsep [1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook]: stable -> have-local-offer
[task 2019-08-15T06:37:50.358Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: 000001CF7D159AC0
[task 2019-08-15T06:37:50.360Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Main Thread]: I/jsep [1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook]: stable -> have-remote-offer
[task 2019-08-15T06:37:50.399Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: Can't add a range if the end is older that the start.: file z:/task_1565840961/build/src/dom/html/TimeRanges.cpp, line 73
[task 2019-08-15T06:37:50.404Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: Can't add a range if the end is older that the start.: file z:/task_1565840961/build/src/dom/html/TimeRanges.cpp, line 73
[task 2019-08-15T06:37:50.443Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: 000001CF7D7A3120
[task 2019-08-15T06:37:50.443Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Main Thread]: I/jsep [1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook]: have-remote-offer -> stable
[task 2019-08-15T06:37:50.443Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Main Thread]: I/signaling [main|WebrtcAudioSessionConduit] AudioConduit.cpp:247: SetDtmfPayloadType : setting dtmf payload 101
[task 2019-08-15T06:37:50.462Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: 000001CF7D7AE3C0
[task 2019-08-15T06:37:50.462Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Main Thread]: I/jsep [1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook]: have-local-offer -> stable
[task 2019-08-15T06:37:50.463Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Main Thread]: I/signaling [main|WebrtcAudioSessionConduit] AudioConduit.cpp:247: SetDtmfPayloadType : setting dtmf payload 101
[task 2019-08-15T06:37:50.502Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: Can't add a range if the end is older that the start.: file z:/task_1565840961/build/src/dom/html/TimeRanges.cpp, line 73
[task 2019-08-15T06:37:50.502Z] 06:37:50 INFO - GECKO(3112) | [Child 7176, Main Thread] WARNING: Can't add a range if the end is older that the start.: file z:/task_1565840961/build/src/dom/html/TimeRanges.cpp, line 73
[task 2019-08-15T06:37:50.542Z] 06:37:50 INFO - GECKO(3112) | (generic/EMERG) Exit UDP socket connected
[task 2019-08-15T06:37:50.543Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) z:/task_1565840961/build/src/media/mtransport/third_party/nICEr/src/net/nr_socket_multi_tcp.c:617 function nr_socket_multi_tcp_listen failed with error 3
[task 2019-08-15T06:37:50.544Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): failed to create passive TCP host candidate: 3
[task 2019-08-15T06:37:50.544Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) z:/task_1565840961/build/src/media/mtransport/third_party/nICEr/src/net/nr_socket_multi_tcp.c:617 function nr_socket_multi_tcp_listen failed with error 3
[task 2019-08-15T06:37:50.544Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): failed to create passive TCP host candidate: 3
[task 2019-08-15T06:37:50.546Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) z:/task_1565840961/build/src/media/mtransport/third_party/nICEr/src/net/nr_socket_multi_tcp.c:617 function nr_socket_multi_tcp_listen failed with error 3
[task 2019-08-15T06:37:50.548Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): failed to create passive TCP host candidate: 3
[task 2019-08-15T06:37:50.553Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) z:/task_1565840961/build/src/media/mtransport/third_party/nICEr/src/net/nr_socket_multi_tcp.c:617 function nr_socket_multi_tcp_listen failed with error 3[task 2019-08-15T06:37:50.553Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): failed to create passive TCP host candidate: 3
[task 2019-08-15T06:37:50.553Z] 06:37:50 INFO - GECKO(3112) | (generic/EMERG) Exit UDP socket connected
[task 2019-08-15T06:37:50.558Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) z:/task_1565840961/build/src/media/mtransport/third_party/nICEr/src/net/nr_socket_multi_tcp.c:617 function nr_socket_multi_tcp_listen failed with error 3
[task 2019-08-15T06:37:50.558Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) ICE(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): failed to create passive TCP host candidate: 3
[task 2019-08-15T06:37:50.564Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) ICE(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): peer (PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default) has no stream matching stream PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0 - ae3706f8:3f1602fb48e80fafc5870b4d5744df43
[task 2019-08-15T06:37:50.565Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Setting up DTLS as client
[task 2019-08-15T06:37:50.565Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: E/mtransport Couldn't disable 'PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0':2
[task 2019-08-15T06:37:50.565Z] 06:37:50 INFO - GECKO(3112) | (ice/NOTICE) ICE(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): peer (PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default) no streams with non-empty check lists
[task 2019-08-15T06:37:50.565Z] 06:37:50 INFO - GECKO(3112) | (ice/NOTICE) ICE(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): peer (PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default) no streams with pre-answer requests
[task 2019-08-15T06:37:50.565Z] 06:37:50 INFO - GECKO(3112) | (ice/NOTICE) ICE(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): peer (PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default) no checks to start
[task 2019-08-15T06:37:50.566Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Couldn't start peer checks on PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook, assuming trickle ICE
[task 2019-08-15T06:37:50.568Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): peer (PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default) has no stream matching stream PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0 - 1ba10ea6:ec56ee9385251a187e40ed270d082300
[task 2019-08-15T06:37:50.568Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Setting up DTLS as server
[task 2019-08-15T06:37:50.568Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): peer (PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default) has no stream matching stream PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_1 - 1ba10ea6:ec56ee9385251a187e40ed270d082300
[task 2019-08-15T06:37:50.578Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook)/CAND(host(IP4:10.143.45.226:56064/UDP)): failed to initialize, 7 remaining
[task 2019-08-15T06:37:50.578Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/m
[task 2019-08-15T06:37:50.578Z] 06:37:50 INFO - GECKO(3112) | edia/tests/mochitest/test_peerConnection_checkPacketDumpHook)/CAND(host(IP4:10.143.45.226:64014/TCP) active): failed to initialize, 6 remaining
[task 2019-08-15T06:37:50.578Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook)/CAND(host(IP4:10.143.45.226:56065/UDP)): failed to initialize, 5 remaining
[task 2019-08-15T06:37:50.580Z] 06:37:50 INFO - GECKO(3112) | (ice/WARNING) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook)/CAND(host(IP4:10.143.45.226:55588/TCP) active): failed to initialize, 4 remaining
[task 2019-08-15T06:37:50.580Z] 06:37:50 INFO - GECKO(3112) | (ice/NOTICE) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): pee
[task 2019-08-15T06:37:50.582Z] 06:37:50 INFO - GECKO(3112) | r (PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/moc
[task 2019-08-15T06:37:50.582Z] 06:37:50 INFO - GECKO(3112) | hitest/test_peerConnection_checkPacketDumpHook:default) no streams with non-empty check lists
[task 2019-08-15T06:37:50.587Z] 06:37:50 INFO - GECKO(3112) | (ice/NOTICE) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): peer (PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default) no streams with pre-answer requests
[task 2019-08-15T06:37:50.588Z] 06:37:50 INFO - GECKO(3112) | (ice/NOTICE) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): peer (PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default) no checks to start
[task 2019-08-15T06:37:50.588Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Couldn't start peer checks on PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook, assuming trickle ICE
[task 2019-08-15T06:37:50.588Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport NrIceCtx(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): trickling candidate candidate:0 1 UDP 2122252543 10.143.45.226 56062 typ host
[task 2019-08-15T06:37:50.593Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0', no candidates.
[task 2019-08-15T06:37:50.593Z] 06:37:50 INFO - GECKO(3112) | (ice/ERR) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): peer (PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default) pairing local trickle ICE candidate host(IP4:10.143.45.226:56062/UDP)
[task 2019-08-15T06:37:50.593Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport NrIceCtx(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): trickling candidate candidate:1 1 TCP 2105524479 10.143.45.226 9 typ host tcptype active
[task 2019-08-15T06:37:50.598Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0', no candidates.
[task 2019-08-15T06:37:50.598Z] 06:37:50 INFO - GECKO(3112) | (ice/ERR) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConne
[task 2019-08-15T06:37:50.598Z] 06:37:50 INFO - GECKO(3112) | ction_checkPa
[task 2019-08-15T06:37:50.600Z] 06:37:50 INFO - GECKO(3112) | cketDumpHook): peer (PC:1565851070076000 (i
[task 2019-08-15T06:37:50.601Z] 06:37:50 INFO - GECKO(3112) | d=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default) pairing local trickle ICE candidate host(IP4:10.143.45.226:50081/TCP) active
[task 2019-08-15T06:37:50.603Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport NrIceCtx(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): trickling candidate candidate:0 2 UDP 2122252542 10.143.45.226 56063 typ host
[task 2019-08-15T06:37:50.608Z] 06:37:50 INFO - GECKO(3112) | (ice/ERR) ICE(PC:1565851070076000 (id=2147
[task 2019-08-15T06:37:50.608Z] 06:37:50 INFO - GECKO(3112) | 484030 url=https://example.com/tests/dom/media/tests/mochi
[task 2019-08-15T06:37:50.610Z] 06:37:50 INFO - GECKO(3112) | test/test_peerConnec
[task 2019-08-15T06:37:50.610Z] 06:37:50 INFO - GECKO(3112) | tion_checkPacketDumpHook): peer (PC:1565851070076000 (id=2147484030 url=https[task 2019-08-15T06:37:50.615Z] 06:37:50 INFO - GECKO(3112) | ://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default) pairing local trickle ICE candidate host(IP4:10.143.45.226:56063/UDP)
[task 2019-08-15T06:37:50.615Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport NrIceCtx(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): trickling candidate candidate:1 2 TCP 2105524478 10.143.45.226 9 typ host tcptype active
[task 2019-08-15T06:37:50.615Z] 06:37:50 INFO - GECKO(3112) | (ice/ERR) ICE
[task 2019-08-15T06:37:50.620Z] 06:37:50 INFO - GECKO(3112) | (PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_che
[task 2019-08-15T06:37:50.620Z] 06:37:50 INFO - GECKO(3112) | ckP
[task 2019-08-15T06:37:50.620Z] 06:37:50 INFO - GECKO(3112) | acketDumpH
[task 2019-08-15T06:37:50.622Z] 06:37:50 INFO - GECKO(3112) | ook): peer (PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom
[task 2019-08-15T06:37:50.627Z] 06:37:50 INFO - GECKO(3112) | /media/tests/mochitest/test_peerConnection_checkPacketDumpHook:de
[task 2019-08-15T06:37:50.627Z] 06:37:50 INFO - GECKO(3112) | fault) pairing local trickle ICE candidate host(IP4:10.143.45.226:63817/TCP) active
[task 2019-08-15T06:37:50.632Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE(PC:1565851070076000 (id=2147484030 url=https://example.c
[task 2019-08-15T06:37:50.632Z] 06:37:50 INFO - GECKO(3112) | om/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): All candidates initialized
[task 2019-08-15T06:37:50.633Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport NrIceCtx(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): trickling candidate candidate:0 1 UDP 2122252543 10.143.45.226 56067 typ host
[task 2019-08-15T06:37:50.635Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0', no candidates.
[task 2019-08-15T06:37:50.637Z] 06:37:50 INFO - GECKO(3112) | (ice/ERR) ICE(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): peer (PC:1565
[task 2019-08-15T06:37:50.639Z] 06:37:50 INFO - GECKO(3112) | 851070081000 (id=2147484030 url=https://exampl
[task 2019-08-15T06:37:50.639Z] 06:37:50 INFO - GECKO(3112) | e.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default) pairing local trick
[task 2019-08-15T06:37:50.640Z] 06:37:50 INFO - GECKO(3112) | l
[task 2019-08-15T06:37:50.640Z] 06:37:50 INFO - GECKO(3112) | e ICE candidate host(IP4:10.143.45.226:56067/UDP)
[task 2019-08-15T06:37:50.650Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport NrIceCtx(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): trickling candidate candidate:1 1 TCP 2105524479 10.143.45.226 9 typ host tcptype active
[task 2019-08-15T06:37:50.650Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0', no candidates.
[task 2019-08-15T06:37:50.652Z] 06:37:50 INFO - GECKO(3112) | (
[task 2019-08-15T06:37:50.654Z] 06:37:50 INFO - GECKO(3112) | ice/ERR) ICE(PC:1565851070081000 (id=2147484030 url=htt
[task 2019-08-15T06:37:50.659Z] 06:37:50 INFO - GECKO(3112) | ps://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): peer (PC:1565851070081000
[task 2019-08-15T06:37:50.659Z] 06:37:50 INFO - GECKO(3112) | (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default) pairing local trickle ICE candidate host(IP4:10.143.45.226:60452/TCP) active
[task 2019-08-15T06:37:50.660Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0', no candidates.
[task 2019-08-15T06:37:50.662Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE(PC:1565851070081000 (id=2147484030 url=https://example.
[task 2019-08-15T06:37:50.662Z] 06:37:50 INFO - GECKO(3112) | com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): All candi
[task 2019-08-15T06:37:50.667Z] 06:37:50 INFO - GECKO(3112) | d
[task 2019-08-15T06:37:50.667Z] 06:37:50 INFO - GECKO(3112) | ates initialized
[task 2019-08-15T06:37:50.672Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-P
[task 2019-08-15T06:37:50.674Z] 06:37:50 INFO - GECKO(3112) | EER(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_pe[task 2019-08-15T06:37:50.717Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) CAND-PAIR(Es0F): Adding pair to check list and trigger check queue: Es0F|IP4:10.143.45.226:56067/UDP|IP4:10.143.45.226:56062/UDP(host(IP4:10.143.45.226:56067/UDP)|candidate:0 1 UDP 2122252543 10.143.45.226 56062 typ host)
[task 2019-08-15T06:37:50.717Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-PEER(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default)/CAND-PAIR(Es0F): setting pair to state WAITING: Es0F|IP4:10.143.45.226:56067/UDP|IP4:10.143.45.226:56062/UDP(host(IP4:10.143.45.226:56067/UDP)|candidate:0 1 UDP 2122252543 10.143.45.226 56062 typ host)
[task 2019-08-15T06:37:50.719Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-PEER(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default)/CAND-PAIR(Es0F): setting pair to state CANCELLED: Es0F|IP4:10.143.45.226:56067/UDP|IP4:10.143.45.226:56062/UDP(host(IP4:10.143.45.226:56067/UDP)|candidate:0 1 UDP 2122252543 10.143.45.226 56062 typ host)
[task 2019-08-15T06:37:50.724Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-PEER(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default)/CAND-PAIR(Es0F): setting pair to state IN_PROGRESS: Es0F|IP4:10.143.45.226:56067/UDP|IP4:10.143.45.226:56062/UDP(host(IP4:10.143.45.226:56067/UDP)|candidate:0 1 UDP 2122252543 10.143.45.226 56062 typ host)
[task 2019-08-15T06:37:50.724Z] 06:37:50 INFO - GECKO(3112) | (stun/INFO) STUN-CLIENT(Es0F|IP4:10.143.45.226:56067/UDP|IP4:10.143.45.226:56062/UDP(host(IP4:10.143.45.226:56067/UDP)|candidate:0 1 UDP 2122252543 10.143.45.226 56062 typ host)): Received response; processing
[task 2019-08-15T06:37:50.729Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-PEER(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default)/CAND-PAIR(Es0F): setting pair to state SUCCEEDED: Es0F|IP4:10.143.45.226:56067/UDP|IP4:10.143.45.226:56062/UDP(host(IP4:10.143.45.226:56067/UDP)|candidate:0 1 UDP 2122252543 10.143.45.226 56062 typ host)
[task 2019-08-15T06:37:50.729Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-PEER(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default)/STREAM(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0 - ae3706f8:3f1602fb48e80fafc5870b4d5744df43)/COMP(1)/CAND-PAIR(Es0F): nominated pair is Es0F|IP4:10.143.45.226:56067/UDP|IP4:10.143.45.226:56062/UDP(host(IP4:10.143.45.226:56067/UDP)|candidate:0 1 UDP 2122252543 10.143.45.226 56062 typ host)
[task 2019-08-15T06:37:50.730Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-PEER(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default)/STREAM(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0 - ae3706f8:3f1602fb48e80fafc5870b4d5744df43)/COMP(1)/CAND-PAIR(Es0F): cancelling all pairs but Es0F|IP4:10.143.45.226:56067/UDP|IP4:10.143.45.226:56062/UDP(host(IP4:10.143.45.226:56067/UDP)|candidate:0 1 UDP 2122252543 10.143.45.226 56062 typ host)
[task 2019-08-15T06:37:50.732Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-PEER(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default)/ICE-STREAM(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0 - ae3706f8:3f1602fb48e80fafc5870b4d5744df43): all active components have nominated candidate pairs
[task 2019-08-15T06:37:50.732Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[ice]: ICE Ready(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0,1)
[task 2019-08-15T06:37:50.734Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[dtls]: Lower layer is now open; starting TLS
[task 2019-08-15T06:37:50.735Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-PEER(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default): all checks completed success=1 fail=0
[task 2019-08-15T06:37:50.735Z] 06:37:50 INFO - GECKO(3112) | (stun/INFO) STUN-CLIENT(BaLo|IP4:10.143.45.226:56062/UDP|IP4:10.143.45.226:56067/UDP(host(IP4:10.143.45.226:56062/UDP)|prflx)): Received response; processing
[task 2019-08-15T06:37:50.737Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-PEER(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default)/CAND-PAIR(BaLo): setting pair to state SUCCEEDED: BaLo|IP4:10.143.45.226:56062/UDP|IP4:10.143.45.226:56067/UDP(host(IP4:10.143.45.226:56062/UDP)|prflx)
[task 2019-08-15T06:37:50.739Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-PEER(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default)/STREAM(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0 - 1ba10ea6:ec56ee9385251a187e40ed270d082300)/COMP(1)/CAND-PAIR(BaLo): nominated pair is BaLo|IP4:10.143.45.226:56062/UDP|IP4:10.143.45.226:56067/UDP(host(IP4:10.143.45.226:56062/UDP)|prflx)
[task 2019-08-15T06:37:50.741Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-PEER(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default)/STREAM(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0 - 1ba10ea6:ec56ee9385251a187e40ed270d082300)/COMP(1)/CAND-PAIR(BaLo): cancelling all pairs but BaLo|IP4:10.143.45.226:56062/UDP|IP4:10.143.45.226:56067/UDP(host(IP4:10.143.45.226:56062/UDP)|prflx)
[task 2019-08-15T06:37:50.743Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-PEER(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default)/STREAM(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0 - 1ba10ea6:ec56ee9385251a187e40ed270d082300)/COMP(1)/CAND-PAIR(BaLo): cancelling FROZEN/WAITING pair BaLo|IP4:10.143.45.226:56062/UDP|IP4:10.143.45.226:56067/UDP(host(IP4:10.143.45.226:56062/UDP)|prflx) in trigger check queue because CAND-PAIR(BaLo) was nominated.
[task 2019-08-15T06:37:50.745Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-PEER(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default)/CAND-PAIR(BaLo): setting pair to state CANCELLED: BaLo|IP4:10.143.45.226:56062/UDP|IP4:10.143.45.226:56067/UDP(host(IP4:10.143.45.226:56062/UDP)|prflx)
[task 2019-08-15T06:37:50.750Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-PEER(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default)/ICE-STREAM(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0 - 1ba10ea6:ec56ee9385251a187e40ed270d082300): all active components have nominated candidate pairs
[task 2019-08-15T06:37:50.750Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[ice]: ICE Ready(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0,1)
[task 2019-08-15T06:37:50.751Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[dtls]: Lower layer is now open; starting TLS
[task 2019-08-15T06:37:50.751Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-PEER(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default): all checks completed success=1 fail=0
[task 2019-08-15T06:37:50.752Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
[task 2019-08-15T06:37:50.754Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport NrIceCtx(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): state 1->2
[task 2019-08-15T06:37:50.754Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
[task 2019-08-15T06:37:50.754Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport NrIceCtx(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): state 1->2
[task 2019-08-15T06:37:50.755Z] 06:37:50 INFO - GECKO(3112) | (stun/INFO) STUN-CLIENT(Es0F|IP4:10.143.45.226:56067/UDP|IP4:10.143.45.226:56062/UDP(host(IP4:10.143.45.226:56067/UDP)|candidate:0 1 UDP 2122252543 10.143.45.226 56062 typ host)): Received response; processing
[task 2019-08-15T06:37:50.755Z] 06:37:50 INFO - GECKO(3112) | (ice/INFO) ICE-PEER(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default)/CAND-PAIR(Es0F): setting pair to state SUCCEEDED: Es0F|IP4:10.143.45.226:56067/UDP|IP4:10.143.45.226:56062/UDP(host(IP4:10.143.45.226:56067/UDP)|candidate:0 1 UDP 2122252543 10.143.45.226 56062 typ host)
[task 2019-08-15T06:37:50.756Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
[task 2019-08-15T06:37:50.761Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
[task 2019-08-15T06:37:50.766Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: ****** SSL handshake completed ******
[task 2019-08-15T06:37:50.766Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Selected ALPN string: webrtc
[task 2019-08-15T06:37:50.766Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Created SRTP flow!
[task 2019-08-15T06:37:50.766Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: ****** SSL handshake completed ******
[task 2019-08-15T06:37:50.767Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Selected ALPN string: webrtc
[task 2019-08-15T06:37:50.767Z] 06:37:50 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Created SRTP flow!
[task 2019-08-15T06:37:51.074Z] 06:37:51 INFO - GECKO(3112) | (ice/ERR) ICE(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpH
[task 2019-08-15T06:37:51.076Z] 06:37:51 INFO - GECKO(3112) | ook): peer (PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default), stream(PC:1565851070081000 (id=21474
[task 2019-08-15T06:37:51.078Z] 06:37:51 INFO - GECKO(3112) | 84030 url=https://example.com/t
[task 2019-08-15T06:37:51.078Z] 06:37:51 INFO - GECKO(3112) | ests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport
[task 2019-08-15T06:37:51.080Z] 06:37:51 INFO - GECKO(3112) | _0 - ae3706f8:3f1602fb48e80fafc5870b4d5744df43) tried to trickle ICE in inappropriate st
[task 2019-08-15T06:37:51.080Z] 06:37:51 INFO - GECKO(3112) | ate 4[task 2019-08-15T06:37:51.080Z] 06:37:51 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Trickle candidate is redundant for stream 'PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0' because it is completed:
[task 2019-08-15T06:37:51.099Z] 06:37:51 INFO - GECKO(3112) | (ice/ERR) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): peer (PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default)
[task 2019-08-15T06:37:51.099Z] 06:37:51 INFO - GECKO(3112) | , stream(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tes
[task 2019-08-15T06:37:51.100Z] 06:37:51 INFO - GECKO(3112) | ts/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0 - 1ba10ea6:ec56ee9385251a187e40e
[task 2019-08-15T06:37:51.100Z] 06:37:51 INFO - GECKO(3112) | d270d082300) tried to trickle ICE in inappropriate
[task 2019-08-15T06:37:51.102Z] 06:37:51 INFO - GECKO(3112) | state 4
[task 2019-08-15T06:37:51.102Z] 06:37:51 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Trickle candidate is redundant for stream 'PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0' because it is completed: candidate:0 1 UDP 2122252543 10.143.45.226 56067 typ host
[task 2019-08-15T06:37:51.121Z] 06:37:51 INFO - GECKO(3112) | (ice/ERR) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook): peer (PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/te
[task 2019-08-15T06:37:51.121Z] 06:37:51 INFO - GECKO(3112) | st_peerConnection_check
[task 2019-08-15T06:37:51.121Z] 06:37:51 INFO - GECKO(3112) | PacketDumpHook:default),
[task 2019-08-15T06:37:51.121Z] 06:37:51 INFO - GECKO(3112) | stream(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/me
[task 2019-08-15T06:37:51.122Z] 06:37:51 INFO - GECKO(3112) | dia/tests/mochitest/test_peerConne
[task 2019-08-15T06:37:51.122Z] 06:37:51 INFO - GECKO(3112) | ction_checkPacketDumpHook tr
[task 2019-08-15T06:37:51.122Z] 06:37:51 INFO - GECKO(3112) | ansport-id=transport_0 - 1ba10ea6:ec
[task 2019-08-15T06:37:51.122Z] 06:37:51 INFO - GECKO(3112) | 56ee9385251a187e40ed270d082300) tried to trickle ICE in inappropriate
[task 2019-08-15T06:37:51.122Z] 06:37:51 INFO - GECKO(3112) | state 4
[task 2019-08-15T06:37:51.124Z] 06:37:51 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Trickle candidate is redundant for stream 'PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0' because it is completed: candidate:1 1 TCP 2105524479 10.143.45.226 9 typ host tcptype active
[task 2019-08-15T06:37:51.126Z] 06:37:51 INFO - GECKO(3112) | (ice/ERR) ICE(P
[task 2019-08-15T06:37:51.128Z] 06:37:51 INFO - GECKO(3112) | C:1565851070076000 (id=2147484030 url=h
[task 2019-08-15T06:37:51.128Z] 06:37:51 INFO - GECKO(3112) | ttps://example
[task 2019-08-15T06:37:51.133Z] 06:37:51 INFO - GECKO(3112) | .com/tests/dom/me
[task 2019-08-15T06:37:51.133Z] 06:37:51 INFO - GECKO(3112) | dia/tests/mochitest/test_peerConnection_checkPacketDumpHook): peer (PC:1565851070076000 (id=2147484030 url=https://exam
[task 2019-08-15T06:37:51.135Z] 06:37:51 INFO - GECKO(3112) | ple.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook:default), stream(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0 - 1ba10ea6:ec56ee9385251a187e40ed270d082300) tried to trickle ICE in inappropriate state 4
[task 2019-08-15T06:37:51.135Z] 06:37:51 INFO - GECKO(3112) | [Child 7176: Socket Thread]: D/mtransport Trickle candidate is redundant for stream 'PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0' because it is completed:
[task 2019-08-15T06:37:55.966Z] 06:37:55 INFO - GECKO(3112) | (stun/INFO) STUN-CLIENT(consent): Received response; processing
[task 2019-08-15T06:37:55.968Z] 06:37:55 INFO - GECKO(3112) | (ice/INFO) ICE(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook)/STREAM(PC:1565851070081000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0 - ae3706f8:3f1602fb48e80fafc5870b4d5744df43)/COMP(1): Consent refreshed
[task 2019-08-15T06:37:56.598Z] 06:37:56 INFO - GECKO(3112) | (stun/INFO) STUN-CLIENT(consent): Received response; processing
[task 2019-08-15T06:37:56.598Z] 06:37:56 INFO - GECKO(3112) | (ice/INFO) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook)/STREAM(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0 - 1ba10ea6:ec56ee9385251a187e40ed270d082300)/COMP(1): Consent refreshed
[task 2019-08-15T06:38:00.682Z] 06:38:00 INFO - GECKO(3112) | (stun/INFO) STUN-CLIENT(consent): Received response; processing
[task 2019-08-15T06:38:00.682Z] 06:38:00 INFO - GECKO(3112) | (ice/INFO) ICE(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook)/STREAM(PC:1565851070076000 (id=2147484030 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook transport-id=transport_0 - 1ba10ea6:ec56ee9385251a187e40ed270d082300)/COMP(1): Consent refreshed
[task 2019-08-15T06:38:01.150Z] 06:38:01 INFO - TEST-INFO | started process screenshot
[task 2019-08-15T06:38:01.261Z] 06:38:01 INFO - TEST-INFO | screenshot: exit 0
[task 2019-08-15T06:38:01.261Z] 06:38:01 INFO - <snipped 233 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2019-08-15T06:38:01.262Z] 06:38:01 INFO - Buffered messages logged at 06:37:50
[task 2019-08-15T06:38:01.263Z] 06:38:01 INFO - CLINE-NO-DEFAULT-ADDR-SDP: "audio 56062 UDP/TLS/RTP/SAVPF 109 9 0 8 101\r\nc=IN IP4 10.143.45.226\r\na=candidate:0 1 UDP 2122252543 10.143.45.226 56062 typ host\r\na=sendrecv\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:2/recvonly urn:ietf:params:rtp-hdrext:csrc-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:ec56ee9385251a187e40ed270d082300\r\na=ice-ufrag:1ba10ea6\r\na=mid:0\r\na=msid:{27bbf5bf-1482-4b78-b390-a5801d5a072d} {85749680-ffb6-47cb-ba06-16149bc1f1de}\r\na=rtcp-mux\r\na=rtpmap:109 opus/48000/2\r\na=rtpmap:9 G722/8000/1\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:101 telephone-event/8000\r\na=setup:actpass\r\na=ssrc:2752602948 cname:{cfdf075d-2723-4d3e-a139-7f755e41ea77}"
[task 2019-08-15T06:38:01.263Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | pcLocal: SDP contains non-zero IP c line
[task 2019-08-15T06:38:01.264Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | SDP MLine Index needs to exist
[task 2019-08-15T06:38:01.265Z] 06:38:01 INFO - Received: {"candidate":"candidate:0 1 UDP 2122252543 10.143.45.226 56062 typ host","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"1ba10ea6"} from pcLocal
[task 2019-08-15T06:38:01.265Z] 06:38:01 INFO - PeerConnectionWrapper (pcRemote): adding ICE candidate {"candidate":"candidate:0 1 UDP 2122252543 10.143.45.226 56062 typ host","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"1ba10ea6"}
[task 2019-08-15T06:38:01.266Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | PeerConnectionWrapper (pcRemote) successfully added an ICE candidate
[task 2019-08-15T06:38:01.266Z] 06:38:01 INFO - pcLocal: iceCandidate = {"candidate":"candidate:1 1 TCP 2105524479 10.143.45.226 9 typ host tcptype active","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"1ba10ea6"}[task 2019-08-15T06:38:01.268Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | SDP mid not empty
[task 2019-08-15T06:38:01.269Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | usernameFragment not empty
[task 2019-08-15T06:38:01.269Z] 06:38:01 INFO - CLINE-NO-DEFAULT-ADDR-SDP: "audio 56062 UDP/TLS/RTP/SAVPF 109 9 0 8 101\r\nc=IN IP4 10.143.45.226\r\na=candidate:0 1 UDP 2122252543 10.143.45.226 56062 typ host\r\na=candidate:1 1 TCP 2105524479 10.143.45.226 9 typ host tcptype active\r\na=sendrecv\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:2/recvonly urn:ietf:params:rtp-hdrext:csrc-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:ec56ee9385251a187e40ed270d082300\r\na=ice-ufrag:1ba10ea6\r\na=mid:0\r\na=msid:{27bbf5bf-1482-4b78-b390-a5801d5a072d} {85749680-ffb6-47cb-ba06-16149bc1f1de}\r\na=rtcp-mux\r\na=rtpmap:109 opus/48000/2\r\na=rtpmap:9 G722/8000/1\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:101 telephone-event/8000\r\na=setup:actpass\r\na=ssrc:2752602948 cname:{cfdf075d-2723-4d3e-a139-7f755e41ea77}"
[task 2019-08-15T06:38:01.270Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | pcLocal: SDP contains non-zero IP c line
[task 2019-08-15T06:38:01.270Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | SDP MLine Index needs to exist
[task 2019-08-15T06:38:01.271Z] 06:38:01 INFO - Received: {"candidate":"candidate:1 1 TCP 2105524479 10.143.45.226 9 typ host tcptype active","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"1ba10ea6"} from pcLocal
....
/test_peerConnection_checkPacketDumpHook.html | SDP mid not empty
[task 2019-08-15T06:38:01.306Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | usernameFragment not empty
[task 2019-08-15T06:38:01.307Z] 06:38:01 INFO - CLINE-NO-DEFAULT-ADDR-SDP: "audio 56067 UDP/TLS/RTP/SAVPF 109 9 0 8 101\r\nc=IN IP4 10.143.45.226\r\na=candidate:0 1 UDP 2122252543 10.143.45.226 56067 typ host\r\na=candidate:1 1 TCP 2105524479 10.143.45.226 9 typ host tcptype active\r\na=sendrecv\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:3f1602fb48e80fafc5870b4d5744df43\r\na=ice-ufrag:ae3706f8\r\na=mid:0\r\na=msid:{d5f4aaa9-7ad7-4d25-83b0-7dab7d2c3e61} {c7c000a5-dded-4d51-8af9-8d6142124cba}\r\na=rtcp-mux\r\na=rtpmap:109 opus/48000/2\r\na=rtpmap:9 G722/8000/1\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:101 telephone-event/8000\r\na=setup:active\r\na=ssrc:2062591155 cname:{342bab13-38be-4c18-ad7b-656b4e70118e}"
[task 2019-08-15T06:38:01.308Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | pcRemote: SDP contains non-zero IP c line
[task 2019-08-15T06:38:01.308Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | SDP MLine Index needs to exist
[task 2019-08-15T06:38:01.309Z] 06:38:01 INFO - Received: {"candidate":"candidate:1 1 TCP 2105524479 10.143.45.226 9 typ host tcptype active","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"ae3706f8"} from pcRemote
[task 2019-08-15T06:38:01.310Z] 06:38:01 INFO - PeerConnectionWrapper (pcLocal): adding ICE candidate {"candidate":"candidate:1 1 TCP 2105524479 10.143.45.226 9 typ host tcptype active","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"ae3706f8"}
[task 2019-08-15T06:38:01.310Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | PeerConnectionWrapper (pcLocal) successfully added an ICE candidate
[task 2019-08-15T06:38:01.311Z] 06:38:01 INFO - pcRemote: iceCandidate = {"candidate":"","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"ae3706f8"}
[task 2019-08-15T06:38:01.311Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | SDP mid not empty
[task 2019-08-15T06:38:01.312Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | usernameFragment not empty
[task 2019-08-15T06:38:01.314Z] 06:38:01 INFO - CLINE-NO-DEFAULT-ADDR-SDP: "audio 56067 UDP/TLS/RTP/SAVPF 109 9 0 8 101\r\nc=IN IP4 10.143.45.226\r\na=candidate:0 1 UDP 2122252543 10.143.45.226 56067 typ host\r\na=candidate:1 1 TCP 2105524479 10.143.45.226 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:3f1602fb48e80fafc5870b4d5744df43\r\na=ice-ufrag:ae3706f8\r\na=mid:0\r\na=msid:{d5f4aaa9-7ad7-4d25-83b0-7dab7d2c3e61} {c7c000a5-dded-4d51-8af9-8d6142124cba}\r\na=rtcp-mux\r\na=rtpmap:109 opus/48000/2\r\na=rtpmap:9 G722/8000/1\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:101 telephone-event/8000\r\na=setup:active\r\na=ssrc:2062591155 cname:{342bab13-38be-4c18-ad7b-656b4e70118e}"
[task 2019-08-15T06:38:01.315Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | pcRemote: SDP contains non-zero IP c line
[task 2019-08-15T06:38:01.316Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | SDP MLine Index needs to exist
[task 2019-08-15T06:38:01.316Z] 06:38:01 INFO - Received: {"candidate":"","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"ae3706f8"} from pcRemote
[task 2019-08-15T06:38:01.317Z] 06:38:01 INFO - PeerConnectionWrapper (pcLocal): adding ICE candidate {"candidate":"","sdpMid":"0","sdpMLineIndex":0,"usernameFragment":"ae3706f8"}
[task 2019-08-15T06:38:01.317Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | PeerConnectionWrapper (pcLocal) successfully added an ICE candidate
[task 2019-08-15T06:38:01.317Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | iceGetheringState should not be undefined
[task 2019-08-15T06:38:01.318Z] 06:38:01 INFO - PeerConnectionWrapper (pcRemote): onicegatheringstatechange fired, new state is: complete
[task 2019-08-15T06:38:01.319Z] 06:38:01 INFO - pcRemote: received end of trickle ICE event
[task 2019-08-15T06:38:01.319Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | ICE gathering state has reached complete
[task 2019-08-15T06:38:01.320Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | iceConnectionState should not be undefined
[task 2019-08-15T06:38:01.321Z] 06:38:01 INFO - PeerConnectionWrapper (pcRemote): oniceconnectionstatechange fired, new state is: checking
[task 2019-08-15T06:38:01.322Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | PeerConnectionWrapper (pcRemote): legal ICE state transition from new to checking
[task 2019-08-15T06:38:01.324Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | iceConnectionState should not be undefined
[task 2019-08-15T06:38:01.325Z] 06:38:01 INFO - PeerConnectionWrapper (pcLocal): oniceconnectionstatechange fired, new state is: checking
[task 2019-08-15T06:38:01.326Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | PeerConnectionWrapper (pcLocal): legal ICE state transition from new to checking
[task 2019-08-15T06:38:01.327Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | iceConnectionState should not be undefined
[task 2019-08-15T06:38:01.328Z] 06:38:01 INFO - PeerConnectionWrapper (pcRemote): oniceconnectionstatechange fired, new state is: connected
[task 2019-08-15T06:38:01.329Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | PeerConnectionWrapper (pcRemote): legal ICE state transition from checking to connected
[task 2019-08-15T06:38:01.330Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | iceConnectionState should not be undefined
[task 2019-08-15T06:38:01.331Z] 06:38:01 INFO - PeerConnectionWrapper (pcLocal): oniceconnectionstatechange fired, new state is: connected
[task 2019-08-15T06:38:01.331Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | PeerConnectionWrapper (pcLocal): legal ICE state transition from checking to connected
[task 2019-08-15T06:38:01.332Z] 06:38:01 INFO - PeerConnectionWrapper (pcLocal): ICE connection state log: new,checking,connected[task 2019-08-15T06:38:01.333Z] 06:38:01 INFO - Run step 34: PC_REMOTE_WAIT_FOR_ICE_CONNECTED
[task 2019-08-15T06:38:01.333Z] 06:38:01 INFO - PeerConnectionWrapper (pcRemote): ICE connection state log: new,checking,connected
[task 2019-08-15T06:38:01.334Z] 06:38:01 INFO - Run step 35: PC_LOCAL_VERIFY_ICE_GATHERING
[task 2019-08-15T06:38:01.334Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | PeerConnectionWrapper (pcLocal) received local trickle ICE candidates
[task 2019-08-15T06:38:01.334Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | PeerConnectionWrapper (pcLocal) ICE gathering state is not 'new'
[task 2019-08-15T06:38:01.335Z] 06:38:01 INFO - Run step 36: PC_REMOTE_VERIFY_ICE_GATHERING
[task 2019-08-15T06:38:01.335Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | PeerConnectionWrapper (pcRemote) received local trickle ICE candidates
[task 2019-08-15T06:38:01.336Z] 06:38:01 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | PeerConnectionWrapper (pcRemote) ICE gathering state is not 'new'
[task 2019-08-15T06:38:01.336Z] 06:38:01 INFO - Run step 37: PC_LOCAL_CHECK_PACKET_DUMP_HOOKS
[task 2019-08-15T06:38:01.337Z] 06:38:01 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-08-15T06:38:01.337Z] 06:38:01 INFO - Buffered messages finished
[task 2019-08-15T06:38:01.338Z] 06:38:01 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html | Error in test execution: Error: Timeout waiting for rtp recv packet on level 0 timeout/<@https://example.com/tests/dom/media/tests/mochitest/head.js:652:42 ... promise callbacktimeout@https://example.com/tests/dom/media/tests/mochitest/head.js:652:16 ... waitForRecvPacket@https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html:43:11 ... asyncPC_LOCAL_CHECK_PACKET_DUMP_HOOKS@https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_checkPacketDumpHook.html:64:17 ... execute/</<@https://example.com/tests/dom/media/tests/mochitest/head.js:944:32 ...
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 3•6 years ago
|
||
Description
•