Closed Bug 1575628 Opened 6 years ago Closed 6 years ago

Intermittent jsreftest A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down

Categories

(Core :: JavaScript Engine, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, regression)

Cycle collector OOM during JS reftest:

[task 2019-08-21T17:43:23.896Z] 17:43:23     INFO - REFTEST TEST-START | file:///Z:/task_1566405636/build/tests/jsreftest/tests/jsreftest.html?test=test262/language/expressions/class/dstr/meth-obj-ptrn-id-init-fn-name-gen.js
[task 2019-08-21T17:43:23.896Z] 17:43:23     INFO - REFTEST TEST-LOAD | file:///Z:/task_1566405636/build/tests/jsreftest/tests/jsreftest.html?test=test262/language/expressions/class/dstr/meth-obj-ptrn-id-init-fn-name-gen.js | 4997 / 21582 (23%)
[task 2019-08-21T17:43:25.812Z] 17:43:25     INFO - out of memory: 0x0000000000010000 bytes requested
[task 2019-08-21T17:43:26.153Z] 17:43:26    ERROR - A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
[task 2019-08-21T17:43:26.308Z] 17:43:26     INFO - [Parent 852, Gecko_IOThread] WARNING: file z:/task_1566402276/build/src/ipc/chromium/src/base/process_util_win.cc, line 160
[task 2019-08-21T17:43:26.308Z] 17:43:26     INFO - [Parent 852, Gecko_IOThread] WARNING: pipe error: 109: file z:/task_1566402276/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-08-21T17:43:26.328Z] 17:43:26     INFO - !!! error running onStopped callback: TypeError: callback is not a function
[task 2019-08-21T17:43:26.355Z] 17:43:26     INFO - [Parent 852, Gecko_IOThread] WARNING: pipe error: 109: file z:/task_1566402276/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-08-21T17:43:26.355Z] 17:43:26     INFO - [Child 1748, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1566402276/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-08-21T17:43:26.550Z] 17:43:26     INFO - 1566409406515	Marionette	TRACE	Received observer notification xpcom-will-shutdown
[task 2019-08-21T17:43:26.550Z] 17:43:26     INFO - 1566409406515	Marionette	INFO	Stopped listening on port 2828
[task 2019-08-21T17:43:26.550Z] 17:43:26     INFO - 1566409406515	Marionette	DEBUG	Remote service is inactive
[task 2019-08-21T17:43:26.726Z] 17:43:26     INFO - [Child 1748, Chrome_ChildThread] WARNING: pipe error: 10
[task 2019-08-21T17:43:26.733Z] 17:43:26     INFO - REFTEST INFO | Downloading symbols from: https://queue.taskcluster.net/v1/task/ckDA03WaSzSQRSOnBOSQLg/artifacts/public/build/target.crashreporter-symbols.zip
[task 2019-08-21T17:43:59.311Z] 17:43:59     INFO - REFTEST INFO | Copy/paste: Z:\task_1566405636\build\win32-minidump_stackwalk.exe c:\users\task_1566405636\appdata\local\temp\tmpbzofey.mozrunner\minidumps\52547360-d14b-4337-93a8-57ccf193a58a.dmp c:\users\task_1566405636\appdata\local\temp\tmp8tgsyd
[task 2019-08-21T17:44:14.158Z] 17:44:14     INFO - REFTEST INFO | Saved minidump as Z:\task_1566405636\build\blobber_upload_dir\52547360-d14b-4337-93a8-57ccf193a58a.dmp
[task 2019-08-21T17:44:14.165Z] 17:44:14     INFO - REFTEST INFO | Saved app info as Z:\task_1566405636\build\blobber_upload_dir\52547360-d14b-4337-93a8-57ccf193a58a.extra
[task 2019-08-21T17:44:14.242Z] 17:44:14     INFO - REFTEST PROCESS-CRASH | file:///Z:/task_1566405636/build/tests/jsreftest/tests/jsreftest.html?test=test262/language/expressions/class/dstr/meth-obj-ptrn-id-init-fn-name-gen.js | application crashed [@ mozalloc_handle_oom(unsigned int)]
[task 2019-08-21T17:44:14.242Z] 17:44:14     INFO - Crash dump filename: c:\users\task_1566405636\appdata\local\temp\tmpbzofey.mozrunner\minidumps\52547360-d14b-4337-93a8-57ccf193a58a.dmp
[task 2019-08-21T17:44:14.242Z] 17:44:14     INFO - Operating system: Windows NT
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO -                   6.1.7601 Service Pack 1
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO - CPU: x86
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO -      GenuineIntel family 6 model 63 stepping 2
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO -      8 CPUs
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO - 
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO - GPU: UNKNOWN
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO - 
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO - Crash reason:  EXCEPTION_BREAKPOINT
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO - Crash address: 0x6cc9addc
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO - Assertion: Unknown assertion type 0x00000000
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO - Process uptime: 253 seconds
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO - 
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO - Thread 0 (crashed)
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO -  0  mozglue.dll!mozalloc_abort [mozalloc_abort.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 33 + 0x0]
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO -     eip = 0x6cc9addc   esp = 0x002ed000   ebp = 0x002ed008   ebx = 0x64a69284
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO -     esi = 0x002ed016   edi = 0x6714f9b0   eax = 0x0000000a   ecx = 0x67157af0
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO -     edx = 0x00000000   efl = 0x00000216
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO -     Found by: given as instruction pointer in context
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO -  1  mozglue.dll!mozalloc_handle_oom(unsigned int) [mozalloc_oom.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 51 + 0x6]
[task 2019-08-21T17:44:14.243Z] 17:44:14     INFO -     eip = 0x6cc9b023   esp = 0x002ed010   ebp = 0x002ed050   esi = 0x002ed016
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -     edi = 0x00000000
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -  2  mozglue.dll!moz_xmalloc [cxxalloc.h:883e6437a6eade66c61f24737f2205a40961da4f : 33 + 0x28]
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -     eip = 0x6cc9ad9e   esp = 0x002ed058   ebp = 0x002ed06c   esi = 0x00000000
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -     edi = 0x00010000
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -  3  xul.dll!CCGraphBuilder::NoteJSChild(JS::GCCellPtr const &) [nsCycleCollector.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 0 + 0xb]
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -     eip = 0x585f7e24   esp = 0x002ed074   ebp = 0x002ed098   esi = 0x239ba000
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -     edi = 0x2dff7040
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -  4  xul.dll!TraversalTracer::onChild(JS::GCCellPtr const &) [CycleCollectedJSRuntime.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 421 + 0x1a]
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -     eip = 0x585e2f9a   esp = 0x002ed0a0   ebp = 0x002ed190   ebx = 0x2dff7040
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -     esi = 0x00000000   edi = 0x002ed350
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -  5  xul.dll!DoCallback<JSObject>(JS::CallbackTracer *,JSObject * *,char const *) [Tracer.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 45 + 0x18]
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -     eip = 0x5c3b8a9d   esp = 0x002ed198   ebp = 0x002ed1b4   ebx = 0x5d02b390
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -     esi = 0x002ed350   edi = 0x5d78d53a
[task 2019-08-21T17:44:14.244Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -  6  xul.dll!js::ObjectGroup::traceChildren(JSTracer *) [Marking.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 1371 + 0x12]
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -     eip = 0x5c3910b7   esp = 0x002ed1bc   ebp = 0x002ed1e8   ebx = 0x002ed350
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -     esi = 0x2dff7040   edi = 0x2dfe0100
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -  7  xul.dll!TraversalTracer::onChild(JS::GCCellPtr const &) [CycleCollectedJSRuntime.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 430 + 0x1c]
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -     eip = 0x585e30db   esp = 0x002ed1f0   ebp = 0x002ed2dc   ebx = 0x2dfe0100
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -     esi = 0x00000005   edi = 0x05bfc020
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -  8  xul.dll!JSObject::traceChildren(JSTracer *) [JSObject.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 4052 + 0x43]
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -     eip = 0x5c1750db   esp = 0x002ed2e4   ebp = 0x002ed310   ebx = 0x002ed350
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -     esi = 0x00000000   edi = 0x2dff4320
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -  9  xul.dll!js::TraceChildren(JSTracer *,void *,JS::TraceKind) [Tracer.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 99 + 0xf]
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -     eip = 0x5c3b8539   esp = 0x002ed318   ebp = 0x002ed334   ebx = 0x0115c800
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -     esi = 0x2dff4320   edi = 0x2dff4320
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO - 10  xul.dll!mozilla::CycleCollectedJSRuntime::TraverseGCThing(mozilla::CycleCollectedJSRuntime::TraverseSelect,JS::GCCellPtr,nsCycleCollectionTraversalCallback &) [CycleCollectedJSRuntime.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 725 + 0x4a]
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -     eip = 0x585e2c1b   esp = 0x002ed33c   ebp = 0x002ed384   ebx = 0x0115c800
[task 2019-08-21T17:44:14.245Z] 17:44:14     INFO -     esi = 0x239ba000   edi = 0x2dff4320
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO - 11  xul.dll!bool CCGraphBuilder::BuildGraph(class js::SliceBudget & const) [nsCycleCollector.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 2061 + 0x46]
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO -     eip = 0x585f6f91   esp = 0x002ed38c   ebp = 0x002ed3e8   ebx = 0x2dff4320
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO -     esi = 0x239ba000   edi = 0x0115c814
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO - 12  xul.dll!void nsCycleCollector::MarkRoots(class js::SliceBudget & const) [nsCycleCollector.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 2672 + 0x5]
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO -     eip = 0x585f999b   esp = 0x002ed3f0   ebp = 0x002ed3f8   ebx = 0x00000000
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO -     esi = 0x01109100   edi = 0x06809800
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO - 13  xul.dll!nsCycleCollector::Collect(ccType,js::SliceBudget &,nsICycleCollectorListener *,bool) [nsCycleCollector.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 3417 + 0xe]
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO -     eip = 0x585fbdd8   esp = 0x002ed400   ebp = 0x002ed474   esi = 0x002ed570
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO -     edi = 0x00000001
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO - 14  xul.dll!nsCycleCollector_collectSlice(js::SliceBudget &,bool) [nsCycleCollector.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 3962 + 0xd]
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO -     eip = 0x585fdfbb   esp = 0x002ed47c   ebp = 0x002ed4a0   ebx = 0x002ed48c
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO -     esi = 0x0110a040   edi = 0x011052d0
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO - 15  xul.dll!static void nsJSContext::RunCycleCollectorSlice(class mozilla::TimeStamp) [nsJSEnvironment.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 1479 + 0x11]
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO -     eip = 0x59811dac   esp = 0x002ed4a8   ebp = 0x002ed628   ebx = 0x0000001e
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO -     esi = 0x00000001   edi = 0x2da8dc20
[task 2019-08-21T17:44:14.246Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.247Z] 17:44:14     INFO - 16  xul.dll!ICCRunnerFired(mozilla::TimeStamp) [nsJSEnvironment.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 1530 + 0x28]
[task 2019-08-21T17:44:14.247Z] 17:44:14     INFO -     eip = 0x598123ae   esp = 0x002ed630   ebp = 0x002ed674   ebx = 0x00000000
[task 2019-08-21T17:44:14.247Z] 17:44:14     INFO -     esi = 0x177bf7c0   edi = 0x177bf810
[task 2019-08-21T17:44:14.247Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.247Z] 17:44:14     INFO - 17  xul.dll!std::_Func_impl_no_alloc<bool (*)(mozilla::TimeStamp),bool,mozilla::TimeStamp>::_Do_call(mozilla::TimeStamp &&) + 0x2b
[task 2019-08-21T17:44:14.247Z] 17:44:14     INFO -     eip = 0x591b66bb   esp = 0x002ed67c   ebp = 0x002ed694   ebx = 0x00000000
[task 2019-08-21T17:44:14.247Z] 17:44:14     INFO -     esi = 0x177bf7c0   edi = 0x177bf810
[task 2019-08-21T17:44:14.247Z] 17:44:14     INFO -     Found by: call frame info
[task 2019-08-21T17:44:14.247Z] 17:44:14     INFO - 18  xul.dll!nsresult mozilla::IdleTaskRunner::Run() [IdleTaskRunner.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 58 + 0x11]
[task 2019-08-21T17:44:14.247Z] 17:44:14     INFO -     eip = 0x5867a1dd   esp = 0x002ed69c   ebp = 0x002ed710
[task 2019-08-21T17:44:14.247Z] 17:44:14     INFO -     Found by: previous frame's frame pointer
[task 2019-08-21T17:44:14.247Z] 17:44:14     INFO - 19  xul.dll!nsJSContext::RunNextCollectorTimer(JS::GCReason,mozilla::TimeStamp) [nsJSEnvironment.cpp:883e6437a6eade66c61f24737f2205a40961da4f : 1945 + 0x8]

There are a few of these JS reftest OOMs on file. Somebody from the JS team needs to look at why the tests are using so much memory.

This is in the log:
out of memory: 0x0000000000010000 bytes requested

That's a pretty small amount of memory, so I don't think it is something like the cycle collector using too much memory to run that is the issue.

(In reply to Andrew McCreight [:mccr8] from comment #2)

There are a few of these JS reftest OOMs on file.

Yeah, I'm looking at this in bug 1518138.

Summary: Intermittent A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down → Intermittent jsreftest A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down

This bug failed 32 times in the last 7 days. Occurs on linux64, macosx1014-64-shippable and windows platforms on opt and debug build types.

Recent log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=264381666&repo=autoland&lineNumber=9216

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