Closed
Bug 1755860
Opened 4 years ago
Closed 3 years ago
Intermittent TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCDtlsTransport-state.html | close() causes the other end's DTLS transport to close - Test timed out
Categories
(Core :: WebRTC, defect, P5)
Core
WebRTC
Tracking
()
RESOLVED
DUPLICATE
of bug 1785511
People
(Reporter: intermittent-bug-filer, Unassigned)
References
Details
(Keywords: intermittent-failure)
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=368196096&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/W9wCcoeXREeON7bGDSJEpw/runs/0/artifacts/public/logs/live_backing.log
[task 2022-02-17T07:25:26.048Z] 07:25:26 INFO - TEST-PASS | /webrtc/RTCDtlsTransport-state.html | close() causes the local transport to close immediately
[task 2022-02-17T07:25:26.048Z] 07:25:26 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCDtlsTransport-state.html | close() causes the other end's DTLS transport to close - Test timed out
[task 2022-02-17T07:25:26.048Z] 07:25:26 INFO -
[task 2022-02-17T07:25:26.048Z] 07:25:26 INFO - TEST-UNEXPECTED-NOTRUN | /webrtc/RTCDtlsTransport-state.html | stop bundled transceiver retains dtls transport state - expected PASS
[task 2022-02-17T07:25:26.049Z] 07:25:26 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCDtlsTransport-state.html | expected OK
[task 2022-02-17T07:25:26.049Z] 07:25:26 INFO - TEST-INFO took 10286ms
[task 2022-02-17T07:25:26.051Z] 07:25:26 INFO - PID 6076 | 0.000000 | 0.000000 | Initializing PC Ctx | PeerConnectionImpl.cpp:446 | Initialize
[task 2022-02-17T07:25:26.052Z] 07:25:26 INFO - PID 6076 | 12.312000 | 12.312000 | Destructor Invoked | PeerConnectionImpl.cpp:361 | ~PeerConnectionImpl
[task 2022-02-17T07:25:26.052Z] 07:25:26 INFO - PID 6076 | 12.312000 | 0.000000 | {7f2edd47-e101-49b2-a019-f2e902af8610} | PeerConnectionImpl.cpp:362 | ~PeerConnectionImpl
[task 2022-02-17T07:25:26.054Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:375: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {7f2edd47-e101-49b2-a019-f2e902af8610}
[task 2022-02-17T07:25:26.054Z] 07:25:26 INFO - PID 6076 | Timecard created 1645082715.714000
[task 2022-02-17T07:25:26.054Z] 07:25:26 INFO - PID 6076 | Timestamp | Delta | Event | File | Function
[task 2022-02-17T07:25:26.055Z] 07:25:26 INFO - PID 6076 | =========================================================================================================================
[task 2022-02-17T07:25:26.056Z] 07:25:26 INFO - PID 6076 | 0.000000 | 0.000000 | Constructor Completed | PeerConnectionImpl.cpp:344 | PeerConnectionImpl
[task 2022-02-17T07:25:26.057Z] 07:25:26 INFO - PID 6076 | 0.000000 | 0.000000 | Initializing PC Ctx | PeerConnectionImpl.cpp:446 | Initialize
[task 2022-02-17T07:25:26.058Z] 07:25:26 INFO - PID 6076 | 10.316000 | 10.316000 | Destructor Invoked | PeerConnectionImpl.cpp:361 | ~PeerConnectionImpl
[task 2022-02-17T07:25:26.059Z] 07:25:26 INFO - PID 6076 | 10.316000 | 0.000000 | {2ffab0c3-abfe-4aab-b010-b0f3517f53b0} | PeerConnectionImpl.cpp:362 | ~PeerConnectionImpl
[task 2022-02-17T07:25:26.060Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:375: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {2ffab0c3-abfe-4aab-b010-b0f3517f53b0}
[task 2022-02-17T07:25:26.060Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: 1f596071120
[task 2022-02-17T07:25:26.062Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: I/jsep [{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo]: have-remote-offer -> stable
[task 2022-02-17T07:25:26.063Z] 07:25:26 INFO - PID 6076 | (generic/EMERG) Exit UDP socket connected
[task 2022-02-17T07:25:26.064Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: I/mtransport NrIceCtx(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): StartGathering
[task 2022-02-17T07:25:26.065Z] 07:25:26 INFO - PID 6076 | (ice/WARNING) /builds/worker/checkouts/gecko/dom/media/webrtc/transport/third_party/nICEr/src/net/nr_socket_multi_tcp.c:639 function nr_socket_multi_tcp_listen failed with error 3
[task 2022-02-17T07:25:26.066Z] 07:25:26 INFO - PID 6076 | (ice/WARNING) ICE-STREAM(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0 - 62405445:b32053c028f9dc099728e49a1a10fa34): failed to create passive TCP host candidate: 3
[task 2022-02-17T07:25:26.067Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport Setting up DTLS as client
[task 2022-02-17T07:25:26.069Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport Setting DTLS1.3 supported_versions workaround
[task 2022-02-17T07:25:26.069Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: E/mtransport Couldn't disable 'PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0':2
[task 2022-02-17T07:25:26.071Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: I/mtransport NrIceCtx(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo)/STREAM(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0) : parsing trickle candidate candidate:0 1 UDP 2122252543 10.0.0.16 65256 typ host
[task 2022-02-17T07:25:26.073Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: I/mtransport NrIceCtx(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo)/STREAM(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0) : parsing trickle candidate candidate:1 1 TCP 2105524479 10.0.0.16 9 typ host tcptype active
[task 2022-02-17T07:25:26.075Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: I/mtransport NrIceCtx(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo)/STREAM(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0) : parsing trickle candidate candidate:0 2 UDP 2122252542 10.0.0.16 65257 typ host
[task 2022-02-17T07:25:26.076Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): peer (PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default) no such component for candidate candidate:0 2 UDP 2122252542 10.0.0.16 65257 typ host1645082726047 Marionette INFO Stopped listening on port 54678
[task 2022-02-17T07:25:26.077Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport Trickle candidate is ignored for stream 'PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0', probably because it is for an unused component: candidate:0 2 UDP 2122252542 10.0.0.16 65257 typ host
[task 2022-02-17T07:25:26.078Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: I/mtransport NrIceCtx(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo)/STREAM(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0) : parsing trickle candidate candidate:1 2 TCP 2105524478 10.0.0.16 9 typ host tcptype active
[task 2022-02-17T07:25:26.079Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): peer (PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default) no such component for candidate candidate:1 2 TCP 2105524478 10.0.0.16 9 typ host tcptype active
[task 2022-02-17T07:25:26.081Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport Trickle candidate is ignored for stream 'PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0', probably because it is for an unused component: candidate:1 2 TCP 2105524478 10.0.0.16 9 typ host tcptype active
[task 2022-02-17T07:25:26.081Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: I/mtransport NrIceCtx(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): StartChecks
[task 2022-02-17T07:25:26.082Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): peer (PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default) starting grace period timer for 10000 ms
[task 2022-02-17T07:25:26.083Z] 07:25:26 INFO - PID 6076 | (ice/NOTICE) ICE(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): peer (PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default) no streams with non-empty check lists
[task 2022-02-17T07:25:26.084Z] 07:25:26 INFO - PID 6076 | (ice/NOTICE) ICE(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): peer (PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default) no streams with pre-answer requests
[task 2022-02-17T07:25:26.086Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): peer (PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default) no checks to start, but gathering is not done yet, cancelling grace period timer[Child 7748: Main Thread]: D/DataChannel 1f59837cac0: Close()ing 1f598352040
[task 2022-02-17T07:25:26.087Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport Couldn't start peer checks on PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo, assuming trickle ICE
[task 2022-02-17T07:25:26.087Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport NrIceCtx(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): trickling candidate candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host
[task 2022-02-17T07:25:26.089Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0', no candidates.
[task 2022-02-17T07:25:26.090Z] 07:25:26 INFO - PID 6076 | (ice/ERR) ICE(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): peer (PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default) pairing local trickle ICE candidate host(IP4:10.0.0.16:65259/UDP)
[task 2022-02-17T07:25:26.091Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): peer (PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default) starting grace period timer for 10000 ms
[task 2022-02-17T07:25:26.092Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2462: Passing local candidate to content: candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host
[task 2022-02-17T07:25:26.093Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(/uVE): setting pair to state FROZEN: /uVE|IP4:10.0.0.16:65259/UDP|IP4:10.0.0.16:65256/UDP(host(IP4:10.0.0.16:65259/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65256 typ host)
[task 2022-02-17T07:25:26.094Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo)/CAND-PAIR(/uVE): Pairing candidate IP4:10.0.0.16:65259/UDP (7e7f00ff):IP4:10.0.0.16:65256/UDP (7e7f00ff) priority=9115005270282338815 (7e7f00fffcfe01ff)
[task 2022-02-17T07:25:26.095Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/ICE-STREAM(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0 - 62405445:b32053c028f9dc099728e49a1a10fa34): Starting check timer for stream.
[task 2022-02-17T07:25:26.096Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(/uVE): setting pair to state WAITING: /uVE|IP4:10.0.0.16:65259/UDP|IP4:10.0.0.16:65256/UDP(host(IP4:10.0.0.16:65259/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65256 typ host)
[task 2022-02-17T07:25:26.098Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(/uVE): setting pair to state IN_PROGRESS: /uVE|IP4:10.0.0.16:65259/UDP|IP4:10.0.0.16:65256/UDP(host(IP4:10.0.0.16:65259/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65256 typ host)
[task 2022-02-17T07:25:26.099Z] 07:25:26 INFO - PID 6076 | (ice/NOTICE) ICE(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): peer (PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default) is now checking
[task 2022-02-17T07:25:26.099Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport NrIceCtx(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): state 0->1
[task 2022-02-17T07:25:26.101Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport NrIceCtx(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): trickling candidate candidate:1 1 TCP 2105524479 10.0.0.16 9 typ host tcptype active
[task 2022-02-17T07:25:26.102Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0', no candidates.
[task 2022-02-17T07:25:26.102Z] 07:25:26 INFO - PID 6076 | (ice/ERR) ICE(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): peer (PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default) pairing local trickle ICE candidate host(IP4:10.0.0.16:53634/TCP) active
[task 2022-02-17T07:25:26.103Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport Couldn't get default ICE candidate for 'PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0', no candidates.
[task 2022-02-17T07:25:26.105Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2462: Passing local candidate to content: candidate:1 1 TCP 2105524479 10.0.0.16 9 typ host tcptype active
[task 2022-02-17T07:25:26.105Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5989301a0: Close()ing 1f5988bd3c0
[task 2022-02-17T07:25:26.106Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5989aca60: Close()ing 1f598ad5900
[task 2022-02-17T07:25:26.107Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f59892fc00: Close()ing 1f598a65660
[task 2022-02-17T07:25:26.108Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f598930ec0: Close()ing 1f5988bd740
[task 2022-02-17T07:25:26.108Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5989e2880: Close()ing 1f598ad5e40
[task 2022-02-17T07:25:26.108Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5989f2ce0: Close()ing 1f598ae2900
[task 2022-02-17T07:25:26.109Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596e71ac0: Close()ing 1f598ae2d60
[task 2022-02-17T07:25:26.110Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596e72c40: Close()ing 1f598a2a900
[task 2022-02-17T07:25:26.111Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596d07ca0: Close()ing 1f596d459e0
[task 2022-02-17T07:25:26.112Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): All candidates initialized
[task 2022-02-17T07:25:26.113Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5989f1520: Close()ing 1f598a2ac80
[task 2022-02-17T07:25:26.114Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: I/mtransport NrIceCtx(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo)/STREAM(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0) : parsing trickle candidate candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host
[task 2022-02-17T07:25:26.114Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f598dbc520: Close()ing 1f596e89660
[task 2022-02-17T07:25:26.116Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(Zzzj): setting pair to state FROZEN: Zzzj|IP4:10.0.0.16:65256/UDP|IP4:10.0.0.16:65259/UDP(host(IP4:10.0.0.16:65256/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host)
[task 2022-02-17T07:25:26.117Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596d07c00: Close()ing 1f596d45740
[task 2022-02-17T07:25:26.117Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo)/CAND-PAIR(Zzzj): Pairing candidate IP4:10.0.0.16:65256/UDP (7e7f00ff):IP4:10.0.0.16:65259/UDP (7e7f00ff) priority=9115005270282338815 (7e7f00fffcfe01ff)
[task 2022-02-17T07:25:26.118Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596888b60: Close()ing 1f5967fae40
[task 2022-02-17T07:25:26.120Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/ICE-STREAM(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0 - b09dfac6:42184262a5d58f71bd44011bb5ddda46): Starting check timer for stream.
[task 2022-02-17T07:25:26.120Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f598dbda60: Close()ing 1f598dbf3c0
[task 2022-02-17T07:25:26.122Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(Zzzj): setting pair to state WAITING: Zzzj|IP4:10.0.0.16:65256/UDP|IP4:10.0.0.16:65259/UDP(host(IP4:10.0.0.16:65256/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host)
[task 2022-02-17T07:25:26.123Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f59893c5c0: Close()ing 1f598dbf740
[task 2022-02-17T07:25:26.124Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(Zzzj): setting pair to state IN_PROGRESS: Zzzj|IP4:10.0.0.16:65256/UDP|IP4:10.0.0.16:65259/UDP(host(IP4:10.0.0.16:65256/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host)
[task 2022-02-17T07:25:26.126Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f59893c660: Close()ing 1f598dbf9e0
[task 2022-02-17T07:25:26.126Z] 07:25:26 INFO - PID 6076 | (ice/NOTICE) ICE(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): peer (PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default) is now checking
[task 2022-02-17T07:25:26.127Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f598dbd2e0: Close()ing 1f598941820
[task 2022-02-17T07:25:26.129Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport NrIceCtx(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): state 0->1
[task 2022-02-17T07:25:26.129Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f598dbd380: Close()ing 1f598941ac0
[task 2022-02-17T07:25:26.132Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(Zzzj): triggered check on Zzzj|IP4:10.0.0.16:65256/UDP|IP4:10.0.0.16:65259/UDP(host(IP4:10.0.0.16:65256/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host)
[task 2022-02-17T07:25:26.134Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596a37240: Close()ing 1f596abe580
[task 2022-02-17T07:25:26.135Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(Zzzj): setting pair to state FROZEN: Zzzj|IP4:10.0.0.16:65256/UDP|IP4:10.0.0.16:65259/UDP(host(IP4:10.0.0.16:65256/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host)
[task 2022-02-17T07:25:26.137Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f598972b60: Close()ing 1f598dbb820
[task 2022-02-17T07:25:26.138Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo)/CAND-PAIR(Zzzj): Pairing candidate IP4:10.0.0.16:65256/UDP (7e7f00ff):IP4:10.0.0.16:65259/UDP (7e7f00ff) priority=9115005270282338815 (7e7f00fffcfe01ff)
[task 2022-02-17T07:25:26.138Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f598972c00: Close()ing 1f598dbbac0
[task 2022-02-17T07:25:26.140Z] 07:25:26 INFO - PID 6076 | (ice/INFO) CAND-PAIR(Zzzj): Adding pair to check list and trigger check queue: Zzzj|IP4:10.0.0.16:65256/UDP|IP4:10.0.0.16:65259/UDP(host(IP4:10.0.0.16:65256/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host)
[task 2022-02-17T07:25:26.141Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5989ab660: Close()ing 1f598ad5040
[task 2022-02-17T07:25:26.142Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(Zzzj): setting pair to state WAITING: Zzzj|IP4:10.0.0.16:65256/UDP|IP4:10.0.0.16:65259/UDP(host(IP4:10.0.0.16:65256/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host)
[task 2022-02-17T07:25:26.143Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f598dbd7e0: Close()ing 1f598afb900
[task 2022-02-17T07:25:26.144Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(Zzzj): setting pair to state CANCELLED: Zzzj|IP4:10.0.0.16:65256/UDP|IP4:10.0.0.16:65259/UDP(host(IP4:10.0.0.16:65256/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host)
[task 2022-02-17T07:25:26.145Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f598dbdc40: Close()ing 1f598afbba0
[task 2022-02-17T07:25:26.146Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f597423480: Close()ing 1f596b1fac0
[task 2022-02-17T07:25:26.147Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: I/mtransport NrIceCtx(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo)/STREAM(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0) : parsing trickle candidate candidate:1 1 TCP 2105524479 10.0.0.16 9 typ host tcptype active
[task 2022-02-17T07:25:26.148Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5968424c0: Close()ing 1f5967fa200
[task 2022-02-17T07:25:26.150Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f598951e20: Close()ing 1f598ae9740
[task 2022-02-17T07:25:26.151Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f598951ec0: Close()ing 1f598ae99e0
[task 2022-02-17T07:25:26.153Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(/uVE): triggered check on /uVE|IP4:10.0.0.16:65259/UDP|IP4:10.0.0.16:65256/UDP(host(IP4:10.0.0.16:65259/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65256 typ host)
[task 2022-02-17T07:25:26.153Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f597423980: Close()ing 1f596b33660
[task 2022-02-17T07:25:26.154Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596792ba0: Close()ing 1f596873660
[task 2022-02-17T07:25:26.155Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f597424560: Close()ing 1f596b339e0
[task 2022-02-17T07:25:26.156Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(/uVE): setting pair to state FROZEN: /uVE|IP4:10.0.0.16:65259/UDP|IP4:10.0.0.16:65256/UDP(host(IP4:10.0.0.16:65259/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65256 typ host)
[task 2022-02-17T07:25:26.157Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo)/CAND-PAIR(/uVE): Pairing candidate IP4:10.0.0.16:65259/UDP (7e7f00ff):IP4:10.0.0.16:65256/UDP (7e7f00ff) priority=9115005270282338815 (7e7f00fffcfe01ff)[Child 7748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2462: Passing local candidate to content:
[task 2022-02-17T07:25:26.159Z] 07:25:26 INFO - PID 6076 | (ice/INFO) CAND-PAIR(/uVE): Adding pair to check list and trigger check queue: /uVE|IP4:10.0.0.16:65259/UDP|IP4:10.0.0.16:65256/UDP(host(IP4:10.0.0.16:65259/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65256 typ host)
[task 2022-02-17T07:25:26.160Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(/uVE): setting pair to state WAITING: /uVE|IP4:10.0.0.16:65259/UDP|IP4:10.0.0.16:65256/UDP(host(IP4:10.0.0.16:65259/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65256 typ host)
[task 2022-02-17T07:25:26.162Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(/uVE): setting pair to state CANCELLED: /uVE|IP4:10.0.0.16:65259/UDP|IP4:10.0.0.16:65256/UDP(host(IP4:10.0.0.16:65259/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65256 typ host)
[task 2022-02-17T07:25:26.162Z] 07:25:26 INFO - PID 6076 | (stun/INFO) STUN-CLIENT(/uVE|IP4:10.0.0.16:65259/UDP|IP4:10.0.0.16:65256/UDP(host(IP4:10.0.0.16:65259/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65256 typ host)): Received response; processing
[task 2022-02-17T07:25:26.164Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(/uVE): setting pair to state SUCCEEDED: /uVE|IP4:10.0.0.16:65259/UDP|IP4:10.0.0.16:65256/UDP(host(IP4:10.0.0.16:65259/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65256 typ host)
[task 2022-02-17T07:25:26.165Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/STREAM(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0 - 62405445:b32053c028f9dc099728e49a1a10fa34)/COMP(1)/CAND-PAIR(/uVE): nominated pair is /uVE|IP4:10.0.0.16:65259/UDP|IP4:10.0.0.16:65256/UDP(host(IP4:10.0.0.16:65259/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65256 typ host)
[task 2022-02-17T07:25:26.167Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/STREAM(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0 - 62405445:b32053c028f9dc099728e49a1a10fa34)/COMP(1)/CAND-PAIR(/uVE): cancelling all pairs but /uVE|IP4:10.0.0.16:65259/UDP|IP4:10.0.0.16:65256/UDP(host(IP4:10.0.0.16:65259/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65256 typ host)[Child 7748: libwebrtcModuleThread]: E/signaling [libwebrtcModuleThread|WebrtcAudioSessionConduit] AudioConduit.cpp:666: AudioConduit 1f59881c400: RTCP RR Packet Send Failed
[task 2022-02-17T07:25:26.168Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f597c73c00: Close()ing 1f596ede900
[task 2022-02-17T07:25:26.168Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596849ce0: Close()ing 1f595c73660
[task 2022-02-17T07:25:26.170Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/STREAM(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0 - 62405445:b32053c028f9dc099728e49a1a10fa34)/COMP(1)/CAND-PAIR(/uVE): cancelling FROZEN/WAITING pair /uVE|IP4:10.0.0.16:65259/UDP|IP4:10.0.0.16:65256/UDP(host(IP4:10.0.0.16:65259/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65256 typ host) in trigger check queue because CAND-PAIR(/uVE) was nominated.
[task 2022-02-17T07:25:26.170Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5989e10c0: Close()ing 1f5989b13c0
[task 2022-02-17T07:25:26.171Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(/uVE): setting pair to state CANCELLED: /uVE|IP4:10.0.0.16:65259/UDP|IP4:10.0.0.16:65256/UDP(host(IP4:10.0.0.16:65259/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65256 typ host)
[task 2022-02-17T07:25:26.172Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596888a20: Close()ing 1f595c739e0
[task 2022-02-17T07:25:26.173Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/ICE-STREAM(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0 - 62405445:b32053c028f9dc099728e49a1a10fa34): all active components have nominated candidate pairs
[task 2022-02-17T07:25:26.174Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5976bf7a0: Close()ing 1f59769a2e0
[task 2022-02-17T07:25:26.174Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[ice]: ICE Ready(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0,1)
[task 2022-02-17T07:25:26.176Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5989f2380: Close()ing 1f598a65f20
[task 2022-02-17T07:25:26.176Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[dtls]: Lower layer is now open; starting TLS
[task 2022-02-17T07:25:26.177Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596afd8e0: Close()ing 1f59683c820
[task 2022-02-17T07:25:26.178Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default): all checks completed success=1 fail=0
[task 2022-02-17T07:25:26.179Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596afdac0: Close()ing 1f59683cac0
[task 2022-02-17T07:25:26.180Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): peer (PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default) cancelling grace period timer
[task 2022-02-17T07:25:26.180Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596849740: Close()ing 1f596818ac0
[task 2022-02-17T07:25:26.182Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: I/mtransport NrIceCtx(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo)/STREAM(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0) : parsing trickle candidate
[task 2022-02-17T07:25:26.183Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596848c00: Close()ing 1f596818740
[task 2022-02-17T07:25:26.184Z] 07:25:26 INFO - PID 6076 | (ice/WARNING) ICE(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): Error parsing attribute:
[task 2022-02-17T07:25:26.184Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596aa95c0: Close()ing 1f596a669e0
[task 2022-02-17T07:25:26.186Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: E/mtransport Couldn't parse trickle candidate for stream 'PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0':
[task 2022-02-17T07:25:26.186Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596aa9520: Close()ing 1f596a66740
[task 2022-02-17T07:25:26.187Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: E/signaling [Socket Thread|MediaTransportHandler] MediaTransportHandler.cpp:1013: Couldn't process ICE candidate with transport id transport_0:
[task 2022-02-17T07:25:26.188Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596888fc0: Close()ing 1f596873200
[task 2022-02-17T07:25:26.189Z] 07:25:26 INFO - PID 6076 | (stun/INFO) STUN-CLIENT(Zzzj|IP4:10.0.0.16:65256/UDP|IP4:10.0.0.16:65259/UDP(host(IP4:10.0.0.16:65256/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host)): Received response; processing
[task 2022-02-17T07:25:26.190Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f599015200: Close()ing 1f59898c740
[task 2022-02-17T07:25:26.192Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(Zzzj): setting pair to state SUCCEEDED: Zzzj|IP4:10.0.0.16:65256/UDP|IP4:10.0.0.16:65259/UDP(host(IP4:10.0.0.16:65256/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host)
[task 2022-02-17T07:25:26.192Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5990152a0: Close()ing 1f59898c9e0
[task 2022-02-17T07:25:26.193Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/STREAM(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0 - b09dfac6:42184262a5d58f71bd44011bb5ddda46)/COMP(1)/CAND-PAIR(Zzzj): nominated pair is Zzzj|IP4:10.0.0.16:65256/UDP|IP4:10.0.0.16:65259/UDP(host(IP4:10.0.0.16:65256/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host)
[task 2022-02-17T07:25:26.194Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596afe560: Close()ing 1f596a66200
[task 2022-02-17T07:25:26.195Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/STREAM(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0 - b09dfac6:42184262a5d58f71bd44011bb5ddda46)/COMP(1)/CAND-PAIR(Zzzj): cancelling all pairs but Zzzj|IP4:10.0.0.16:65256/UDP|IP4:10.0.0.16:65259/UDP(host(IP4:10.0.0.16:65256/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host)
[task 2022-02-17T07:25:26.195Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5960c3840: Close()ing 1f595cfa3c0
[task 2022-02-17T07:25:26.197Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/STREAM(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0 - b09dfac6:42184262a5d58f71bd44011bb5ddda46)/COMP(1)/CAND-PAIR(Zzzj): cancelling FROZEN/WAITING pair Zzzj|IP4:10.0.0.16:65256/UDP|IP4:10.0.0.16:65259/UDP(host(IP4:10.0.0.16:65256/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host) in trigger check queue because CAND-PAIR(Zzzj) was nominated.
[task 2022-02-17T07:25:26.197Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5990164c0: Close()ing 1f599008740
[task 2022-02-17T07:25:26.198Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/CAND-PAIR(Zzzj): setting pair to state CANCELLED: Zzzj|IP4:10.0.0.16:65256/UDP|IP4:10.0.0.16:65259/UDP(host(IP4:10.0.0.16:65256/UDP)|candidate:0 1 UDP 2122252543 10.0.0.16 65259 typ host)
[task 2022-02-17T07:25:26.199Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f599016560: Close()ing 1f5990089e0
[task 2022-02-17T07:25:26.200Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default)/ICE-STREAM(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0 - b09dfac6:42184262a5d58f71bd44011bb5ddda46): all active components have nominated candidate pairs
[task 2022-02-17T07:25:26.200Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596daef20: Close()ing 1f596dbb9e0
[task 2022-02-17T07:25:26.201Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[ice]: ICE Ready(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0,1)
[task 2022-02-17T07:25:26.202Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596d28740: Close()ing 1f596d2d740
[task 2022-02-17T07:25:26.202Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[dtls]: Lower layer is now open; starting TLS
[task 2022-02-17T07:25:26.203Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596b71840: Close()ing 1f596ba1740
[task 2022-02-17T07:25:26.203Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE-PEER(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default): all checks completed success=1 fail=0
[task 2022-02-17T07:25:26.204Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5990505c0: Close()ing 1f599006900
[task 2022-02-17T07:25:26.205Z] 07:25:26 INFO - PID 6076 | (ice/INFO) ICE(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): peer (PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo:default) cancelling grace period timer
[task 2022-02-17T07:25:26.206Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f599050660: Close()ing 1f599006ba0
[task 2022-02-17T07:25:26.206Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596b718e0: Close()ing 1f596ba19e0
[task 2022-02-17T07:25:26.207Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596841de0: Close()ing 1f596a5be40
[task 2022-02-17T07:25:26.207Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596889ce0: Close()ing 1f596873740
[task 2022-02-17T07:25:26.208Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
[task 2022-02-17T07:25:26.208Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5990519c0: Close()ing 1f598f72ba0
[task 2022-02-17T07:25:26.209Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596791700: Close()ing 1f59673b740
[task 2022-02-17T07:25:26.210Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport NrIceCtx(PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): state 1->2
[task 2022-02-17T07:25:26.210Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel Connection 1f596761a00/Channel 1f59673b740: Closing stream 1
[task 2022-02-17T07:25:26.211Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
[task 2022-02-17T07:25:26.211Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel Removing channel 1 : 1f59673b740
[task 2022-02-17T07:25:26.212Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: D/mtransport NrIceCtx(PC:{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo): state 1->2
[task 2022-02-17T07:25:26.212Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel Channel already closing/closed (2)
[task 2022-02-17T07:25:26.213Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel Deleting DataChannelConnection 1f596761a00
[task 2022-02-17T07:25:26.213Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596a36ca0: Close()ing 1f596a7ec80
[task 2022-02-17T07:25:26.214Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596849600: Close()ing 1f59673b2e0
[task 2022-02-17T07:25:26.215Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f599093d40: Close()ing 1f59902d740
[task 2022-02-17T07:25:26.215Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f599093de0: Close()ing 1f59902d9e0
[task 2022-02-17T07:25:26.216Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596afd3e0: Close()ing 1f596a944a0
[task 2022-02-17T07:25:26.217Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5974a7d80: Close()ing 1f59751b040
[task 2022-02-17T07:25:26.217Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596ef10c0: Close()ing 1f59751b200
[task 2022-02-17T07:25:26.218Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596ef13e0: Close()ing 1f59751b3c0
[task 2022-02-17T07:25:26.218Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596ef1700: Close()ing 1f59751b580
[task 2022-02-17T07:25:26.219Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
[task 2022-02-17T07:25:26.219Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f59892f8e0: Close()ing 1f598a65200
[task 2022-02-17T07:25:26.220Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596ef1a20: Close()ing 1f59751b740
[task 2022-02-17T07:25:26.221Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596d287e0: Close()ing 1f596d2d9e0
[task 2022-02-17T07:25:26.221Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5989e2ec0: Close()ing 1f598aa5200
[task 2022-02-17T07:25:26.222Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596ef1d40: Close()ing 1f59751b900
[task 2022-02-17T07:25:26.222Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596ef2060: Close()ing 1f59751bac0
[task 2022-02-17T07:25:26.223Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f59904e340: Close()ing 1f5990349e0
[task 2022-02-17T07:25:26.224Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596ef2380: Close()ing 1f59751bc80
[task 2022-02-17T07:25:26.224Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596ef26a0: Close()ing 1f59751be40
[task 2022-02-17T07:25:26.225Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5989e21a0: Close()ing 1f598a659e0
[task 2022-02-17T07:25:26.225Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596ef29c0: Close()ing 1f59751a040
[task 2022-02-17T07:25:26.226Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596889f60: Close()ing 1f596a7e900
[task 2022-02-17T07:25:26.226Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5988c7560: Close()ing 1f598891c80
[task 2022-02-17T07:25:26.227Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596ef2ce0: Close()ing 1f59751a200
[task 2022-02-17T07:25:26.228Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5975f6340: Close()ing 1f597605040
[task 2022-02-17T07:25:26.228Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5975f6160: Close()ing 1f5975ccc80
[task 2022-02-17T07:25:26.229Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f597551f60: Close()ing 1f59757be40
[task 2022-02-17T07:25:26.229Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5975517e0: Close()ing 1f59757bba0
[task 2022-02-17T07:25:26.230Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5989f2920: Close()ing 1f598aa5c80
[task 2022-02-17T07:25:26.231Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f597550fc0: Close()ing 1f59757b9e0
[task 2022-02-17T07:25:26.231Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f597538f60: Close()ing 1f59757b820
[task 2022-02-17T07:25:26.232Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f597538ba0: Close()ing 1f59757b660
[task 2022-02-17T07:25:26.232Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f597538880: Close()ing 1f59757b4a0
[task 2022-02-17T07:25:26.233Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5975372a0: Close()ing 1f59757b2e0
[task 2022-02-17T07:25:26.233Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5974a77e0: Close()ing 1f5975ccf20
[task 2022-02-17T07:25:26.234Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5991032a0: Close()ing 1f5976e32e0
[task 2022-02-17T07:25:26.234Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f5975377a0: Close()ing 1f597508660
[task 2022-02-17T07:25:26.235Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f597537840: Close()ing 1f597508900
[task 2022-02-17T07:25:26.236Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596b72ba0: Close()ing 1f596a259e0
[task 2022-02-17T07:25:26.236Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596b72b00: Close()ing 1f596a25740
[task 2022-02-17T07:25:26.237Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596d289c0: Close()ing 1f596d939e0
[task 2022-02-17T07:25:26.237Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f596841340: Close()ing 1f5967213c0
[task 2022-02-17T07:25:26.238Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: D/DataChannel 1f597551060: Close()ing 1f597525580
[task 2022-02-17T07:25:26.238Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:1953: Close: Closing PeerConnectionImpl {73e197ab-f338-4867-9bd0-ffd000dddf3d}; ending call
[task 2022-02-17T07:25:26.239Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: I/jsep [{73e197ab-f338-4867-9bd0-ffd000dddf3d} 1645082717852000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo]: stable -> closed
[task 2022-02-17T07:25:26.240Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:1953: Close: Closing PeerConnectionImpl {86da3df5-fab8-45fc-8c22-42e1e7c93d6d}; ending call
[task 2022-02-17T07:25:26.240Z] 07:25:26 INFO - PID 6076 | [Child 7748: Main Thread]: I/jsep [{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo]: stable -> closed
[task 2022-02-17T07:25:26.241Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: E/mtransport Couldn't send media on 'PC:{86da3df5-fab8-45fc-8c22-42e1e7c93d6d} 1645082717857000 (id=12884901921 url=http://web-platform.test:8000/webrtc/RTCDtlsTranspo transport-id=transport_0'
[task 2022-02-17T07:25:26.242Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: E/mtransport Flow[transport_0(none)]; Layer[dtls]: DTLS handshake error -5991 (PR_IO_ERROR)
[task 2022-02-17T07:25:26.243Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: E/mtransport /builds/worker/checkouts/gecko/dom/media/webrtc/transport/transportlayerdtls.cpp:936: Flow[transport_0(none)]; Layer[dtls]: state 2->5
[task 2022-02-17T07:25:26.243Z] 07:25:26 INFO - PID 6076 | [Child 7748: Socket Thread]: E/mtransport /builds/worker/checkouts/gecko/dom/media/webrtc/transport/transportlayersrtp.cpp:174: Flow[transport_0(none)]; Layer[srtp]: state 2->5
[task 2022-02-17T07:25:26.244Z] 07:25:26 INFO - PID 6076 | [Child 7748: WebrtcCallThread #1]: E/signaling [WebrtcCallThread #1|WebrtcAudioSessionConduit] AudioConduit.cpp:646: SendSenderRtcp RTCP SR Packet Send Failed
[task 2022-02-17T07:25:26.460Z] 07:25:26 INFO - PID 6076 | ###!!! [Parent][PGPUParent] Error: RunMessage(msgname=PGPU::Msg_ShutdownVR) Channel closing: too late to send/recv, messages will be lost
[task 2022-02-17T07:25:26.515Z] 07:25:26 INFO - Browser exited with return code 0
[task 2022-02-17T07:25:26.517Z] 07:25:26 INFO - Closing logging queue
[task 2022-02-17T07:25:26.518Z] 07:25:26 INFO - queue closed
[task 2022-02-17T07:25:26.592Z] 07:25:26 INFO - Application command: Z:\task_164507819717921\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_164507819717921\AppData\Local\Temp\tmpas0icgtu
[task 2022-02-17T07:25:26.597Z] 07:25:26 INFO - PID 9024 | 1645082663099 Marionette INFO Marionette enabled
[task 2022-02-17T07:25:26.598Z] 07:25:26 INFO - PID 9024 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_164507819717921\\AppData\\Local\\Temp\\tmpzons2_6u\\search.json.mozlz4", (void 0)))
[task 2022-02-17T07:25:26.599Z] 07:25:26 INFO - PID 9024 | 1645082665776 Marionette INFO Listening on port 54704
[task 2022-02-17T07:25:26.599Z] 07:25:26 INFO - Starting runner
[task 2022-02-17T07:25:27.568Z] 07:25:27 INFO - TEST-START | /webrtc/RTCIceCandidate-constructor.html
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 3•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
|
Reporter | |
Comment 4•3 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=380955404&repo=mozilla-central
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 6•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: REOPENED → RESOLVED
Closed: 4 years ago → 3 years ago
Resolution: --- → INCOMPLETE
|
|
Reporter | |
Comment 7•3 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=387263430&repo=mozilla-central
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
||
Updated•3 years ago
|
Status: REOPENED → RESOLVED
Closed: 3 years ago → 3 years ago
Resolution: --- → DUPLICATE
You need to log in
before you can comment on or make changes to this bug.
Description
•