Closed Bug 1672010 Opened 5 years ago Closed 3 years ago

Intermittent services/sync/tests/unit/test_addons_store.js | application crashed [@ JSObject2WrappedJSMap::UpdateWeakPointersAfterGC] | After xpcshell return code: -11

Categories

(Core :: XPConnect, defect, P3)

Product:
Core
Component:
XPConnect
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE
Tracking Flags:
Tracking Status
firefox-esr78 --- wontfix
firefox83 --- wontfix
firefox84 --- wontfix
firefox85 --- ?
firefox86 --- ?

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: crash, intermittent-failure)

Crash Data

Filed by: nbeleuzu [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=319017760&repo=mozilla-release
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/G4IJiVToQ9i3DIYh_w5pbg/runs/0/artifacts/public/logs/live_backing.log

[task 2020-10-19T15:49:48.004Z] 15:49:48  WARNING -  PROCESS-CRASH | services/sync/tests/unit/test_addons_store.js | application crashed [@ JSObject2WrappedJSMap::UpdateWeakPointersAfterGC()]
[task 2020-10-19T15:49:48.005Z] 15:49:48     INFO -  Crash dump filename: /tmp/xpc-other-QqPdax/45ad490f-6e9a-7703-dc5a-3240082e3b3e.dmp
[task 2020-10-19T15:49:48.006Z] 15:49:48     INFO -  Operating system: Linux
[task 2020-10-19T15:49:48.006Z] 15:49:48     INFO -                    0.0.0 Linux 4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018 x86_64
[task 2020-10-19T15:49:48.006Z] 15:49:48     INFO -  CPU: amd64
[task 2020-10-19T15:49:48.007Z] 15:49:48     INFO -       family 6 model 85 stepping 7
[task 2020-10-19T15:49:48.007Z] 15:49:48     INFO -       2 CPUs
[task 2020-10-19T15:49:48.012Z] 15:49:48     INFO -  GPU: UNKNOWN
[task 2020-10-19T15:49:48.013Z] 15:49:48     INFO -  Crash reason:  SIGSEGV /0x00000080
[task 2020-10-19T15:49:48.013Z] 15:49:48     INFO -  Crash address: 0x0
[task 2020-10-19T15:49:48.014Z] 15:49:48     INFO -  Process uptime: not available
[task 2020-10-19T15:49:48.014Z] 15:49:48     INFO -  Thread 0 (crashed)
[task 2020-10-19T15:49:48.015Z] 15:49:48     INFO -   0  libxul.so!JSObject2WrappedJSMap::UpdateWeakPointersAfterGC() [XPCMaps.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 52 + 0x0]
[task 2020-10-19T15:49:48.015Z] 15:49:48     INFO -      rax = 0xe5e5e5e5e5e5e5e4   rdx = 0x000000000000039e
[task 2020-10-19T15:49:48.016Z] 15:49:48     INFO -      rcx = 0x00007f7033b3081c   rbx = 0xe5e5e5e5e5e5e5e5
[task 2020-10-19T15:49:48.016Z] 15:49:48     INFO -      rsi = 0x0000000000000000   rdi = 0x0000000000000000
[task 2020-10-19T15:49:48.016Z] 15:49:48     INFO -      rbp = 0x00007f7033b308e0   rsp = 0x00007ffc1476d4d0
[task 2020-10-19T15:49:48.017Z] 15:49:48     INFO -       r8 = 0x00007f703dcb32a0    r9 = 0x0000000000000003
[task 2020-10-19T15:49:48.017Z] 15:49:48     INFO -      r10 = 0x001638aca6511007   r11 = 0x00000000ffffffff
[task 2020-10-19T15:49:48.018Z] 15:49:48     INFO -      r12 = 0x00007f7033b30a80   r13 = 0x00002157e4973c00
[task 2020-10-19T15:49:48.018Z] 15:49:48     INFO -      r14 = 0x00007f7033b30818   r15 = 0x00007f7033b30a80
[task 2020-10-19T15:49:48.018Z] 15:49:48     INFO -      rip = 0x00007f70513c5192
[task 2020-10-19T15:49:48.019Z] 15:49:48     INFO -      Found by: given as instruction pointer in context
[task 2020-10-19T15:49:48.019Z] 15:49:48     INFO -   1  libxul.so!xpc::CompartmentPrivate::UpdateWeakPointersAfterGC() [XPCJSRuntime.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 931 + 0x9]
[task 2020-10-19T15:49:48.020Z] 15:49:48     INFO -      rbx = 0x00007f703dcbf9c0   rbp = 0x00007f7038bebc30
[task 2020-10-19T15:49:48.020Z] 15:49:48     INFO -      rsp = 0x00007ffc1476d530   r12 = 0x0000000000000001
[task 2020-10-19T15:49:48.020Z] 15:49:48     INFO -      r13 = 0x00007f703df1d318   r14 = 0x00007f703d6bf080
[task 2020-10-19T15:49:48.021Z] 15:49:48     INFO -      r15 = 0x00007f703df29000   rip = 0x00007f7051dd8fe4
[task 2020-10-19T15:49:48.021Z] 15:49:48     INFO -      Found by: call frame info
[task 2020-10-19T15:49:48.021Z] 15:49:48     INFO -   2  libxul.so!js::gc::GCRuntime::beginSweepingSweepGroup(JSFreeOp*, js::SliceBudget&) [GC.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 5365 + 0x1d]
[task 2020-10-19T15:49:48.022Z] 15:49:48     INFO -      rbx = 0x00007f703d216000   rbp = 0x00007f7038bebc30
[task 2020-10-19T15:49:48.022Z] 15:49:48     INFO -      rsp = 0x00007ffc1476d580   r12 = 0x0000000000000001
[task 2020-10-19T15:49:48.023Z] 15:49:48     INFO -      r13 = 0x00007f703df1d318   r14 = 0x00007f703d6bf080
[task 2020-10-19T15:49:48.023Z] 15:49:48     INFO -      r15 = 0x00007f703df29000   rip = 0x00007f70518ea854
[task 2020-10-19T15:49:48.024Z] 15:49:48     INFO -      Found by: call frame info
[task 2020-10-19T15:49:48.024Z] 15:49:48     INFO -   3  libxul.so!sweepaction::SweepActionSequence::run(js::gc::SweepAction::Args&) [GC.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 6083 + 0xc]
[task 2020-10-19T15:49:48.025Z] 15:49:48     INFO -      rbx = 0x00007f703df2a340   rbp = 0x00007ffc1476daa8
[task 2020-10-19T15:49:48.025Z] 15:49:48     INFO -      rsp = 0x00007ffc1476d940   r12 = 0x00007ffc1476d978
[task 2020-10-19T15:49:48.025Z] 15:49:48     INFO -      r13 = 0x00007ffc1476d970   r14 = 0x0000000000000001
[task 2020-10-19T15:49:48.026Z] 15:49:48     INFO -      r15 = 0x00007f7053e1df52   rip = 0x00007f705287b2af
[task 2020-10-19T15:49:48.026Z] 15:49:48     INFO -      Found by: call frame info
[task 2020-10-19T15:49:48.027Z] 15:49:48     INFO -   4  libxul.so!sweepaction::SweepActionForEach<js::gc::SweepGroupsIter, JSRuntime*>::run(js::gc::SweepAction::Args&) [GC.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 6118 + 0xd]
[task 2020-10-19T15:49:48.027Z] 15:49:48     INFO -      rbx = 0x00007f703df19220   rbp = 0x00007ffc1476daa8
[task 2020-10-19T15:49:48.028Z] 15:49:48     INFO -      rsp = 0x00007ffc1476d960   r12 = 0x00007ffc1476d978
[task 2020-10-19T15:49:48.028Z] 15:49:48     INFO -      r13 = 0x00007ffc1476d970   r14 = 0x0000000000000001
[task 2020-10-19T15:49:48.028Z] 15:49:48     INFO -      r15 = 0x00007f7053e1df52   rip = 0x00007f7052879a87
[task 2020-10-19T15:49:48.029Z] 15:49:48     INFO -      Found by: call frame info
[task 2020-10-19T15:49:48.029Z] 15:49:48     INFO -   5  libxul.so!js::gc::GCRuntime::incrementalSlice(js::SliceBudget&, mozilla::Maybe<JSGCInvocationKind> const&, JS::GCReason, js::gc::AutoGCSession&) [GC.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 6831 + 0xda]
[task 2020-10-19T15:49:48.030Z] 15:49:48     INFO -      rbx = 0x0000000000000000   rbp = 0x00007ffc1476dae0
[task 2020-10-19T15:49:48.030Z] 15:49:48     INFO -      rsp = 0x00007ffc1476d9e0   r12 = 0x00007f703df1c550
[task 2020-10-19T15:49:48.031Z] 15:49:48     INFO -      r13 = 0x00007f703df1c4f8   r14 = 0x0000000000160001
[task 2020-10-19T15:49:48.031Z] 15:49:48     INFO -      r15 = 0x00007f7053e1df52   rip = 0x00007f70518ee5b2
[task 2020-10-19T15:49:48.032Z] 15:49:48     INFO -      Found by: call frame info
[task 2020-10-19T15:49:48.032Z] 15:49:48     INFO -   6  libxul.so!js::gc::GCRuntime::gcCycle(bool, js::SliceBudget, mozilla::Maybe<JSGCInvocationKind> const&, JS::GCReason) [GC.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 7238 + 0x13]
[task 2020-10-19T15:49:48.032Z] 15:49:48     INFO -      rbx = 0x00007ffc1476dc80   rbp = 0x00007f703df1d400
[task 2020-10-19T15:49:48.033Z] 15:49:48     INFO -      rsp = 0x00007ffc1476dc30   r12 = 0x0000000000000002
[task 2020-10-19T15:49:48.033Z] 15:49:48     INFO -      r13 = 0x0000000000000001   r14 = 0x00007ffc1476dc78
[task 2020-10-19T15:49:48.038Z] 15:49:48     INFO -      r15 = 0x00007f703df1c4f8   rip = 0x00007f705287772a
[task 2020-10-19T15:49:48.039Z] 15:49:48     INFO -      Found by: call frame info
[task 2020-10-19T15:49:48.040Z] 15:49:48     INFO -   7  libxul.so!js::gc::GCRuntime::collect(bool, js::SliceBudget, mozilla::Maybe<JSGCInvocationKind> const&, JS::GCReason) [GC.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 7473 + 0x28]
[task 2020-10-19T15:49:48.040Z] 15:49:48     INFO -      rbx = 0x0000000000000001   rbp = 0x00007ffc1476dd60
[task 2020-10-19T15:49:48.040Z] 15:49:48     INFO -      rsp = 0x00007ffc1476dcf0   r12 = 0x0000000000000002
[task 2020-10-19T15:49:48.040Z] 15:49:48     INFO -      r13 = 0x00007f703df29000   r14 = 0x0000000000000001
[task 2020-10-19T15:49:48.040Z] 15:49:48     INFO -      r15 = 0x00007f703df1c4f8   rip = 0x00007f7052877dce
[task 2020-10-19T15:49:48.040Z] 15:49:48     INFO -      Found by: call frame info
[task 2020-10-19T15:49:48.042Z] 15:49:48     INFO -   8  libxul.so!js::gc::GCRuntime::gc(JSGCInvocationKind, JS::GCReason) [GC.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 7549 + 0x4d]
[task 2020-10-19T15:49:48.042Z] 15:49:48     INFO -      rbx = 0x00007f703df1c000   rbp = 0x00007f703dcb9b68
[task 2020-10-19T15:49:48.042Z] 15:49:48     INFO -      rsp = 0x00007ffc1476dd60   r12 = 0x00007f705376d5a8
[task 2020-10-19T15:49:48.042Z] 15:49:48     INFO -      r13 = 0x00007f705376d5a8   r14 = 0x00007f703df29000
[task 2020-10-19T15:49:48.042Z] 15:49:48     INFO -      r15 = 0x00007f703df1c4f8   rip = 0x00007f705286ace1
[task 2020-10-19T15:49:48.042Z] 15:49:48     INFO -      Found by: call frame info
[task 2020-10-19T15:49:48.042Z] 15:49:48     INFO -   9  libxul.so!JSRuntime::destroyRuntime() [Runtime.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 288 + 0xf]
[task 2020-10-19T15:49:48.042Z] 15:49:48     INFO -      rbx = 0x00007f703df1c000   rbp = 0x00007f703dcb9b68
[task 2020-10-19T15:49:48.042Z] 15:49:48     INFO -      rsp = 0x00007ffc1476ddc0   r12 = 0x00007f705376d5a8
[task 2020-10-19T15:49:48.042Z] 15:49:48     INFO -      r13 = 0x00007f705376d5a8   r14 = 0x00007f703df29000
[task 2020-10-19T15:49:48.044Z] 15:49:48     INFO -      r15 = 0x00007f703df1c4f8   rip = 0x00007f70527b3bd3
[task 2020-10-19T15:49:48.044Z] 15:49:48     INFO -      Found by: call frame info
[task 2020-10-19T15:49:48.044Z] 15:49:48     INFO -  10  libxul.so!js::DestroyContext(JSContext*) [JSContext.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 223 + 0x8]
[task 2020-10-19T15:49:48.044Z] 15:49:48     INFO -      rbx = 0x00007f703df29000   rbp = 0x00007f703dcb9b68
[task 2020-10-19T15:49:48.044Z] 15:49:48     INFO -      rsp = 0x00007ffc1476de00   r12 = 0x00007f705376d5a8
[task 2020-10-19T15:49:48.044Z] 15:49:48     INFO -      r13 = 0x00007f705376d5a8   r14 = 0x00007f703df1c000
[task 2020-10-19T15:49:48.044Z] 15:49:48     INFO -      r15 = 0x00007f705376d5a8   rip = 0x00007f705278a20c
[task 2020-10-19T15:49:48.044Z] 15:49:48     INFO -      Found by: call frame info
[task 2020-10-19T15:49:48.044Z] 15:49:48     INFO -  11  libxul.so!mozilla::CycleCollectedJSContext::~CycleCollectedJSContext() [CycleCollectedJSContext.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 108 + 0xc]
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -      rbx = 0x00007f703dcb4000   rbp = 0x00007f703dcb9b68
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -      rsp = 0x00007ffc1476de40   r12 = 0x00007f705376d5a8
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -      r13 = 0x00007f705376d5a8   r14 = 0x00007f703dcb9b90
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -      r15 = 0x00007f705376d5a8   rip = 0x00007f7051ac3a92
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -      Found by: call frame info
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -  12  libxul.so!XPCJSContext::~XPCJSContext() [XPCJSContext.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 1103 + 0x25]
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -      rbx = 0x0000000000000000   rbp = 0x0000000000000000
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -      rsp = 0x00007ffc1476dec0   r12 = 0x0000000000000000
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -      r13 = 0x00007f703de1eb50   r14 = 0x00007f703dcb4000
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -      r15 = 0x00007f703de76040   rip = 0x00007f7051dd5d9a
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -      Found by: call frame info
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -  13  libxul.so!nsXPConnect::~nsXPConnect() [nsXPConnect.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 128 + 0x11]
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -      rbx = 0x00007f703dcb4000   rbp = 0x0000000000000000
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -      rsp = 0x00007ffc1476df00   r12 = 0x00007f703dcb36a0
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -      r13 = 0x00007f703de1eb50   r14 = 0x00007f70562c0a80
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -      r15 = 0x00007ffc1476df08   rip = 0x00007f7051dea2a7
[task 2020-10-19T15:49:48.045Z] 15:49:48     INFO -      Found by: call frame info
[task 2020-10-19T15:49:48.046Z] 15:49:48     INFO -  14  libxul.so!nsXPConnect::ReleaseXPConnectSingleton() [nsXPConnect.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 163 + 0x16]
[task 2020-10-19T15:49:48.046Z] 15:49:48     INFO -      rbx = 0x00007f703de1eb50   rbp = 0x000000000000000f
[task 2020-10-19T15:49:48.046Z] 15:49:48     INFO -      rsp = 0x00007ffc1476df70   r12 = 0x00007f7042e72160
[task 2020-10-19T15:49:48.046Z] 15:49:48     INFO -      r13 = 0x00007f7042e67800   r14 = 0x00007f705376d5a8
[task 2020-10-19T15:49:48.046Z] 15:49:48     INFO -      r15 = 0x00007f7042e72210   rip = 0x00007f7051dea893
[task 2020-10-19T15:49:48.046Z] 15:49:48     INFO -      Found by: call frame info
[task 2020-10-19T15:49:48.046Z] 15:49:48     INFO -  15  libxul.so!nsComponentManagerImpl::Shutdown() [nsComponentManager.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 933 + 0x5]
[task 2020-10-19T15:49:48.046Z] 15:49:48     INFO -      rbx = 0x00007f7042e72218   rbp = 0x000000000000000f
[task 2020-10-19T15:49:48.046Z] 15:49:48     INFO -      rsp = 0x00007ffc1476df80   r12 = 0x00007f7042e72160
[task 2020-10-19T15:49:48.047Z] 15:49:48     INFO -      r13 = 0x00007f7042e67800   r14 = 0x00007f705376d5a8
[task 2020-10-19T15:49:48.047Z] 15:49:48     INFO -      r15 = 0x00007f7042e72210   rip = 0x00007f7051af8866
[task 2020-10-19T15:49:48.047Z] 15:49:48     INFO -      Found by: call frame info
[task 2020-10-19T15:49:48.048Z] 15:49:48     INFO -  16  libxul.so!mozilla::ShutdownXPCOM(nsIServiceManager*) [XPCOMInit.cpp:f4553c5e469b1c15ade6ab55c9cae3f166ce01ab : 734 + 0x5]
[task 2020-10-19T15:49:48.048Z] 15:49:48     INFO -      rbx = 0x0000000000000000   rbp = 0x00007f7042e47a60
[task 2020-10-19T15:49:48.048Z] 15:49:48     INFO -      rsp = 0x00007ffc1476dfc0   r12 = 0x0000000000000000
[task 2020-10-19T15:49:48.049Z] 15:49:48     INFO -      r13 = 0x00007f703df29000   r14 = 0x0000000000000000
[task 2020-10-19T15:49:48.049Z] 15:49:48     INFO -      r15 = 0x00007f7042eb72e0   rip = 0x00007f7051b1c967
[task 2020-10-19T15:49:48.050Z] 15:49:48     INFO -      Found by: call frame info```

Andrew, do you have any thoughts on this crash? Is this bug actionable? Nika says this crash is happening during late XPConnect shutdown.

This bug was filed for an intermittent test crash, but we have crash reports with the same JSObject2WrappedJSMap::UpdateWeakPointersAfterGC signature from the wild:

https://crash-stats.mozilla.org/signature/?signature=JSObject2WrappedJSMap%3A%3AUpdateWeakPointersAfterGC&product=Firefox&date=%3E%3D2020-06-14T19%3A43%3A00.000Z&date=%3C2020-12-14T19%3A43%3A00.000Z&_sort=-dat

Severity: normal → S3
status-firefox83: --- → affected
status-firefox84: --- → affected
status-firefox85: --- → ?
status-firefox86: --- → ?
status-firefox-esr78: --- → affected
Flags: needinfo?(continuation)
OS: Unspecified → All
Hardware: Unspecified → All

There's already a bug on file for this intermittent failure. I guess we might as well leave a public one so we can see how often it is happening.

Flags: needinfo?(continuation)
See Also: → 1650075
Priority: -- → P3
Crash Signature: [@ JSObject2WrappedJSMap::UpdateWeakPointersAfterGC()] → [@ JSObject2WrappedJSMap::UpdateWeakPointersAfterGC]
Summary: Intermittent services/sync/tests/unit/test_addons_store.js | application crashed [@ JSObject2WrappedJSMap::UpdateWeakPointersAfterGC()] | After xpcshell return code: -11 → Intermittent services/sync/tests/unit/test_addons_store.js | application crashed [@ JSObject2WrappedJSMap::UpdateWeakPointersAfterGC] | After xpcshell return code: -11
You need to log in before you can comment on or make changes to this bug.