1595023 - Intermittent TEST-UNEXPECTED-CRASH | /webrtc/RTCPeerConnection-createOffer.html | expected OK

Status: RESOLVED WORKSFORME

(Core :: WebRTC, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2019-11-08T08:07:01.687Z] 08:07:01 INFO - TEST-START | /webrtc/RTCPeerConnection-createOffer.html
[task 2019-11-08T08:07:01.688Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel Supports: AUTH
[task 2019-11-08T08:07:01.689Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel Supports: ASCONF
[task 2019-11-08T08:07:01.689Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel Supports: MULTIBUF
[task 2019-11-08T08:07:01.690Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel Supports: RE-CONFIG
[task 2019-11-08T08:07:01.690Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel Handling DCEP message of length 12
[task 2019-11-08T08:07:01.690Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel HandleOpenRequestMessage: length 12, sizeof(*req) = 13
[task 2019-11-08T08:07:01.691Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel Inserting channel 1 : 0xe1b6e220
[task 2019-11-08T08:07:01.691Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel HandleOpenRequestMessage: sending ON_CHANNEL_CREATED for /: 1
[task 2019-11-08T08:07:01.692Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel HandleOpenRequestMessage: deferring sending ON_CHANNEL_OPEN for 0xe1b6e220
[task 2019-11-08T08:07:01.693Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel Sent buffer (written=1, len=1, left=0)
[task 2019-11-08T08:07:01.693Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Init: origin = http://web-platform.test:8000
[task 2019-11-08T08:07:01.693Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2216: CloseInt: Closing PeerConnectionImpl 97f08a1090c14d78; ending call
[task 2019-11-08T08:07:01.694Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/jsep [1573200421287359 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)]: stable -> closed
[task 2019-11-08T08:07:01.695Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2222: CloseInt: Destroying DataChannelConnection 0xe540bd40 for 97f08a1090c14d78
[task 2019-11-08T08:07:01.695Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel Handling DCEP message of length 1
[task 2019-11-08T08:07:01.696Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel OpenAck received for stream 1, waiting=0
[task 2019-11-08T08:07:01.696Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Destroying DataChannelConnection 0xe540bd40
[task 2019-11-08T08:07:01.697Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Closing all channels (connection 0xe540bd40)
[task 2019-11-08T08:07:01.697Z] 08:07:01 INFO - Closing window 67
[task 2019-11-08T08:07:01.698Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel DataChannelConnection labeled transport_0 (0xe540bd40) switching connection state OPEN -> CLOSED
[task 2019-11-08T08:07:01.698Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Connection 0xe540bd40/Channel 0xe4add3a0: Closing stream 1
[task 2019-11-08T08:07:01.699Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Removing channel 1 : 0xe4add3a0
[task 2019-11-08T08:07:01.700Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Connection 0xe540bd40: Resetting outgoing stream 1
[task 2019-11-08T08:07:01.700Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel DataChannelConnection labeled (0xe4add3a0) (stream 1) changing ready state OPEN -> CLOSING
[task 2019-11-08T08:07:01.700Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Destroying Data channel 1
[task 2019-11-08T08:07:01.701Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Connection 0xe540bd40: Sending outgoing stream reset for 1 streams
[task 2019-11-08T08:07:01.701Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel SendDeferredMessages called, pending type: 0
[task 2019-11-08T08:07:01.702Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel Deregistered 0xe540bd40 from the SCTP stack.
[task 2019-11-08T08:07:01.702Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel SendDeferredMessages called, pending type: 0
[task 2019-11-08T08:07:01.703Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2216: CloseInt: Closing PeerConnectionImpl 7698db9370df4520; ending call
[task 2019-11-08T08:07:01.705Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/jsep [1573200421290138 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)]: stable -> closed[task 2019-11-08T08:07:01.705Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2222: CloseInt: Destroying DataChannelConnection 0xe1f8c480 for 7698db9370df4520
[task 2019-11-08T08:07:01.705Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Destroying DataChannelConnection 0xe1f8c480
[task 2019-11-08T08:07:01.706Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Closing all channels (connection 0xe1f8c480)
[task 2019-11-08T08:07:01.706Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel DataChannelConnection labeled transport_0 (0xe1f8c480) switching connection state OPEN -> CLOSED
[task 2019-11-08T08:07:01.706Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Connection 0xe1f8c480/Channel 0xe1b6e220: Closing stream 1
[task 2019-11-08T08:07:01.708Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Removing channel 1 : 0xe1b6e220
[task 2019-11-08T08:07:01.708Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Connection 0xe1f8c480: Resetting outgoing stream 1
[task 2019-11-08T08:07:01.708Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel DataChannelConnection labeled (0xe1b6e220) (stream 1) changing ready state OPEN -> CLOSING
[task 2019-11-08T08:07:01.709Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Destroying Data channel 1
[task 2019-11-08T08:07:01.710Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Connection 0xe1f8c480: Sending outgoing stream reset for 1 streams
[task 2019-11-08T08:07:01.710Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel Deregistered 0xe1f8c480 from the SCTP stack.
[task 2019-11-08T08:07:01.710Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:344: PeerConnectionImpl: PeerConnectionImpl constructor for
[task 2019-11-08T08:07:01.712Z] 08:07:01 INFO - PID 29431 | [Parent 29431: Socket Thread]: D/mtransport NrIceCtx static call to find local stun addresses
[task 2019-11-08T08:07:01.712Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:344: PeerConnectionImpl: PeerConnectionImpl constructor for
[task 2019-11-08T08:07:01.712Z] 08:07:01 INFO - PID 29431 | [Parent 29431: Socket Thread]: D/mtransport NrIceCtx static call to find local stun addresses
[task 2019-11-08T08:07:01.712Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Maximum message size (outgoing data): 2147483637 (set=no, enforced=yes)
[task 2019-11-08T08:07:01.713Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Setting number of SCTP streams to 256, was 10/2048
[task 2019-11-08T08:07:01.714Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Registered 0xe1fb75e0 within the SCTP stack.
[task 2019-11-08T08:07:01.714Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel DC Open: label /, type 0, inorder 1, prValue 0, listener (nil), context (nil), external: false, stream 65535
[task 2019-11-08T08:07:01.715Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Inserting channel 65535 : 0xe1cf6c40
[task 2019-11-08T08:07:01.715Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Queuing channel 0xe1cf6c40 (65535) to finish open
[task 2019-11-08T08:07:01.715Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Init: origin = http://web-platform.test:8000
[task 2019-11-08T08:07:01.716Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel DataChannelConnection labeled (0xe4add3a0) (stream 1) changing ready state CLOSING -> CLOSED
[task 2019-11-08T08:07:01.719Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel operator(): sending ON_CHANNEL_CLOSED for /: 1
[task 2019-11-08T08:07:01.719Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel 0xe4add5e0(0xe4add3a0): OnChannelClosed - Dispatching
[task 2019-11-08T08:07:01.719Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel DataChannelConnection labeled (0xe1b6e220) (stream 1) changing ready state CLOSING -> CLOSED
[task 2019-11-08T08:07:01.719Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel operator(): sending ON_CHANNEL_CLOSED for /: 1
[task 2019-11-08T08:07:01.719Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel 0xe1b6e460(0xe1b6e220): OnChannelClosed - Dispatching
[task 2019-11-08T08:07:01.719Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|PeerConnectionMedia] PeerConnectionMedia.cpp:72: OnStunAddrsAvailable: receiving (5) stun addrs
[task 2019-11-08T08:07:01.721Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|PeerConnectionMedia] PeerConnectionMedia.cpp:72: OnStunAddrsAvailable: receiving (5) stun addrs
[task 2019-11-08T08:07:01.721Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: 0xe1b6e700
[task 2019-11-08T08:07:01.721Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/jsep [1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)]: stable -> have-remote-offer
[task 2019-11-08T08:07:01.722Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:1469: SetRemoteDescription: pc = 79e251e93e8c2abf, asking JS to create transceiver
[task 2019-11-08T08:07:01.722Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:1469: SetRemoteDescription: pc = 79e251e93e8c2abf, asking JS to create transceiver
[task 2019-11-08T08:07:01.724Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: 0xe124a100
[task 2019-11-08T08:07:01.724Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/jsep [1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)]: stable -> have-local-offer
[task 2019-11-08T08:07:01.724Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: 0xe124a820
[task 2019-11-08T08:07:01.724Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/jsep [1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)]: have-local-offer -> stable[task 2019-11-08T08:07:01.725Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|WebrtcAudioSessionConduit] AudioConduit.cpp:248: SetDtmfPayloadType : setting dtmf payload 101
[task 2019-11-08T08:07:01.726Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Maximum message size (outgoing data): 1073741823 (set=yes, enforced=no)
[task 2019-11-08T08:07:01.726Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel ConnectToTransport connecting DTLS transport with parameters: Transport ID: 'transport_0', Role: 'server', Local Port: '5000', Remote Port: '5000'
[task 2019-11-08T08:07:01.726Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel DataChannelConnection labeled (0xe1fb75e0) switching connection state CLOSED -> CONNECTING
[task 2019-11-08T08:07:01.727Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Removing channel 65535 : 0xe1cf6c40
[task 2019-11-08T08:07:01.727Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Inserting channel 1 : 0xe1cf6c40
[task 2019-11-08T08:07:01.728Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: 0xe124adc0
[task 2019-11-08T08:07:01.728Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/jsep [1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)]: have-remote-offer -> stable
[task 2019-11-08T08:07:01.729Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Maximum message size (outgoing data): 1073741823 (set=yes, enforced=no)
[task 2019-11-08T08:07:01.729Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Setting number of SCTP streams to 256, was 10/2048
[task 2019-11-08T08:07:01.730Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Registered 0xe1fb7cc0 within the SCTP stack.
[task 2019-11-08T08:07:01.730Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel ConnectToTransport connecting DTLS transport with parameters: Transport ID: 'transport_0', Role: 'client', Local Port: '5000', Remote Port: '5000'
[task 2019-11-08T08:07:01.730Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel DataChannelConnection labeled (0xe1fb7cc0) switching connection state CLOSED -> CONNECTING
[task 2019-11-08T08:07:01.731Z] 08:07:01 INFO - PID 29431 | (generic/INFO) Exit UDP socket connected
[task 2019-11-08T08:07:01.732Z] 08:07:01 INFO - PID 29431 | (ice/WARNING) /builds/worker/workspace/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-11-08T08:07:01.732Z] 08:07:01 INFO - PID 29431 | (ice/WARNING) ICE(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): failed to create passive TCP host candidate: 3
[task 2019-11-08T08:07:01.733Z] 08:07:01 INFO - PID 29431 | (ice/WARNING) /builds/worker/workspace/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-11-08T08:07:01.733Z] 08:07:01 INFO - PID 29431 | (ice/WARNING) ICE(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): failed to create passive TCP host candidate: 3
[task 2019-11-08T08:07:01.735Z] 08:07:01 INFO - PID 29431 | (ice/WARNING) ICE(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) has no stream matching stream PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0 - c9ca19b6:272a111250f4a03d63d9a02d925987b0
[task 2019-11-08T08:07:01.735Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport Setting up DTLS as server
[task 2019-11-08T08:07:01.735Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel Setting transport signals, dtls not open yet
[task 2019-11-08T08:07:01.736Z] 08:07:01 INFO - PID 29431 | (ice/NOTICE) ICE(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) no streams with non-empty check lists
[task 2019-11-08T08:07:01.737Z] 08:07:01 INFO - PID 29431 | (ice/NOTICE) ICE(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) no streams with pre-answer requests
[task 2019-11-08T08:07:01.737Z] 08:07:01 INFO - PID 29431 | (ice/NOTICE) ICE(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) no checks to start
[task 2019-11-08T08:07:01.738Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport Couldn't start peer checks on PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html), assuming trickle ICE
[task 2019-11-08T08:07:01.739Z] 08:07:01 INFO - PID 29431 | (generic/INFO) Exit UDP socket connected
[task 2019-11-08T08:07:01.739Z] 08:07:01 INFO - PID 29431 | (ice/WARNING) /builds/worker/workspace/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-11-08T08:07:01.739Z] 08:07:01 INFO - PID 29431 | (ice/WARNING) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): failed to create passive TCP host candidate: 3
[task 2019-11-08T08:07:01.740Z] 08:07:01 INFO - PID 29431 | (ice/WARNING) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) has no stream matching stream PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0 - aab98724:4801deed8474a3a04f9c01dbcd47bccc
[task 2019-11-08T08:07:01.741Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport Setting up DTLS as client
[task 2019-11-08T08:07:01.742Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: E/mtransport Couldn't disable 'PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0':2
[task 2019-11-08T08:07:01.742Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel Setting transport signals, dtls not open yet[task 2019-11-08T08:07:01.742Z] 08:07:01 INFO - PID 29431 | (ice/NOTICE) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) no streams with non-empty check lists
[task 2019-11-08T08:07:01.744Z] 08:07:01 INFO - PID 29431 | (ice/NOTICE) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) no streams with pre-answer requests
[task 2019-11-08T08:07:01.744Z] 08:07:01 INFO - PID 29431 | (ice/NOTICE) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) no checks to start
[task 2019-11-08T08:07:01.745Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport Couldn't start peer checks on PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html), assuming trickle ICE
[task 2019-11-08T08:07:01.746Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport NrIceCtx(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): trickling candidate candidate:0 1 UDP 2122252543 695f318a-f471-4fb5-b624-73e7b82327af.local 48469 typ host
[task 2019-11-08T08:07:01.746Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0', no candidates.
[task 2019-11-08T08:07:01.746Z] 08:07:01 INFO - PID 29431 | (ice/ERR) ICE(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) pairing local trickle ICE candidate host(IP4:172.17.0.4:48469/UDP)
[task 2019-11-08T08:07:01.748Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport NrIceCtx(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): trickling candidate candidate:1 1 TCP 2105524479 695f318a-f471-4fb5-b624-73e7b82327af.local 9 typ host tcptype active
[task 2019-11-08T08:07:01.748Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0', no candidates.
[task 2019-11-08T08:07:01.748Z] 08:07:01 INFO - PID 29431 | (ice/ERR) ICE(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) pairing local trickle ICE candidate host(IP4:172.17.0.4:51891/TCP) active
[task 2019-11-08T08:07:01.750Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport NrIceCtx(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): trickling candidate candidate:0 2 UDP 2122252542 695f318a-f471-4fb5-b624-73e7b82327af.local 47559 typ host
[task 2019-11-08T08:07:01.750Z] 08:07:01 INFO - PID 29431 | (ice/ERR) ICE(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) pairing local trickle ICE candidate host(IP4:172.17.0.4:47559/UDP)
[task 2019-11-08T08:07:01.751Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport NrIceCtx(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): trickling candidate candidate:1 2 TCP 2105524478 695f318a-f471-4fb5-b624-73e7b82327af.local 9 typ host tcptype active
[task 2019-11-08T08:07:01.751Z] 08:07:01 INFO - PID 29431 | (ice/ERR) ICE(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) pairing local trickle ICE candidate host(IP4:172.17.0.4:49906/TCP) active
[task 2019-11-08T08:07:01.752Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): All candidates initialized
[task 2019-11-08T08:07:01.753Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport NrIceCtx(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): trickling candidate candidate:0 1 UDP 2122252543 940c444f-a435-4596-98be-70d720e0d9f5.local 59266 typ host
[task 2019-11-08T08:07:01.753Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0', no candidates.
[task 2019-11-08T08:07:01.754Z] 08:07:01 INFO - PID 29431 | (ice/ERR) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) pairing local trickle ICE candidate host(IP4:172.17.0.4:59266/UDP)
[task 2019-11-08T08:07:01.755Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport NrIceCtx(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): trickling candidate candidate:1 1 TCP 2105524479 940c444f-a435-4596-98be-70d720e0d9f5.local 9 typ host tcptype active
[task 2019-11-08T08:07:01.755Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0', no candidates.
[task 2019-11-08T08:07:01.756Z] 08:07:01 INFO - PID 29431 | (ice/ERR) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) pairing local trickle ICE candidate host(IP4:172.17.0.4:49474/TCP) active
[task 2019-11-08T08:07:01.757Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0', no candidates.
[task 2019-11-08T08:07:01.757Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): All candidates initialized
[task 2019-11-08T08:07:01.758Z] 08:07:01 INFO - PID 29431 | (ice/WARNING) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): Error parsing attribute:
[task 2019-11-08T08:07:01.759Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: E/mtransport Couldn't parse trickle candidate for stream 'PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0':
[task 2019-11-08T08:07:01.759Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: E/signaling [Socket Thread|MediaTransportHandler] MediaTransportHandler.cpp:715: Couldn't process ICE candidate with transport id transport_0:
[task 2019-11-08T08:07:01.759Z] 08:07:01 INFO - PID 29431 | (ice/WARNING) ICE(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): Error parsing attribute:
[task 2019-11-08T08:07:01.761Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: E/mtransport Couldn't parse trickle candidate for stream 'PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0':
[task 2019-11-08T08:07:01.761Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: E/signaling [Socket Thread|MediaTransportHandler] MediaTransportHandler.cpp:715: Couldn't process ICE candidate with transport id transport_0:
[task 2019-11-08T08:07:01.762Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(qu8c): setting pair to state FROZEN: qu8c|IP4:172.17.0.4:59266/UDP|IP4:172.17.0.4:48469/UDP(host(IP4:172.17.0.4:59266/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 48469 typ host)
[task 2019-11-08T08:07:01.763Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html))/CAND-PAIR(qu8c): Pairing candidate IP4:172.17.0.4:59266/UDP (7e7f00ff):IP4:172.17.0.4:48469/UDP (7e7f00ff) priority=9115005270282338815 (7e7f00fffcfe01ff)
[task 2019-11-08T08:07:01.764Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/ICE-STREAM(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0 - aab98724:4801deed8474a3a04f9c01dbcd47bccc): Starting check timer for stream.
[task 2019-11-08T08:07:01.764Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(qu8c): setting pair to state WAITING: qu8c|IP4:172.17.0.4:59266/UDP|IP4:172.17.0.4:48469/UDP(host(IP4:172.17.0.4:59266/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 48469 typ host)
[task 2019-11-08T08:07:01.765Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(qu8c): setting pair to state IN_PROGRESS: qu8c|IP4:172.17.0.4:59266/UDP|IP4:172.17.0.4:48469/UDP(host(IP4:172.17.0.4:59266/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 48469 typ host)
[task 2019-11-08T08:07:01.765Z] 08:07:01 INFO - PID 29431 | (ice/NOTICE) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) is now checking
[task 2019-11-08T08:07:01.766Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport NrIceCtx(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): state 0->1[task 2019-11-08T08:07:01.767Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) no such component for candidate candidate:0 2 UDP 2122252542 172.17.0.4 47559 typ host
[task 2019-11-08T08:07:01.768Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport Trickle candidate is ignored for stream 'PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0', probably because it is for an unused component: candidate:0 2 UDP 2122252542 172.17.0.4 47559 typ host
[task 2019-11-08T08:07:01.768Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) no such component for candidate candidate:1 2 TCP 2105524478 172.17.0.4 9 typ host tcptype active
[task 2019-11-08T08:07:01.770Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport Trickle candidate is ignored for stream 'PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0', probably because it is for an unused component: candidate:1 2 TCP 2105524478 172.17.0.4 9 typ host tcptype active
[task 2019-11-08T08:07:01.770Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(xITo): setting pair to state FROZEN: xITo|IP4:172.17.0.4:48469/UDP|IP4:172.17.0.4:59266/UDP(host(IP4:172.17.0.4:48469/UDP)|prflx)
[task 2019-11-08T08:07:01.770Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html))/CAND-PAIR(xITo): Pairing candidate IP4:172.17.0.4:48469/UDP (7e7f00ff):IP4:172.17.0.4:59266/UDP (6e7f00ff) priority=7962083765675491839 (6e7f00fffcfe01ff)
[task 2019-11-08T08:07:01.771Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(xITo): setting pair to state FROZEN: xITo|IP4:172.17.0.4:48469/UDP|IP4:172.17.0.4:59266/UDP(host(IP4:172.17.0.4:48469/UDP)|prflx)
[task 2019-11-08T08:07:01.772Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/ICE-STREAM(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0 - c9ca19b6:272a111250f4a03d63d9a02d925987b0): Starting check timer for stream.
[task 2019-11-08T08:07:01.772Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(xITo): setting pair to state WAITING: xITo|IP4:172.17.0.4:48469/UDP|IP4:172.17.0.4:59266/UDP(host(IP4:172.17.0.4:48469/UDP)|prflx)
[task 2019-11-08T08:07:01.773Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(xITo): setting pair to state IN_PROGRESS: xITo|IP4:172.17.0.4:48469/UDP|IP4:172.17.0.4:59266/UDP(host(IP4:172.17.0.4:48469/UDP)|prflx)
[task 2019-11-08T08:07:01.773Z] 08:07:01 INFO - PID 29431 | (ice/NOTICE) ICE(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) is now checking
[task 2019-11-08T08:07:01.774Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport NrIceCtx(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): state 0->1
[task 2019-11-08T08:07:01.775Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(xITo): triggered check on xITo|IP4:172.17.0.4:48469/UDP|IP4:172.17.0.4:59266/UDP(host(IP4:172.17.0.4:48469/UDP)|prflx)
[task 2019-11-08T08:07:01.775Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(xITo): setting pair to state FROZEN: xITo|IP4:172.17.0.4:48469/UDP|IP4:172.17.0.4:59266/UDP(host(IP4:172.17.0.4:48469/UDP)|prflx)
[task 2019-11-08T08:07:01.775Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html))/CAND-PAIR(xITo): Pairing candidate IP4:172.17.0.4:48469/UDP (7e7f00ff):IP4:172.17.0.4:59266/UDP (6e7f00ff) priority=7962083765675491839 (6e7f00fffcfe01ff)
[task 2019-11-08T08:07:01.777Z] 08:07:01 INFO - PID 29431 | (ice/INFO) CAND-PAIR(xITo): Adding pair to check list and trigger check queue: xITo|IP4:172.17.0.4:48469/UDP|IP4:172.17.0.4:59266/UDP(host(IP4:172.17.0.4:48469/UDP)|prflx)
[task 2019-11-08T08:07:01.777Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(xITo): setting pair to state WAITING: xITo|IP4:172.17.0.4:48469/UDP|IP4:172.17.0.4:59266/UDP(host(IP4:172.17.0.4:48469/UDP)|prflx)
[task 2019-11-08T08:07:01.778Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(xITo): setting pair to state CANCELLED: xITo|IP4:172.17.0.4:48469/UDP|IP4:172.17.0.4:59266/UDP(host(IP4:172.17.0.4:48469/UDP)|prflx)
[task 2019-11-08T08:07:01.778Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(XsAc): setting pair to state FROZEN: XsAc|IP4:172.17.0.4:48469/UDP|IP4:172.17.0.4:59266/UDP(host(IP4:172.17.0.4:48469/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 59266 typ host)
[task 2019-11-08T08:07:01.779Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE(PC:1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html))/CAND-PAIR(XsAc): Pairing candidate IP4:172.17.0.4:48469/UDP (7e7f00ff):IP4:172.17.0.4:59266/UDP (7e7f00ff) priority=9115005270282338815 (7e7f00fffcfe01ff)
[task 2019-11-08T08:07:01.780Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(qu8c): triggered check on qu8c|IP4:172.17.0.4:59266/UDP|IP4:172.17.0.4:48469/UDP(host(IP4:172.17.0.4:59266/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 48469 typ host)
[task 2019-11-08T08:07:01.780Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(qu8c): setting pair to state FROZEN: qu8c|IP4:172.17.0.4:59266/UDP|IP4:172.17.0.4:48469/UDP(host(IP4:172.17.0.4:59266/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 48469 typ host)
[task 2019-11-08T08:07:01.781Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html))/CAND-PAIR(qu8c): Pairing candidate IP4:172.17.0.4:59266/UDP (7e7f00ff):IP4:172.17.0.4:48469/UDP (7e7f00ff) priority=9115005270282338815 (7e7f00fffcfe01ff)
[task 2019-11-08T08:07:01.782Z] 08:07:01 INFO - PID 29431 | (ice/INFO) CAND-PAIR(qu8c): Adding pair to check list and trigger check queue: qu8c|IP4:172.17.0.4:59266/UDP|IP4:172.17.0.4:48469/UDP(host(IP4:172.17.0.4:59266/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 48469 typ host)
[task 2019-11-08T08:07:01.782Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(qu8c): setting pair to state WAITING: qu8c|IP4:172.17.0.4:59266/UDP|IP4:172.17.0.4:48469/UDP(host(IP4:172.17.0.4:59266/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 48469 typ host)
[task 2019-11-08T08:07:01.783Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(qu8c): setting pair to state CANCELLED: qu8c|IP4:172.17.0.4:59266/UDP|IP4:172.17.0.4:48469/UDP(host(IP4:172.17.0.4:59266/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 48469 typ host)
[task 2019-11-08T08:07:01.783Z] 08:07:01 INFO - PID 29431 | (stun/INFO) STUN-CLIENT(qu8c|IP4:172.17.0.4:59266/UDP|IP4:172.17.0.4:48469/UDP(host(IP4:172.17.0.4:59266/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 48469 typ host)): Received response; processing
[task 2019-11-08T08:07:01.784Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(qu8c): setting pair to state SUCCEEDED: qu8c|IP4:172.17.0.4:59266/UDP|IP4:172.17.0.4:48469/UDP(host(IP4:172.17.0.4:59266/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 48469 typ host)
[task 2019-11-08T08:07:01.784Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/STREAM(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0 - aab98724:4801deed8474a3a04f9c01dbcd47bccc)/COMP(1)/CAND-PAIR(qu8c): nominated pair is qu8c|IP4:172.17.0.4:59266/UDP|IP4:172.17.0.4:48469/UDP(host(IP4:172.17.0.4:59266/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 48469 typ host)
[task 2019-11-08T08:07:01.785Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/STREAM(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0 - aab98724:4801deed8474a3a04f9c01dbcd47bccc)/COMP(1)/CAND-PAIR(qu8c): cancelling all pairs but qu8c|IP4:172.17.0.4:59266/UDP|IP4:172.17.0.4:48469/UDP(host(IP4:172.17.0.4:59266/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 48469 typ host)
[task 2019-11-08T08:07:01.786Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/STREAM(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0 - aab98724:4801deed8474a3a04f9c01dbcd47bccc)/COMP(1)/CAND-PAIR(qu8c): cancelling FROZEN/WAITING pair qu8c|IP4:172.17.0.4:59266/UDP|IP4:172.17.0.4:48469/UDP(host(IP4:172.17.0.4:59266/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 48469 typ host) in trigger check queue because CAND-PAIR(qu8c) was nominated.
[task 2019-11-08T08:07:01.787Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/CAND-PAIR(qu8c): setting pair to state CANCELLED: qu8c|IP4:172.17.0.4:59266/UDP|IP4:172.17.0.4:48469/UDP(host(IP4:172.17.0.4:59266/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 48469 typ host)
[task 2019-11-08T08:07:01.787Z] 08:07:01 INFO - PID 29431 | (ice/INFO) ICE-PEER(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default)/ICE-STREAM(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0 - aab98724:4801deed8474a3a04f9c01dbcd47bccc): all active components have nominated candidate pairs
[task 2019-11-08T08:07:01.789Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[ice]: ICE Ready(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html) transport-id=transport_0,1)
[task 2019-11-08T08:07:01.789Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[dtls]: Lower layer is now open; starting TLS[task 2019-11-08T08:07:01.848Z] 08:07:01 INFO - PID 29431 | (ice/NOTICE) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) no streams with non-empty check lists
[task 2019-11-08T08:07:01.848Z] 08:07:01 INFO - PID 29431 | (ice/NOTICE) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) no streams with pre-answer requests
[task 2019-11-08T08:07:01.848Z] 08:07:01 INFO - PID 29431 | (ice/NOTICE) ICE(PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)): peer (PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html):default) no checks to start
[task 2019-11-08T08:07:01.850Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/mtransport Couldn't start peer checks on PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html), assuming trickle ICE
[task 2019-11-08T08:07:01.850Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel DataChannelConnection labeled (0xe0e31160) (stream 1) changing ready state OPEN -> OPEN
[task 2019-11-08T08:07:01.850Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel operator(): sending ON_CHANNEL_OPEN for /: 1
[task 2019-11-08T08:07:01.851Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel 0xe0e31040(0xe0e31160): OnChannelConnected - Dispatching
[task 2019-11-08T08:07:01.851Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel DataChannelConnection labeled (0xe1cf6c40) (stream 1) changing ready state CLOSING -> CLOSED
[task 2019-11-08T08:07:01.852Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel operator(): sending ON_CHANNEL_CLOSED for /: 1
[task 2019-11-08T08:07:01.853Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel 0xe1cf6d60(0xe1cf6c40): OnChannelClosed - Dispatching
[task 2019-11-08T08:07:01.853Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2216: CloseInt: Closing PeerConnectionImpl 18f5153b4d161eb0; ending call
[task 2019-11-08T08:07:01.853Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/jsep [1573200421419616 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)]: stable -> closed
[task 2019-11-08T08:07:01.854Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2216: CloseInt: Closing PeerConnectionImpl 79e251e93e8c2abf; ending call
[task 2019-11-08T08:07:01.855Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/jsep [1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)]: stable -> closed
[task 2019-11-08T08:07:01.855Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2222: CloseInt: Destroying DataChannelConnection 0xe1fb7cc0 for 79e251e93e8c2abf
[task 2019-11-08T08:07:01.855Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Destroying DataChannelConnection 0xe1fb7cc0
[task 2019-11-08T08:07:01.855Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Closing all channels (connection 0xe1fb7cc0)
[task 2019-11-08T08:07:01.856Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel DataChannelConnection labeled transport_3 (0xe1fb7cc0) switching connection state CONNECTING -> CLOSED
[task 2019-11-08T08:07:01.857Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Connection 0xe1fb7cc0/Channel 0xe0e31160: Closing stream 1
[task 2019-11-08T08:07:01.857Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Removing channel 1 : 0xe0e31160
[task 2019-11-08T08:07:01.857Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Connection 0xe1fb7cc0: Resetting outgoing stream 1
[task 2019-11-08T08:07:01.858Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel DataChannelConnection labeled (0xe0e31160) (stream 1) changing ready state OPEN -> CLOSING
[task 2019-11-08T08:07:01.858Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Destroying Data channel 1
[task 2019-11-08T08:07:01.859Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel Connection 0xe1fb7cc0: Sending outgoing stream reset for 1 streams
[task 2019-11-08T08:07:01.860Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: D/DataChannel Deregistered 0xe1fb7cc0 from the SCTP stack.
[task 2019-11-08T08:07:01.860Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: E/mtransport Flow[transport_3(none)]; Layer[dtls]: Can't call SendPacket() in state 1
[task 2019-11-08T08:07:01.860Z] 08:07:01 INFO - PID 29431 | [Child 31012: Socket Thread]: E/signaling [Socket Thread|MediaTransportHandler] MediaTransportHandler.cpp:789: PC:1573200421421161 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html): Transport flow (transport_3) failed to send packet
[task 2019-11-08T08:07:01.861Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel DataChannelConnection labeled (0xe0e31160) (stream 1) changing ready state CLOSING -> CLOSED
[task 2019-11-08T08:07:01.862Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel operator(): sending ON_CHANNEL_CLOSED for /: 1
[task 2019-11-08T08:07:01.862Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: D/DataChannel 0xe0e31040(0xe0e31160): OnChannelClosed - Dispatching
[task 2019-11-08T08:07:01.862Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2216: CloseInt: Closing PeerConnectionImpl b64eb4bc75b830ca; ending call
[task 2019-11-08T08:07:01.862Z] 08:07:01 INFO - PID 29431 | [Child 31012: Main Thread]: I/jsep [1573200420425888 (id=53687091202 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)]: stable -> closed
[task 2019-11-08T08:13:01.746Z] 08:13:01 INFO - IOError on command, setting status to CRASH
[task 2019-11-08T08:13:01.748Z] 08:13:01 INFO - TEST-UNEXPECTED-CRASH | /webrtc/RTCPeerConnection-createOffer.html | expected OK

Comment 1

[Dan Minor [:dminor]]

From the timing it seems quite likely this was regressed by Bug 1588817.

Comment 2

[Intermittent Failures Robot]

2 failures in 4850 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1
• try: 1

Platform breakdown:

• linux32-shippable: 1
• windows10-64-shippable-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1595023&startday=2019-11-04&endday=2019-11-10&tree=all

Comment 3

[Intermittent Failures Robot]

1 failures in 4207 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1595023&startday=2019-11-11&endday=2019-11-17&tree=all

Comment 4

[Intermittent Failures Robot]

1 failures in 5444 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• windows10-64-shippable-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1595023&startday=2019-11-18&endday=2019-11-24&tree=all

Comment 5

[Intermittent Failures Robot]

1 failures in 5144 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1595023&startday=2019-12-02&endday=2019-12-08&tree=all