Closed
Bug 1733624
Opened 3 years ago
Closed 3 years ago
Intermittent [tier 2] Main app process exited normally | application crashed [@ mozilla::NrTcpSocket::DoCallbacks()]
Categories
(Core :: WebRTC: Networking, defect)
Core
WebRTC: Networking
Tracking
()
RESOLVED
DUPLICATE
of bug 1717285
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: crash, intermittent-failure)
Crash Data
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=353347083&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/MhUNVjEzT2i529r4NIpFAg/runs/0/artifacts/public/logs/live_backing.log
[task 2021-10-01T13:01:25.424Z] 13:01:25 INFO - TEST-START | dom/media/webrtc/tests/mochitests/test_peerConnection_stats_relayProtocol.html
[task 2021-10-01T13:01:25.473Z] 13:01:25 INFO - GECKO(5240) | Timecard created 1633093272.383000
[task 2021-10-01T13:01:25.491Z] 13:01:25 INFO - GECKO(5240) | Timestamp | Delta | Event | File | Function
[task 2021-10-01T13:01:25.529Z] 13:01:25 INFO - GECKO(5240) | ======================================================================================================================
[task 2021-10-01T13:01:25.597Z] 13:01:25 INFO - GECKO(5240) | 0.057000 | 0.057000 | Constructor Completed | PeerConnectionImpl.cpp:332 | PeerConnectionImpl
[task 2021-10-01T13:01:25.657Z] 13:01:25 INFO - GECKO(5240) | 0.058000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:451 | Initialize
[task 2021-10-01T13:01:25.718Z] 13:01:25 INFO - GECKO(5240) | 0.637000 | 0.579000 | Set Remote Description | PeerConnectionImpl.cpp:1382 | SetRemoteDescription
[task 2021-10-01T13:01:25.780Z] 13:01:25 INFO - GECKO(5240) | 0.761000 | 0.124000 | Create Answer | PeerConnectionImpl.cpp:1244 | CreateAnswer
[task 2021-10-01T13:01:25.839Z] 13:01:25 INFO - GECKO(5240) | 0.764000 | 0.003000 | Set Local Description | PeerConnectionImpl.cpp:1282 | SetLocalDescription
[task 2021-10-01T13:01:25.900Z] 13:01:25 INFO - GECKO(5240) | 0.880000 | 0.116000 | Add Ice Candidate | PeerConnectionImpl.cpp:1530 | AddIceCandidate
[task 2021-10-01T13:01:25.954Z] 13:01:25 INFO - GECKO(5240) | 0.882000 | 0.002000 | Set Remote Description | PeerConnectionImpl.cpp:1382 | SetRemoteDescription
[task 2021-10-01T13:01:26.012Z] 13:01:26 INFO - GECKO(5240) | 0.913000 | 0.031000 | Add Ice Candidate | PeerConnectionImpl.cpp:1530 | AddIceCandidate
[task 2021-10-01T13:01:26.071Z] 13:01:26 INFO - GECKO(5240) | 0.914000 | 0.001000 | Create Answer | PeerConnectionImpl.cpp:1244 | CreateAnswer
[task 2021-10-01T13:01:26.123Z] 13:01:26 INFO - GECKO(5240) | 0.916000 | 0.002000 | Set Local Description | PeerConnectionImpl.cpp:1282 | SetLocalDescription
[task 2021-10-01T13:01:26.175Z] 13:01:26 INFO - GECKO(5240) | 0.976000 | 0.060000 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2538 | IceGatheringStateChange
[task 2021-10-01T13:01:26.222Z] 13:01:26 INFO - GECKO(5240) | 1.148000 | 0.172000 | Add Ice Candidate | PeerConnectionImpl.cpp:1530 | AddIceCandidate
[task 2021-10-01T13:01:26.268Z] 13:01:26 INFO - GECKO(5240) | 1.171000 | 0.023000 | Add Ice Candidate | PeerConnectionImpl.cpp:1530 | AddIceCandidate
[task 2021-10-01T13:01:26.316Z] 13:01:26 INFO - GECKO(5240) | 1.172000 | 0.001000 | Add Ice Candidate | PeerConnectionImpl.cpp:1530 | AddIceCandidate
[task 2021-10-01T13:01:26.359Z] 13:01:26 INFO - GECKO(5240) | 1.677000 | 0.505000 | Ice state: checking | PeerConnectionImpl.cpp:2482 | IceConnectionStateChange
[task 2021-10-01T13:01:26.397Z] 13:01:26 INFO - GECKO(5240) | 3.699000 | 2.022000 | Ice gathering state: complete | PeerConnectionImpl.cpp:2541 | IceGatheringStateChange
[task 2021-10-01T13:01:26.432Z] 13:01:26 INFO - GECKO(5240) | 10.018000 | 6.319000 | Ice state: connected | PeerConnectionImpl.cpp:2485 | IceConnectionStateChange
[task 2021-10-01T13:01:26.463Z] 13:01:26 INFO - GECKO(5240) | 13.077000 | 3.059000 | Destructor Invoked | PeerConnectionImpl.cpp:341 | ~PeerConnectionImpl
[task 2021-10-01T13:01:26.512Z] 13:01:26 INFO - GECKO(5240) | [Child 8668: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:367: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {639d3820-3749-40fa-b361-3af568941386}
[task 2021-10-01T13:01:26.519Z] 13:01:26 INFO - GECKO(5240) | Timecard created 1633093272.340000
[task 2021-10-01T13:01:26.551Z] 13:01:26 INFO - GECKO(5240) | Timestamp | Delta | Event | File | Function
[task 2021-10-01T13:01:26.577Z] 13:01:26 INFO - GECKO(5240) | ======================================================================================================================
[task 2021-10-01T13:01:26.598Z] 13:01:26 INFO - GECKO(5240) | 0.041000 | 0.041000 | Constructor Completed | PeerConnectionImpl.cpp:332 | PeerConnectionImpl
[task 2021-10-01T13:01:26.620Z] 13:01:26 INFO - GECKO(5240) | 0.042000 | 0.001000 | Initializing PC Ctx | PeerConnectionImpl.cpp:451 | Initialize
[task 2021-10-01T13:01:26.637Z] 13:01:26 INFO - GECKO(5240) | 0.622000 | 0.580000 | Create Offer | PeerConnectionImpl.cpp:1213 | CreateOffer
[task 2021-10-01T13:01:26.653Z] 13:01:26 INFO - GECKO(5240) | 0.661000 | 0.039000 | Set Local Description | PeerConnectionImpl.cpp:1282 | SetLocalDescription
[task 2021-10-01T13:01:26.667Z] 13:01:26 INFO - GECKO(5240) | 0.840000 | 0.179000 | Set Remote Description | PeerConnectionImpl.cpp:1382 | SetRemoteDescription
[task 2021-10-01T13:01:26.679Z] 13:01:26 INFO - GECKO(5240) | 0.875000 | 0.035000 | Ice gathering state: gathering | PeerConnectionImpl.cpp:2538 | IceGatheringStateChange
[task 2021-10-01T13:01:26.689Z] 13:01:26 INFO - GECKO(5240) | 0.878000 | 0.003000 | Create Offer | PeerConnectionImpl.cpp:1213 | CreateOffer
[task 2021-10-01T13:01:26.698Z] 13:01:26 INFO - GECKO(5240) | 0.879000 | 0.001000 | Set Local Description | PeerConnectionImpl.cpp:1282 | SetLocalDescription
[task 2021-10-01T13:01:26.706Z] 13:01:26 INFO - GECKO(5240) | 0.993000 | 0.114000 | Set Remote Description | PeerConnectionImpl.cpp:1382 | SetRemoteDescription
[task 2021-10-01T13:01:26.714Z] 13:01:26 INFO - GECKO(5240) | 1.229000 | 0.236000 | Ice gathering state: complete | PeerConnectionImpl.cpp:2541 | IceGatheringStateChange
[task 2021-10-01T13:01:26.721Z] 13:01:26 INFO - GECKO(5240) | 2.469000 | 1.240000 | Add Ice Candidate | PeerConnectionImpl.cpp:1530 | AddIceCandidate
[task 2021-10-01T13:01:26.727Z] 13:01:26 INFO - GECKO(5240) | 3.458000 | 0.989000 | Add Ice Candidate | PeerConnectionImpl.cpp:1530 | AddIceCandidate
[task 2021-10-01T13:01:26.733Z] 13:01:26 INFO - GECKO(5240) | 3.655000 | 0.197000 | Add Ice Candidate | PeerConnectionImpl.cpp:1530 | AddIceCandidate
[task 2021-10-01T13:01:26.740Z] 13:01:26 INFO - GECKO(5240) | 4.769000 | 1.114000 | Ice state: checking | PeerConnectionImpl.cpp:2482 | IceConnectionStateChange
[task 2021-10-01T13:01:26.746Z] 13:01:26 INFO - GECKO(5240) | 11.049000 | 6.280000 | Ice state: connected | PeerConnectionImpl.cpp:2485 | IceConnectionStateChange
[task 2021-10-01T13:01:26.752Z] 13:01:26 INFO - GECKO(5240) | 13.142000 | 2.093000 | Destructor Invoked | PeerConnectionImpl.cpp:341 | ~PeerConnectionImpl
[task 2021-10-01T13:01:26.760Z] 13:01:26 INFO - GECKO(5240) | [Child 8668: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:367: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {4448c3c8-fc03-49e8-8eb2-2cdd7a3a6797}
[task 2021-10-01T13:01:26.766Z] 13:01:26 INFO - GECKO(5240) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
[task 2021-10-01T13:01:26.772Z] 13:01:26 INFO - GECKO(5240) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
[task 2021-10-01T13:01:26.778Z] 13:01:26 INFO - GECKO(5240) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
[task 2021-10-01T13:01:26.784Z] 13:01:26 INFO - GECKO(5240) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
[task 2021-10-01T13:01:26.790Z] 13:01:26 INFO - GECKO(5240) | 1633093286601 addons.xpi ERROR System addon update list error Error: got node name: html, expected: updates
[task 2021-10-01T13:01:30.380Z] 13:01:30 INFO - GECKO(5240) | [Child 8668: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:331: PeerConnectionImpl: PeerConnectionImpl constructor for
[task 2021-10-01T13:01:30.388Z] 13:01:30 INFO - GECKO(5240) | [Parent 3972: Socket Thread]: D/mtransport NrIceCtx static call to find local stun addresses
[task 2021-10-01T13:01:30.398Z] 13:01:30 INFO - GECKO(5240) | [Child 8668: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:331: PeerConnectionImpl: PeerConnectionImpl constructor for
[task 2021-10-01T13:01:30.407Z] 13:01:30 INFO - GECKO(5240) | [Parent 3972: Socket Thread]: D/mtransport NrIceCtx static call to find local stun addresses
[task 2021-10-01T13:01:30.550Z] 13:01:30 INFO - GECKO(5240) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
[task 2021-10-01T13:01:30.558Z] 13:01:30 INFO - GECKO(5240) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
[task 2021-10-01T13:01:30.568Z] 13:01:30 INFO - GECKO(5240) | [Child 8668: Main Thread]: I/signaling [main|PeerConnectionMedia] PeerConnectionMedia.cpp:75: OnStunAddrsAvailable: receiving (3) stun addrs
[task 2021-10-01T13:01:30.578Z] 13:01:30 INFO - GECKO(5240) | [Child 8668: Main Thread]: I/signaling [main|PeerConnectionMedia] PeerConnectionMedia.cpp:75: OnStunAddrsAvailable: receiving (3) stun addrs
[task 2021-10-01T13:01:30.691Z] 13:01:30 INFO - GECKO(5240) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
[task 2021-10-01T13:01:30.699Z] 13:01:30 INFO - GECKO(5240) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
[task 2021-10-01T13:01:30.991Z] 13:01:30 INFO - GECKO(5240) | [Child 8668: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: 24f0842beb0
[task 2021-10-01T13:01:31.024Z] 13:01:31 INFO - GECKO(5240) | [Child 8668: Main Thread]: I/jsep [{eda3c330-f20f-4e8f-a827-51eba1bedb81} 1633093290359000 (id=4294967362 url=http://mochi.test:8888/tests/dom/media/webrtc/tests/]: stable -> have-local-offer
[task 2021-10-01T13:01:31.033Z] 13:01:31 INFO - GECKO(5240) | (generic/EMERG) Exit UDP socket connected
[task 2021-10-01T13:01:31.099Z] 13:01:31 INFO - GECKO(5240) | (ice/INFO) ICE(PC:{eda3c330-
[task 2021-10-01T13:01:31.105Z] 13:01:31 INFO - GECKO(5240) | f20f-4e8f-a827-51eba1bedb81} 1633093290359000 (id=4294967362 url=http
[task 2021-10-01T13:01:31.158Z] 13:01:31 INFO - GECKO(5240) | ://mochi.test:8888/tests/dom/media/webrtc/tests/): Skipping STUN server b
[task 2021-10-01T13:01:31.164Z] 13:01:31 INFO - GECKO(5240) | ecause of address type mis-match
[task 2021-10-01T13:01:31.235Z] 13:01:31 INFO - GECKO(5240) | (ice/INFO
[task 2021-10-01T13:01:31.240Z] 13:01:31 INFO - GECKO(5240) | ) ICE(PC:{eda3c330-f20f-4e8f-a8
[task 2021-10-01T13:01:31.289Z] 13:01:31 INFO - GECKO(5240) | 27-51eba1bedb81} 1633093290359000
[task 2021-10-01T13:01:31.389Z] 13:01:31 INFO - GECKO(5240) | (id=4294967362 url=http://mochi.test:8888/tests/dom/media/webrtc
[task 2021-10-01T13:01:31.457Z] 13:01:31 INFO - GECKO(5240) | /tests/
[task 2021-10-01T13:01:31.481Z] 13:01:31 INFO - GECKO(5240) | ): Skipping TURN server because of address type mis-match
<...>
[task 2021-10-01T13:02:26.021Z] 13:02:26 INFO - GECKO(5240) | [Child 8668: WebRTCPD #1]: E/signaling [WebRTCPD #1|WebrtcAudioSessionConduit] AudioConduit.cpp:633: SendAudioFrame Engine not transmitting
[task 2021-10-01T13:02:26.064Z] 13:02:26 INFO - GECKO(5240) | [Child 8668: WebRTCPD #2]: E/signaling [WebRTCPD #2|WebrtcAudioSessionConduit] AudioConduit.cpp:633: SendAudioFrame Engine not transmitting
[task 2021-10-01T13:02:26.092Z] 13:02:26 INFO - GECKO(5240) | (
[task 2021-10-01T13:02:26.115Z] 13:02:26 INFO - GECKO(5240) | generic/INFO) Te[Child 8668: WebRTCPD #1]: E/signaling [WebRTCPD #1|WebrtcAudioSessionConduit] AudioConduit.cpp:633: SendAudioFrame Engine not transmitting
[task 2021-10-01T13:02:26.132Z] 13:02:26 INFO - GECKO(5240) | [Child 8668: WebRTCPD #2]: E/signaling [WebRTCPD #2|WebrtcAudioSessionConduit] AudioConduit.cpp:633: SendAudioFrame Engine not transmitting
[task 2021-10-01T13:02:26.166Z] 13:02:26 INFO - GECKO(5240) | s
[task 2021-10-01T13:02:26.200Z] 13:02:26 INFO - GECKO(5240) | tNrSocket IP4:10.
[task 2021-10-01T13:02:26.220Z] 13:02:26 INFO - GECKO(5240) | [Child 8668: WebRTCPD #1]: E/signaling [WebRTCPD #1|WebrtcAudioSessionConduit] AudioConduit.cpp:633: SendAudioFrame Engine not transmitting
[task 2021-10-01T13:02:26.240Z] 13:02:26 INFO - GECKO(5240) | 7.204.55:50580/UDP[Child 8668: WebRTCPD #2]: E/signaling [WebRTCPD #2|WebrtcAudioSessionConduit] AudioConduit.cpp:633: SendAudioFrame Engine not transmitting
[task 2021-10-01T13:02:26.249Z] 13:02:26 INFO - GECKO(5240) | denying ingress from IP4:10.7.204.55:50593/UDP: Not behind the same NAT
[task 2021-10-01T13:02:26.260Z] 13:02:26 INFO - GECKO(5240) | (turn/INFO) TURN(relay(IP4:10.7.204.55:50580/UDP|IP4:0.0.0.0:3478/UDP)): deallocating
[task 2021-10-01T13:02:26.271Z] 13:02:26 INFO - GECKO(5240) | (turn/INFO) TURN(relay(IP4:10.7.204.55:0/TCP|IP4:0.0.0.0:3478/TCP)): deallocating
[task 2021-10-01T13:02:26.284Z] 13:02:26 INFO - GECKO(5240) | (generic/INFO) TestNrSocket IP4:10.7.204.55:0/TCP destroying port mapping IP4:10.7.204.55:0/TCP -> IP4:0.0.0.0:3478/TCP
[task 2021-10-01T13:02:26.290Z] 13:02:26 INFO - GECKO(5240) | (generic/ERR) Write error for IP4:0.0.0.0:3478/TCP - 3
[task 2021-10-01T13:02:26.301Z] 13:02:26 INFO - GECKO(5240) | (turn/WARNING) TURN(relay(IP4:10.7.204.55:0/TCP|IP4:0.0.0.0:3478/TCP)): Failed sending request
[task 2021-10-01T13:02:26.317Z] 13:02:26 INFO - GECKO(5240) | (turn/INFO) TURN(relay(IP4:10.7.204.55:0/TLS|IP4:0.0.0.0:5349/TLS))MEMORY STAT | vsize 6671MB | vsizeMaxContiguous 80287129MB | residentFast 99MB | heapAllocated 20MB
[task 2021-10-01T13:02:26.321Z] 13:02:26 INFO - GECKO(5240) | : deallocating
[task 2021-10-01T13:02:26.329Z] 13:02:26 INFO - GECKO(5240) | (generic/INFO) TestNrSocket IP4:10.7.204.55:0/TLS destroying port mapping IP4:10.7.204.55:0/TLS -> IP4:0.0.0.0:5349/TLS
[task 2021-10-01T13:02:26.334Z] 13:02:26 INFO - GECKO(5240) | (generic/ERR) Write error for IP4:0.0.0.0:5349/TLS - 3
[task 2021-10-01T13:02:26.354Z] 13:02:26 INFO - GECKO(5240) | (turn/WARNI
[task 2021-10-01T13:02:26.358Z] 13:02:26 INFO - GECKO(5240) | N
[task 2021-10-01T13:02:26.364Z] 13:02:26 INFO - GECKO(5240) | G) TURN(relay(IP4:10.7.204.55:0/TLS|IP4:0.0.0.0:5349/TLS)): Failed sending request
[task 2021-10-01T13:02:26.386Z] 13:02:26 INFO - GECKO(5240) | (turn/INFO) TURN(relay(IP4:10.7.204.55:50581/UDP|IP4:0.0.0.0:3478/UDP)
[task 2021-10-01T13:02:26.394Z] 13:02:26 INFO - TEST-OK | dom/media/webrtc/tests/mochitests/test_peerConnection_stats_relayProtocol.html | took 60570ms
[task 2021-10-01T13:02:26.399Z] 13:02:26 INFO - GECKO(5240) | ): deallocating
[task 2021-10-01T13:02:26.410Z] 13:02:26 INFO - GECKO(5240) | (generic/INFO) TestNrSocket IP4:10.7.204.55:50581/UDP destroying port mapping IP4:10.7.204.55:50587/
[task 2021-10-01T13:02:26.416Z] 13:02:26 INFO - ICE Server websocket closed
[task 2021-10-01T13:02:26.420Z] 13:02:26 INFO - GECKO(5240) | UDP -> IP4:10.7.204.55:3478/UDP
[task 2021-10-01T13:02:26.427Z] 13:02:26 INFO - GECKO(5240) | (generic/INFO) TestNrSocket IP4:10.7.204.55:50581/UDP creating port mapping IP4:10.7.204.55:50596/UDP -> IP4:10.7.204.55:3478/UDP
[task 2021-10-01T13:02:26.433Z] 13:02:26 INFO - GECKO(5240) | (turn/INFO) TURN(relay(IP4:10.7.204.55:0/TCP|IP4:0.0.0.0:3478/TCP)): deallocating
[task 2021-10-01T13:02:26.439Z] 13:02:26 INFO - GECKO(5240) | (generic/INFO) TestNrSocket IP4:10.7.204.55:0/TCP destroying port mapping IP4:10.7.204.55:0/TCP -> IP4:0.0.0.0:3478/TCP
[task 2021-10-01T13:02:26.445Z] 13:02:26 INFO - GECKO(5240) | (generic/ERR) Write error for IP4:0.0.0.0:3478/TCP - 3
[task 2021-10-01T13:02:26.451Z] 13:02:26 INFO - GECKO(5240) | (turn/WARNING) TURN(relay(IP4:10.7.204.55:0/TCP|IP4:0.0.0.0:3478/TCP)): Failed sending request
[task 2021-10-01T13:02:26.457Z] 13:02:26 INFO - GECKO(5240) | (turn/INFO) TURN(relay(IP4:10.7.204.55:0/TLS|IP4:0.0.0.0:5349/TLS)): deallocating
[task 2021-10-01T13:02:26.463Z] 13:02:26 INFO - GECKO(5240) | (generic/INFO) TestNrSocket IP4:10.7.204.55:0/TLS destroying port mapping IP4:10.7.204.55:0/TLS -> IP4:0.0.0.0:5349/TLS
[task 2021-10-01T13:02:26.468Z] 13:02:26 INFO - GECKO(5240) | (generic/ERR) Write error for IP4:0.0.0.0:5349/TLS - 3
[task 2021-10-01T13:02:26.474Z] 13:02:26 INFO - GECKO(5240) | (turn/WARNING) TURN(relay(IP4:10.7.204.55:0/TLS|IP4:0.0.0.0:5349/TLS)): Failed sending request
[task 2021-10-01T13:02:26.480Z] 13:02:26 INFO - GECKO(5240) | (generic/ERR) NrTcpSocket::OnClose 0000024F08157800 reason=2152398868 name=NS_ERROR_NET_RESET
[task 2021-10-01T13:02:26.731Z] 13:02:26 ERROR - GECKO(5240) | A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
[task 2021-10-01T13:02:26.827Z] 13:02:26 INFO - GECKO(5240) | 1633093346824 Marionette TRACE Received observer notification quit-application
[task 2021-10-01T13:02:26.830Z] 13:02:26 INFO - GECKO(5240) | 1633093346824 Marionette INFO Stopped listening on port 2828
[task 2021-10-01T13:02:26.833Z] 13:02:26 INFO - GECKO(5240) | 1633093346824 Marionette DEBUG Marionette stopped listening
[task 2021-10-01T13:02:26.881Z] 13:02:26 INFO - GECKO(5240) | [Parent 3972, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2021-10-01T13:02:27.247Z] 13:02:27 INFO - TEST-INFO | Main app process: exit 0
[task 2021-10-01T13:02:27.249Z] 13:02:27 INFO - runtests.py | Application ran for: 0:09:31.570634
[task 2021-10-01T13:02:27.252Z] 13:02:27 INFO - zombiecheck | Reading PID log: C:\Users\testdroid.YOGA-035.000\AppData\Local\Temp\tmpt1ncjblypidlog
[task 2021-10-01T13:02:27.258Z] 13:02:27 INFO - ==> process 3972 launched child process 1984 ("C:\tasks\task_1633038889\build\application\firefox\firefox.exe" -contentproc --channel="3972.0.416899537\1089478000" -parentBuildID 20211001093323 -prefsHandle 2192 -prefMapHandle 2120 -prefsLen 1 -prefMapSize 251775 -appdir "C:\tasks\task_1633038889\build\application\firefox\browser" - 3972 "\\.\pipe\gecko-crash-server-pipe.3972" 2260 2824cebf538 gpu)
[task 2021-10-01T13:02:27.263Z] 13:02:27 INFO - ==> process 3972 launched child process 14168 ("C:\tasks\task_1633038889\build\application\firefox\firefox.exe" -contentproc --channel="3972.1.1938352392\2013575814" -childID 1 -isForBrowser -prefsHandle 2876 -prefMapHandle 2872 -prefsLen 1790 -prefMapSize 251775 -jsInit 1460 279912 -parentBuildID 20211001093323 -appdir "C:\tasks\task_1633038889\build\application\firefox\browser" - 3972 "\\.\pipe\gecko-crash-server-pipe.3972" 2888 2824136d138 tab)
[task 2021-10-01T13:02:27.268Z] 13:02:27 INFO - ==> process 3972 launched child process 8668 ("C:\tasks\task_1633038889\build\application\firefox\firefox.exe" -contentproc --channel="3972.3.1309506203\596629315" -childID 2 -isForBrowser -prefsHandle 3232 -prefMapHandle 3228 -prefsLen 1933 -prefMapSize 251775 -jsInit 1460 279912 -parentBuildID 20211001093323 -appdir "C:\tasks\task_1633038889\build\application\firefox\browser" - 3972 "\\.\pipe\gecko-crash-server-pipe.3972" 2852 28251a3b738 tab)
[task 2021-10-01T13:02:27.275Z] 13:02:27 INFO - ==> process 3972 launched child process 1340 ("C:\tasks\task_1633038889\build\application\firefox\firefox.exe" -contentproc --channel="3972.5.807467713\1175810157" -childID 3 -isForBrowser -prefsHandle 3416 -prefMapHandle 3420 -prefsLen 1973 -prefMapSize 251775 -jsInit 1460 279912 -parentBuildID 20211001093323 -appdir "C:\tasks\task_1633038889\build\application\firefox\browser" - 3972 "\\.\pipe\gecko-crash-server-pipe.3972" 3444 28251a3fb38 tab)
[task 2021-10-01T13:02:27.280Z] 13:02:27 INFO - ==> process 3972 launched child process 3988 ("C:\tasks\task_1633038889\build\application\firefox\firefox.exe" -contentproc --channel="3972.7.1419716298\1806216518" -childID 4 -isForBrowser -prefsHandle 3416 -prefMapHandle 3916 -prefsLen 9993 -prefMapSize 251775 -jsInit 1460 279912 -parentBuildID 20211001093323 -appdir "C:\tasks\task_1633038889\build\application\firefox\browser" - 3972 "\\.\pipe\gecko-crash-server-pipe.3972" 2816 28253e3a538 tab)
[task 2021-10-01T13:02:27.286Z] 13:02:27 INFO - ==> process 3972 launched child process 3452 ("C:\tasks\task_1633038889\build\application\firefox\firefox.exe" -contentproc --channel="3972.9.666068425\536194761" -childID 5 -isForBrowser -prefsHandle 4332 -prefMapHandle 4336 -prefsLen 10324 -prefMapSize 251775 -jsInit 1460 279912 -parentBuildID 20211001093323 -appdir "C:\tasks\task_1633038889\build\application\firefox\browser" - 3972 "\\.\pipe\gecko-crash-server-pipe.3972" 4360 2824debb138 tab)
[task 2021-10-01T13:02:27.291Z] 13:02:27 INFO - ==> process 3972 launched child process 13012 ("C:\tasks\task_1633038889\build\application\firefox\firefox.exe" -contentproc --channel="3972.11.1943922914\1572843813" -parentBuildID 20211001093323 -prefsHandle 2712 -prefMapHandle 4656 -prefsLen 12199 -prefMapSize 251775 -appdir "C:\tasks\task_1633038889\build\application\firefox\browser" - 3972 "\\.\pipe\gecko-crash-server-pipe.3972" 4744 282545ad538 rdd)
[task 2021-10-01T13:02:27.293Z] 13:02:27 INFO - zombiecheck | Checking for orphan process with PID: 1984
[task 2021-10-01T13:02:27.295Z] 13:02:27 INFO - zombiecheck | Checking for orphan process with PID: 3452
[task 2021-10-01T13:02:27.297Z] 13:02:27 INFO - zombiecheck | Checking for orphan process with PID: 3988
[task 2021-10-01T13:02:27.299Z] 13:02:27 INFO - zombiecheck | Checking for orphan process with PID: 13012
[task 2021-10-01T13:02:27.301Z] 13:02:27 INFO - zombiecheck | Checking for orphan process with PID: 1340
[task 2021-10-01T13:02:27.303Z] 13:02:27 INFO - zombiecheck | Checking for orphan process with PID: 14168
[task 2021-10-01T13:02:27.305Z] 13:02:27 INFO - zombiecheck | Checking for orphan process with PID: 8668
[task 2021-10-01T13:02:27.308Z] 13:02:27 INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/f0SFmwsZQauRisVz32MhQQ/artifacts/public/build/target.crashreporter-symbols.zip
[task 2021-10-01T13:02:30.121Z] 13:02:30 INFO - mozcrash Copy/paste: C:/tasks/task_1633038889/fetches\minidump_stackwalk\minidump_stackwalk.exe C:\Users\testdroid.YOGA-035.000\AppData\Local\Temp\tmpsp1f_4qp.mozrunner\minidumps\4f6c91f7-c605-4979-adfb-bd0fe299b80b.dmp C:\Users\testdroid.YOGA-035.000\AppData\Local\Temp\tmppj_6nglk
[task 2021-10-01T13:02:37.882Z] 13:02:37 INFO - mozcrash Saved minidump as C:\tasks\task_1633038889\build\blobber_upload_dir\4f6c91f7-c605-4979-adfb-bd0fe299b80b.dmp
[task 2021-10-01T13:02:37.885Z] 13:02:37 INFO - mozcrash Saved app info as C:\tasks\task_1633038889\build\blobber_upload_dir\4f6c91f7-c605-4979-adfb-bd0fe299b80b.extra
[task 2021-10-01T13:02:39.339Z] 13:02:39 INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ mozilla::NrTcpSocket::DoCallbacks()]
[task 2021-10-01T13:02:39.339Z] 13:02:39 INFO - Crash dump filename: C:\Users\testdroid.YOGA-035.000\AppData\Local\Temp\tmpsp1f_4qp.mozrunner\minidumps\4f6c91f7-c605-4979-adfb-bd0fe299b80b.dmp
[task 2021-10-01T13:02:39.339Z] 13:02:39 INFO - Operating system: Windows NT
[task 2021-10-01T13:02:39.339Z] 13:02:39 INFO - 10.0.17134
[task 2021-10-01T13:02:39.340Z] 13:02:39 INFO - CPU: arm64
[task 2021-10-01T13:02:39.340Z] 13:02:39 INFO - 8 CPUs
[task 2021-10-01T13:02:39.340Z] 13:02:39 INFO -
[task 2021-10-01T13:02:39.340Z] 13:02:39 INFO - GPU: UNKNOWN
[task 2021-10-01T13:02:39.340Z] 13:02:39 INFO -
[task 2021-10-01T13:02:39.341Z] 13:02:39 INFO - Crash reason: EXCEPTION_ACCESS_VIOLATION_READ
[task 2021-10-01T13:02:39.341Z] 13:02:39 INFO - Crash address: 0xe5e5e5e5
[task 2021-10-01T13:02:39.341Z] 13:02:39 INFO - Assertion: Unknown assertion type 0x00000000
[task 2021-10-01T13:02:39.341Z] 13:02:39 INFO - Process uptime: 569 seconds
[task 2021-10-01T13:02:39.341Z] 13:02:39 INFO -
[task 2021-10-01T13:02:39.341Z] 13:02:39 INFO - Thread 3 tid 844 (crashed) - Socket Thread 0 xul.dll!mozilla::NrSocketBase::fire_callback(int) [nr_socket_prsock.cpp:9790289bfed7ac69a3a990052132db1ead5c2364 : 332 + 0x0]
[task 2021-10-01T13:02:39.342Z] 13:02:39 INFO - x0 = 0xe5e5e5e5e5e5e5e5 x1 = 0x0000000000000000
[task 2021-10-01T13:02:39.342Z] 13:02:39 INFO - x2 = 0x00000059d9d2ec18 x3 = 0x00000059d9d2ee78
[task 2021-10-01T13:02:39.342Z] 13:02:39 INFO - x4 = 0x0000000000000000 x5 = 0x00000059d9d2ebd8
[task 2021-10-01T13:02:39.343Z] 13:02:39 INFO - x6 = 0x0000000000000000 x7 = 0x0000000000000000
[task 2021-10-01T13:02:39.343Z] 13:02:39 INFO - x8 = 0x0000000000000000 x9 = 0x0000000000000006
[task 2021-10-01T13:02:39.343Z] 13:02:39 INFO - x10 = 0x0000000000000007 x11 = 0x0000000000000198
[task 2021-10-01T13:02:39.343Z] 13:02:39 INFO - x12 = 0x0000000000000000 x13 = 0x0000000000000000
[task 2021-10-01T13:02:39.344Z] 13:02:39 INFO - x14 = 0x00000000ffffffde x15 = 0x0000000000000114
[task 2021-10-01T13:02:39.344Z] 13:02:39 INFO - x16 = 0x000017fa03b520e2 x17 = 0x00000059d9d22000
[task 2021-10-01T13:02:39.344Z] 13:02:39 INFO - x18 = 0x0000000000000000 x19 = 0x0000024f08157800
[task 2021-10-01T13:02:39.344Z] 13:02:39 INFO - x20 = 0x0000000000000000 x21 = 0x0000024f7d110328
[task 2021-10-01T13:02:39.344Z] 13:02:39 INFO - x22 = 0x0000024f05029940 x23 = 0x0000024f7d17f4e8
[task 2021-10-01T13:02:39.345Z] 13:02:39 INFO - x24 = 0x00000059d9d2f0e8 x25 = 0x0000000000000001
[task 2021-10-01T13:02:39.346Z] 13:02:39 INFO - x26 = 0x00000059d9d2eff0 x27 = 0x00007ffda386e000
[task 2021-10-01T13:02:39.346Z] 13:02:39 INFO - x28 = 0x00007ffdc7510270 fp = 0x00000059d9d2eeb0
[task 2021-10-01T13:02:39.346Z] 13:02:39 INFO - lr = 0x00007ffda0ad11c0 sp = 0x00000059d9d2eea0
[task 2021-10-01T13:02:39.346Z] 13:02:39 INFO - pc = 0x00007ffda0ad124c
[task 2021-10-01T13:02:39.347Z] 13:02:39 INFO - Found by: given as instruction pointer in context
[task 2021-10-01T13:02:39.347Z] 13:02:39 INFO - 1 xul.dll!mozilla::NrTcpSocket::DoCallbacks() [nr_socket_tcp.cpp:9790289bfed7ac69a3a990052132db1ead5c2364 : 281 + 0x8]
[task 2021-10-01T13:02:39.347Z] 13:02:39 INFO - fp = 0x00000059d9d2eed0 lr = 0x00007ffda0ad11c0
[task 2021-10-01T13:02:39.347Z] 13:02:39 INFO - sp = 0x00000059d9d2eec0 pc = 0x00007ffda0ad11c0
[task 2021-10-01T13:02:39.348Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.348Z] 13:02:39 INFO - 2 xul.dll!mozilla::NrTcpSocket::DoCallbacks() [nr_socket_tcp.cpp:9790289bfed7ac69a3a990052132db1ead5c2364 : 281 + 0x8]
[task 2021-10-01T13:02:39.348Z] 13:02:39 INFO - fp = 0x00000059d9d2ef10 lr = 0x00007ffda0ad1144
[task 2021-10-01T13:02:39.348Z] 13:02:39 INFO - sp = 0x00000059d9d2eee0 pc = 0x00007ffda0ad11c0
[task 2021-10-01T13:02:39.348Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.348Z] 13:02:39 INFO - 3 xul.dll!mozilla::NrTcpSocket::OnClose(nsresult) [nr_socket_tcp.cpp:9790289bfed7ac69a3a990052132db1ead5c2364 : 252 + 0x4]
[task 2021-10-01T13:02:39.348Z] 13:02:39 INFO - fp = 0x00000059d9d2ef20 lr = 0x00007ffda252a1a0
[task 2021-10-01T13:02:39.349Z] 13:02:39 INFO - sp = 0x00000059d9d2ef20 pc = 0x00007ffda0ad1144
[task 2021-10-01T13:02:39.349Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.349Z] 13:02:39 INFO - 4 xul.dll!mozilla::detail::RunnableMethodImpl<RefPtr<mozilla::net::WebrtcTCPSocketCallback>,void (mozilla::net::WebrtcTCPSocketCallback::*)(nsresult),1,mozilla::RunnableKind::Standard,nsresult>::Run() [nsThreadUtils.h:9790289bfed7ac69a3a990052132db1ead5c2364 : 1200 + 0x8]
[task 2021-10-01T13:02:39.349Z] 13:02:39 INFO - fp = 0x00000059d9d2f270 lr = 0x00007ffda001a6f0
[task 2021-10-01T13:02:39.350Z] 13:02:39 INFO - sp = 0x00000059d9d2ef30 pc = 0x00007ffda252a1a0
[task 2021-10-01T13:02:39.350Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.350Z] 13:02:39 INFO - 5 xul.dll!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:9790289bfed7ac69a3a990052132db1ead5c2364 : 1142 + 0xc]
[task 2021-10-01T13:02:39.350Z] 13:02:39 INFO - fp = 0x00000059d9d2f6c0 lr = 0x00007ffda02030b0
[task 2021-10-01T13:02:39.350Z] 13:02:39 INFO - sp = 0x00000059d9d2f280 pc = 0x00007ffda001a6f0
[task 2021-10-01T13:02:39.350Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.351Z] 13:02:39 INFO - 6 xul.dll!mozilla::net::nsSocketTransportService::Run() [nsSocketTransportService2.cpp:9790289bfed7ac69a3a990052132db1ead5c2364 : 1190 + 0x28]
[task 2021-10-01T13:02:39.351Z] 13:02:39 INFO - fp = 0x00000059d9d2fa20 lr = 0x00007ffda001a6f0
[task 2021-10-01T13:02:39.351Z] 13:02:39 INFO - sp = 0x00000059d9d2f6d0 pc = 0x00007ffda02030b0
[task 2021-10-01T13:02:39.351Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.351Z] 13:02:39 INFO - 7 xul.dll!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:9790289bfed7ac69a3a990052132db1ead5c2364 : 1142 + 0xc]
[task 2021-10-01T13:02:39.352Z] 13:02:39 INFO - fp = 0x00000059d9d2fac0 lr = 0x00007ffd9e1da9a8
[task 2021-10-01T13:02:39.352Z] 13:02:39 INFO - sp = 0x00000059d9d2fa30 pc = 0x00007ffda001a6f0
[task 2021-10-01T13:02:39.352Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.352Z] 13:02:39 INFO - 8 xul.dll!mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) [MessagePump.cpp:9790289bfed7ac69a3a990052132db1ead5c2364 : 300 + 0x20]
[task 2021-10-01T13:02:39.352Z] 13:02:39 INFO - fp = 0x00000059d9d2fae8 lr = 0x00007ffd9e1d9804
[task 2021-10-01T13:02:39.352Z] 13:02:39 INFO - sp = 0x00000059d9d2fad0 pc = 0x00007ffd9e1da9a8
[task 2021-10-01T13:02:39.353Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.353Z] 13:02:39 INFO - 9 xul.dll!MessageLoop::RunHandler() [message_loop.cc:9790289bfed7ac69a3a990052132db1ead5c2364 : 324 + 0xc]
[task 2021-10-01T13:02:39.353Z] 13:02:39 INFO - fp = 0x00000059d9d2fb40 lr = 0x00007ffd9e1d9790
[task 2021-10-01T13:02:39.353Z] 13:02:39 INFO - sp = 0x00000059d9d2faf8 pc = 0x00007ffd9e1d9804
[task 2021-10-01T13:02:39.353Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.354Z] 13:02:39 INFO - 10 xul.dll!MessageLoop::Run() [message_loop.cc:9790289bfed7ac69a3a990052132db1ead5c2364 : 306 + 0x0]
[task 2021-10-01T13:02:39.354Z] 13:02:39 INFO - fp = 0x00000059d9d2fd00 lr = 0x00007ffd9e1d9224
[task 2021-10-01T13:02:39.354Z] 13:02:39 INFO - sp = 0x00000059d9d2fb50 pc = 0x00007ffd9e1d9790
[task 2021-10-01T13:02:39.354Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.354Z] 13:02:39 INFO - 11 xul.dll!static nsThread::ThreadFunc(void*) [nsThread.cpp:9790289bfed7ac69a3a990052132db1ead5c2364 : 390 + 0x4]
[task 2021-10-01T13:02:39.355Z] 13:02:39 INFO - fp = 0x00000059d9d2fd70 lr = 0x00007ffdc7408b50
[task 2021-10-01T13:02:39.355Z] 13:02:39 INFO - sp = 0x00000059d9d2fd10 pc = 0x00007ffd9e1d9224
[task 2021-10-01T13:02:39.355Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.355Z] 13:02:39 INFO - 12 nss3.dll!_PR_NativeRunThread(void*) [pruthr.c:9790289bfed7ac69a3a990052132db1ead5c2364 : 399 + 0x4]
[task 2021-10-01T13:02:39.355Z] 13:02:39 INFO - fp = 0x00000059d9d2fd80 lr = 0x00007ffdc743e750
[task 2021-10-01T13:02:39.355Z] 13:02:39 INFO - sp = 0x00000059d9d2fd80 pc = 0x00007ffdc7408b50
[task 2021-10-01T13:02:39.356Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.356Z] 13:02:39 INFO - 13 nss3.dll!pr_root(void*) [w95thred.c:9790289bfed7ac69a3a990052132db1ead5c2364 : 139 + 0x4]
[task 2021-10-01T13:02:39.356Z] 13:02:39 INFO - fp = 0x00000059d9d2fd90 lr = 0x00007ffdcf882ee0
[task 2021-10-01T13:02:39.356Z] 13:02:39 INFO - sp = 0x00000059d9d2fd90 pc = 0x00007ffdc743e750
[task 2021-10-01T13:02:39.356Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.357Z] 13:02:39 INFO - 14 ucrtbase.dll + 0x32edc
[task 2021-10-01T13:02:39.357Z] 13:02:39 INFO - fp = 0x00000059d9d2fdc0 lr = 0x00007ffdcfbc5ba4
[task 2021-10-01T13:02:39.357Z] 13:02:39 INFO - sp = 0x00000059d9d2fda0 pc = 0x00007ffdcf882ee0
[task 2021-10-01T13:02:39.357Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.358Z] 13:02:39 INFO - 15 kernel32.dll + 0x15ba0
[task 2021-10-01T13:02:39.358Z] 13:02:39 INFO - fp = 0x00000059d9d2fe48 lr = 0x00007ffdc72e77fc
[task 2021-10-01T13:02:39.358Z] 13:02:39 INFO - sp = 0x00000059d9d2fdd0 pc = 0x00007ffdcfbc5ba4
[task 2021-10-01T13:02:39.358Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.358Z] 13:02:39 INFO - 16 mozglue.dll!patched_BaseThreadInitThunk(int, void*, void*) [WindowsDllBlocklist.cpp:9790289bfed7ac69a3a990052132db1ead5c2364 : 592 + 0x14]
[task 2021-10-01T13:02:39.358Z] 13:02:39 INFO - fp = 0x00000059d9d2fe60 lr = 0x00007ffdd2842544
[task 2021-10-01T13:02:39.359Z] 13:02:39 INFO - sp = 0x00000059d9d2fe58 pc = 0x00007ffdc72e77fc
[task 2021-10-01T13:02:39.359Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.359Z] 13:02:39 INFO - 17 ntdll.dll + 0x92540
[task 2021-10-01T13:02:39.359Z] 13:02:39 INFO - fp = 0x0000000000000000 lr = 0x0000000000000000
[task 2021-10-01T13:02:39.359Z] 13:02:39 INFO - sp = 0x00000059d9d2fe70 pc = 0x00007ffdd2842544
[task 2021-10-01T13:02:39.359Z] 13:02:39 INFO - Found by: previous frame's frame pointer
[task 2021-10-01T13:02:39.360Z] 13:02:39 INFO -
[task 2021-10-01T13:02:39.360Z] 13:02:39 INFO - Thread 0 tid 7184 - GeckoMain
|
||
Updated•3 years ago
|
Component: WebRTC: Audio/Video → WebRTC: Networking
|
|
||
Updated•3 years ago
|
Group: core-security → media-core-security
|
||
Updated•3 years ago
|
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → DUPLICATE
|
||
Updated•1 year ago
|
Group: media-core-security
You need to log in
before you can comment on or make changes to this bug.
Description
•