Closed Bug 1648563 Opened 5 years ago Closed 5 years ago

Intermittent [TV] TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-createDataChannel.html | Stopping the bundle-tag when there is a negotiated datachannel in the bundle should kill the DataChannel - Test timed out

Categories

(Core :: WebRTC, defect, P5)

Product:
Core
Component:
WebRTC
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=307528287&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/NzE0RE-qRJuNaRyBr1LgOA/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/NzE0RE-qRJuNaRyBr1LgOA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2020-06-25T15:22:35.214Z] 15:22:35 INFO - TEST-PASS | /webrtc/RTCPeerConnection-createDataChannel.html | addTrack, then creating negotiated datachannel, should negotiate properly when max-bundle is used
[task 2020-06-25T15:22:35.214Z] 15:22:35 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-createDataChannel.html | Stopping the bundle-tag when there is a negotiated datachannel in the bundle should kill the DataChannel - Test timed out
[task 2020-06-25T15:22:35.214Z] 15:22:35 INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCPeerConnection-createDataChannel.html | expected OK
[task 2020-06-25T15:22:35.214Z] 15:22:35 INFO - TEST-INFO took 15715ms
[task 2020-06-25T15:22:35.243Z] 15:22:35 INFO - PID 2388 | 1593098555224 Marionette INFO Stopped listening on port 49910
[task 2020-06-25T15:22:35.424Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2121: CloseInt: Closing PeerConnectionImpl 3b8783e81cdc1954; ending call
[task 2020-06-25T15:22:35.425Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: I/jsep [1593098554312000 (id=2147483651 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)]: stable -> closed
[task 2020-06-25T15:22:35.426Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2127: CloseInt: Destroying DataChannelConnection 06F05CE0 for 3b8783e81cdc1954
[task 2020-06-25T15:22:35.426Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Destroying DataChannelConnection 06F05CE0
[task 2020-06-25T15:22:35.427Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Closing all channels (connection 06F05CE0)
[task 2020-06-25T15:22:35.427Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel DataChannelConnection labeled transport_0 (06F05CE0) switching connection state CONNECTING -> CLOSED
[task 2020-06-25T15:22:35.427Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Connection 06F05CE0/Channel 0C519920: Closing stream 0
[task 2020-06-25T15:22:35.428Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Removing channel 0 : 0C519920
[task 2020-06-25T15:22:35.428Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Connection 06F05CE0: Resetting outgoing stream 0
[task 2020-06-25T15:22:35.428Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel DataChannelConnection labeled (0C519920) (stream 0) changing ready state CONNECTING -> CLOSING
[task 2020-06-25T15:22:35.428Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Destroying Data channel 0
[task 2020-06-25T15:22:35.429Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel closing pending channel 0C519920, stream 0
[task 2020-06-25T15:22:35.429Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Connection 06F05CE0/Channel 0C519920: Closing stream 0
[task 2020-06-25T15:22:35.429Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Removing channel 0 : 0C519920
[task 2020-06-25T15:22:35.430Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Channel already closing/closed (2)
[task 2020-06-25T15:22:35.430Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Connection 06F05CE0: Sending outgoing stream reset for 1 streams
[task 2020-06-25T15:22:35.430Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: E/DataChannel ***failed: setsockopt RESET, errno 2
[task 2020-06-25T15:22:35.431Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Clearing resets for 1 streams
[task 2020-06-25T15:22:35.431Z] 15:22:35 INFO - PID 2388 | [Child 4368: Socket Thread]: D/DataChannel Deregistered 0000002E from the SCTP stack.
[task 2020-06-25T15:22:35.432Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2121: CloseInt: Closing PeerConnectionImpl 510b807bed11c6f1; ending call
[task 2020-06-25T15:22:35.432Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: I/jsep [1593098554314000 (id=2147483651 url=http://web-platform.test:8000/webrtc/RTCPeerConnection-createDataChannel.html)]: stable -> closed
[task 2020-06-25T15:22:35.433Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2127: CloseInt: Destroying DataChannelConnection 06F05E70 for 510b807bed11c6f1
[task 2020-06-25T15:22:35.433Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Destroying DataChannelConnection 06F05E70
[task 2020-06-25T15:22:35.434Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Closing all channels (connection 06F05E70)
[task 2020-06-25T15:22:35.434Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel DataChannelConnection labeled transport_0 (06F05E70) switching connection state CONNECTING -> CLOSED
[task 2020-06-25T15:22:35.435Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Connection 06F05E70/Channel 0C5199D0: Closing stream 0
[task 2020-06-25T15:22:35.436Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Removing channel 0 : 0C5199D0
[task 2020-06-25T15:22:35.436Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Connection 06F05E70: Resetting outgoing stream 0
[task 2020-06-25T15:22:35.437Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel DataChannelConnection labeled (0C5199D0) (stream 0) changing ready state CONNECTING -> CLOSING
[task 2020-06-25T15:22:35.437Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Destroying Data channel 0
[task 2020-06-25T15:22:35.437Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel closing pending channel 0C5199D0, stream 0
[task 2020-06-25T15:22:35.437Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Connection 06F05E70/Channel 0C5199D0: Closing stream 0
[task 2020-06-25T15:22:35.437Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Removing channel 0 : 0C5199D0
[task 2020-06-25T15:22:35.437Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Channel already closing/closed (2)
[task 2020-06-25T15:22:35.437Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Connection 06F05E70: Sending outgoing stream reset for 1 streams
[task 2020-06-25T15:22:35.437Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: E/DataChannel ***failed: setsockopt RESET, errno 2
[task 2020-06-25T15:22:35.438Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Clearing resets for 1 streams
[task 2020-06-25T15:22:35.438Z] 15:22:35 INFO - PID 2388 | [Child 4368: Socket Thread]: D/DataChannel Deregistered 0000002F from the SCTP stack.
[task 2020-06-25T15:22:35.441Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel DataChannelConnection labeled (0C519920) (stream 0) changing ready state CLOSING -> CLOSED
[task 2020-06-25T15:22:35.441Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel operator(): sending ON_CHANNEL_CLOSED for /: 0
[task 2020-06-25T15:22:35.441Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 08E469A0(0C519920): OnChannelClosed - Dispatching
[task 2020-06-25T15:22:35.441Z] 15:22:35 INFO - PID 2388 | [Child 4368, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp, line 291
[task 2020-06-25T15:22:35.442Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Deleting DataChannelConnection 06F05CE0
[task 2020-06-25T15:22:35.442Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel DataChannelConnection labeled (0C5199D0) (stream 0) changing ready state CLOSING -> CLOSED
[task 2020-06-25T15:22:35.442Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel operator(): sending ON_CHANNEL_CLOSED for /: 0
[task 2020-06-25T15:22:35.442Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 08E46BE0(0C5199D0): OnChannelClosed - Dispatching
[task 2020-06-25T15:22:35.442Z] 15:22:35 INFO - PID 2388 | [Child 4368, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp, line 291
[task 2020-06-25T15:22:35.442Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Deleting DataChannelConnection 06F05E70
[task 2020-06-25T15:22:35.545Z] 15:22:35 INFO - PID 2388 | [Child 5380, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-06-25T15:22:35.620Z] 15:22:35 INFO - PID 2388 | [Child 2796, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-06-25T15:22:35.743Z] 15:22:35 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel Shutting down SCTP
[task 2020-06-25T15:22:35.878Z] 15:22:35 INFO - PID 2388 | [2020-06-25T15:22:35Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-06-25T15:22:35.878Z] 15:22:35 INFO - PID 2388 | [2020-06-25T15:22:35Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-06-25T15:22:36.773Z] 15:22:36 INFO - PID 2388 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-06-25T15:22:36.788Z] 15:22:36 INFO - PID 2388 | Timecard created 1593098554.311000
[task 2020-06-25T15:22:36.788Z] 15:22:36 INFO - PID 2388 | Timestamp | Delta | Event | File | Function
[task 2020-06-25T15:22:36.789Z] 15:22:36 INFO - PID 2388 | =====================================================================================================================
[task 2020-06-25T15:22:36.789Z] 15:22:36 INFO - PID 2388 | 0.000000 | 0.000000 | Constructor Completed | PeerConnectionImpl.cpp:332 | PeerConnectionImpl
[task 2020-06-25T15:22:36.789Z] 15:22:36 INFO - PID 2388 | 0.001000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:458 | Initialize
[task 2020-06-25T15:22:36.789Z] 15:22:36 INFO - PID 2388 | 0.024000 | 0.023000 | Create Offer | PeerConnectionImpl.cpp:1239 | CreateOffer
[task 2020-06-25T15:22:36.789Z] 15:22:36 INFO - PID 2388 | 0.027000 | 0.003000 | Set Local Description | PeerConnectionImpl.cpp:1308 | SetLocalDescription
[task 2020-06-25T15:22:36.790Z] 15:22:36 INFO - PID 2388 | 0.061000 | 0.034000 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2530 | IceGatheringStateChange
[task 2020-06-25T15:22:36.790Z] 15:22:36 INFO - PID 2388 | 0.069000 | 0.008000 | Ice gathering state: complete | PeerConnectionImpl.cpp:2533 | IceGatheringStateChange
[task 2020-06-25T15:22:36.790Z] 15:22:36 INFO - PID 2388 | 0.071000 | 0.002000 | Set Remote Description | PeerConnectionImpl.cpp:1403 | SetRemoteDescription
[task 2020-06-25T15:22:36.790Z] 15:22:36 INFO - PID 2388 | 0.081000 | 0.010000 | Add Ice Candidate | PeerConnectionImpl.cpp:1548 | AddIceCandidate
[task 2020-06-25T15:22:36.791Z] 15:22:36 INFO - PID 2388 | 0.082000 | 0.001000 | Look up mDNS name | PeerConnectionImpl.cpp:1836 | StampTimecard
[task 2020-06-25T15:22:36.791Z] 15:22:36 INFO - PID 2388 | 0.083000 | 0.001000 | Add Ice Candidate | PeerConnectionImpl.cpp:1548 | AddIceCandidate
[task 2020-06-25T15:22:36.791Z] 15:22:36 INFO - PID 2388 | 0.083000 | 0.000000 | Look up mDNS name | PeerConnectionImpl.cpp:1836 | StampTimecard
[task 2020-06-25T15:22:36.791Z] 15:22:36 INFO - PID 2388 | 0.084000 | 0.001000 | Add Ice Candidate | PeerConnectionImpl.cpp:1548 | AddIceCandidate
[task 2020-06-25T15:22:36.792Z] 15:22:36 INFO - PID 2388 | 2.467000 | 2.383000 | Destructor Invoked | PeerConnectionImpl.cpp:341 | ~PeerConnectionImpl
[task 2020-06-25T15:22:36.793Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:362: PeerConnectionCtx is already gone. Ignoring...
[task 2020-06-25T15:22:36.794Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:366: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for 3b8783e81cdc1954
[task 2020-06-25T15:22:36.794Z] 15:22:36 INFO - PID 2388 | Timecard created 1593098554.313000
[task 2020-06-25T15:22:36.794Z] 15:22:36 INFO - PID 2388 | Timestamp | Delta | Event | File | Function
[task 2020-06-25T15:22:36.794Z] 15:22:36 INFO - PID 2388 | =====================================================================================================================
[task 2020-06-25T15:22:36.797Z] 15:22:36 INFO - PID 2388 | 0.000000 | 0.000000 | Constructor Completed | PeerConnectionImpl.cpp:332 | PeerConnectionImpl
[task 2020-06-25T15:22:36.804Z] 15:22:36 INFO - PID 2388 | 0.001000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:458 | Initialize
[task 2020-06-25T15:22:36.805Z] 15:22:36 INFO - PID 2388 | 0.029000 | 0.028000 | Set Remote Description | PeerConnectionImpl.cpp:1403 | SetRemoteDescription
[task 2020-06-25T15:22:36.806Z] 15:22:36 INFO - PID 2388 | 0.050000 | 0.021000 | Create Answer | PeerConnectionImpl.cpp:1270 | CreateAnswer
[task 2020-06-25T15:22:36.806Z] 15:22:36 INFO - PID 2388 | 0.053000 | 0.003000 | Set Local Description | PeerConnectionImpl.cpp:1308 | SetLocalDescription
[task 2020-06-25T15:22:36.806Z] 15:22:36 INFO - PID 2388 | 0.069000 | 0.016000 | Add Ice Candidate | PeerConnectionImpl.cpp:1548 | AddIceCandidate
[task 2020-06-25T15:22:36.806Z] 15:22:36 INFO - PID 2388 | 0.074000 | 0.005000 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2530 | IceGatheringStateChange
[task 2020-06-25T15:22:36.807Z] 15:22:36 INFO - PID 2388 | 0.077000 | 0.003000 | Look up mDNS name | PeerConnectionImpl.cpp:1836 | StampTimecard
[task 2020-06-25T15:22:36.807Z] 15:22:36 INFO - PID 2388 | 0.077000 | 0.000000 | Add Ice Candidate | PeerConnectionImpl.cpp:1548 | AddIceCandidate
[task 2020-06-25T15:22:36.807Z] 15:22:36 INFO - PID 2388 | 0.077000 | 0.000000 | Ice gathering state: complete | PeerConnectionImpl.cpp:2533 | IceGatheringStateChange
[task 2020-06-25T15:22:36.807Z] 15:22:36 INFO - PID 2388 | 0.080000 | 0.003000 | Look up mDNS name | PeerConnectionImpl.cpp:1836 | StampTimecard
[task 2020-06-25T15:22:36.808Z] 15:22:36 INFO - PID 2388 | 0.080000 | 0.000000 | Add Ice Candidate | PeerConnectionImpl.cpp:1548 | AddIceCandidate
[task 2020-06-25T15:22:36.808Z] 15:22:36 INFO - PID 2388 | 0.081000 | 0.001000 | Look up mDNS name | PeerConnectionImpl.cpp:1836 | StampTimecard
[task 2020-06-25T15:22:36.808Z] 15:22:36 INFO - PID 2388 | 0.081000 | 0.000000 | Add Ice Candidate | PeerConnectionImpl.cpp:1548 | AddIceCandidate
[task 2020-06-25T15:22:36.808Z] 15:22:36 INFO - PID 2388 | 0.082000 | 0.001000 | Look up mDNS name | PeerConnectionImpl.cpp:1836 | StampTimecard
[task 2020-06-25T15:22:36.808Z] 15:22:36 INFO - PID 2388 | 0.082000 | 0.000000 | Add Ice Candidate | PeerConnectionImpl.cpp:1548 | AddIceCandidate
[task 2020-06-25T15:22:36.808Z] 15:22:36 INFO - PID 2388 | 2.477000 | 2.395000 | Destructor Invoked | PeerConnectionImpl.cpp:341 | ~PeerConnectionImpl
[task 2020-06-25T15:22:36.809Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:362: PeerConnectionCtx is already gone. Ignoring...
[task 2020-06-25T15:22:36.809Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:366: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for 510b807bed11c6f1
[task 2020-06-25T15:22:36.809Z] 15:22:36 INFO - PID 2388 | [Child 868, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-06-25T15:22:36.868Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 08E46BE0: Close()ing 0C5199D0
[task 2020-06-25T15:22:36.869Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 08E469A0: Close()ing 0C519920
[task 2020-06-25T15:22:36.870Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 00FA0FA0: Close()ing 0DBA8BE0
[task 2020-06-25T15:22:36.870Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 00FA0CA0: Close()ing 0DBA8B30
[task 2020-06-25T15:22:36.870Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 08EEFA60: Close()ing 0B90ED40
[task 2020-06-25T15:22:36.870Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 08EEF9A0: Close()ing 0B90EBE0
[task 2020-06-25T15:22:36.871Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 0DBBE640: Close()ing 0DBA85B0
[task 2020-06-25T15:22:36.871Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 0D26C940: Close()ing 0C6B2920
[task 2020-06-25T15:22:36.871Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 0D26CA60: Close()ing 0C6B2DF0
[task 2020-06-25T15:22:36.871Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 0C8CC280: Close()ing 0B90EF50
[task 2020-06-25T15:22:36.871Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 0B96AE20: Close()ing 0B90E5B0
[task 2020-06-25T15:22:36.872Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 08E05BE0: Close()ing 0B72A660
[task 2020-06-25T15:22:36.947Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 00FD68E0: Close()ing 0B90EA80
[task 2020-06-25T15:22:36.947Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 08E056A0: Close()ing 0B72A870
[task 2020-06-25T15:22:36.947Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 0DBBE580: Close()ing 0DBA8500
[task 2020-06-25T15:22:36.947Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 0B96AE80: Close()ing 0B90E710
[task 2020-06-25T15:22:36.948Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 08E05700: Close()ing 0B72A500
[task 2020-06-25T15:22:36.948Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 08E05B20: Close()ing 08E5DB30
[task 2020-06-25T15:22:36.948Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 08E05AC0: Close()ing 08E5D870
[task 2020-06-25T15:22:36.948Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 08E05A60: Close()ing 08E5D660
[task 2020-06-25T15:22:36.948Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 0B96A520: Close()ing 0C6B20E0
[task 2020-06-25T15:22:36.948Z] 15:22:36 INFO - PID 2388 | [Child 4368: Main Thread]: D/DataChannel 0B96A4C0: Close()ing 0C6B2030
[task 2020-06-25T15:22:36.967Z] 15:22:36 INFO - PID 2388 | [Child 4368, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-06-25T15:22:36.982Z] 15:22:36 INFO - PID 2388 | [Child 6072, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-06-25T15:22:37.042Z] 15:22:37 INFO - PID 2388 | [Parent 4660, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file /builds/worker/checkouts/gecko/netwerk/cache/nsDiskCacheDeviceSQL.cpp, line 1279
[task 2020-06-25T15:22:37.085Z] 15:22:37 INFO - PID 2388 | [Parent 4660, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/js/xpconnect/loader/mozJSComponentLoader.cpp, line 227
[task 2020-06-25T15:22:37.085Z] 15:22:37 INFO - PID 2388 | [Parent 4660, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/js/xpconnect/loader/mozJSComponentLoader.cpp, line 254
[task 2020-06-25T15:22:37.752Z] 15:22:37 INFO - PID 2388 | [Parent 4660, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp, line 3359
[task 2020-06-25T15:22:37.879Z] 15:22:37 INFO - Browser exited with return code 0

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

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