Closed Bug 1548827 Opened 5 years ago Closed 5 years ago

Intermittent Windows 7 Js Reftest /build/tests/jsreftest/tests/jsreftest.html?test=test262<random path> | application crashed [@ mozalloc_abort]

Categories

(Core :: XPCOM, defect, P5)

Product:
Core
Component:
XPCOM
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
mozilla68
Tracking Flags:
Tracking Status
firefox-esr60 --- unaffected
firefox66 --- unaffected
firefox67 --- unaffected
firefox68 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: smaug)

References

(Regressed 1 open bug)

Details

(Keywords: crash, intermittent-failure, regression)

Crash Data

Attachments

(1 file)

Bug 1548827, let CC to run sooner after GC, r=mccr8
47 bytes, text/x-phabricator-request
Details | Review

Filed by: dluca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=244473725&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/AWNvLUEnQw6EFbiZROjgBw/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://queue.taskcluster.net/v1/task/AWNvLUEnQw6EFbiZROjgBw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

REFTEST TEST-START | file:///Z:/task_1556882394/build/tests/jsreftest/tests/jsreftest.html?test=test262/language/expressions/await/await-in-nested-function.js
13:08:53 INFO - REFTEST TEST-LOAD | file:///Z:/task_1556882394/build/tests/jsreftest/tests/jsreftest.html?test=test262/language/expressions/await/await-in-nested-function.js | 4189 / 21429 (19%)
13:08:54 INFO - out of memory: 0x0000000000010000 bytes requested
13:08:54 INFO - ###!!! [Parent][MessageChannel] Error: (msgtype=0x1E009D,name=PBrowser::Msg_UpdateNativeWindowHandle) Channel error: cannot send/recv
13:08:54 INFO - ###!!! [Parent][MessageChannel] Error: (msgtype=0x1E008C,name=PBrowser::Msg_Destroy) Channel error: cannot send/recv
13:08:54 INFO - A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
13:08:54 INFO - ###!!! [Parent][MessageChannel] Error: (msgtype=0x350041,name=PContent::Msg_NotifyProcessPriorityChanged) Channel error: cannot send/recv
13:08:54 INFO - [Parent 6008, Gecko_IOThread] WARNING: pipe error: 109: file z:/task_1556881008/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:08:54 INFO - [Child 5304, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1556881008/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:08:54 INFO - JavaScript error: resource://reftest/reftest.jsm, line 1558: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString]
13:08:54 INFO - !!! error running onStopped callback: TypeError: callback is not a function
13:08:54 INFO - [Parent 6008, Gecko_IOThread] WARNING: pipe error: 109: file z:/task_1556881008/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:08:54 INFO - [Child 3212, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1556881008/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:08:54 INFO - [Child 3212, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1556881008/build/src/ipc/chromium/src/chrome/common/ipc_chaJavaScript error: resource://reftest/reftest.jsm, line 1558: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString]
13:08:54 INFO - [Child 3828, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1556881008/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:08:54 INFO - JavaScript error: resource://reftest/reftest.jsm, line 1558: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString]
13:08:54 INFO - 1556888934850 Marionette TRACE Received observer notification xpcom-will-shutdown
13:08:54 INFO - 1556888934850 Marionette INFO Stopped listening on port 2828
13:08:54 INFO - 1556888934850 Marionette DEBUG Remote service is inactive
13:08:55 INFO - REFTEST INFO | Downloading symbols from: https://queue.taskcluster.net/v1/task/aTgH22ZuRt6y3-5HUlX_5w/artifacts/public/build/target.crashreporter-symbols.zip
13:08:58 INFO - REFTEST INFO | Copy/paste: Z:\task_1556882394\build\win32-minidump_stackwalk.exe c:\users\task_1556882394\appdata\local\temp\tmppifnd5.mozrunner\minidumps\75eae32f-fb87-4cd0-bff0-6326cf00f2a2.dmp c:\users\task_1556882394\appdata\local\temp\tmprz69qa
13:09:11 INFO - REFTEST INFO | Saved minidump as Z:\task_1556882394\build\blobber_upload_dir\75eae32f-fb87-4cd0-bff0-6326cf00f2a2.dmp
13:09:11 INFO - REFTEST INFO | Saved app info as Z:\task_1556882394\build\blobber_upload_dir\75eae32f-fb87-4cd0-bff0-6326cf00f2a2.extra
13:09:11 INFO - REFTEST PROCESS-CRASH | file:///Z:/task_1556882394/build/tests/jsreftest/tests/jsreftest.html?test=test262/language/expressions/await/await-in-nested-function.js | application crashed [@ mozalloc_abort]
13:09:11 INFO - Crash dump filename: c:\users\task_1556882394\appdata\local\temp\tmppifnd5.mozrunner\minidumps\75eae32f-fb87-4cd0-bff0-6326cf00f2a2.dmp
13:09:11 INFO - Operating system: Windows NT
13:09:11 INFO - 6.1.7601 Service Pack 1
13:09:11 INFO - CPU: x86
13:09:11 INFO - GenuineIntel family 6 model 63 stepping 2
13:09:11 INFO - 8 CPUs
13:09:11 INFO -
13:09:11 INFO - GPU: UNKNOWN
13:09:11 INFO -
13:09:11 INFO - Crash reason: EXCEPTION_BREAKPOINT
13:09:11 INFO - Crash address: 0x72fd913c
13:09:11 INFO - Assertion: Unknown assertion type 0x00000000
13:09:11 INFO - Process uptime: 216 seconds
13:09:11 INFO -
13:09:11 INFO - Thread 0 (crashed)
13:09:11 INFO - 0 mozglue.dll!mozalloc_abort [mozalloc_abort.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 33 + 0x0]
13:09:11 INFO - eip = 0x72fd913c esp = 0x0026cde8 ebp = 0x0026cdf0 ebx = 0x77deaf28
13:09:11 INFO - esi = 0x0026cdfe edi = 0x6d9bf9b0 eax = 0x0000000a ecx = 0x6d9c7af0
13:09:11 INFO - edx = 0x00000000 efl = 0x00000206
13:09:11 INFO - Found by: given as instruction pointer in context
13:09:11 INFO - 1 mozglue.dll!mozalloc_handle_oom(unsigned int) [mozalloc_oom.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 51 + 0x6]
13:09:11 INFO - eip = 0x72fd92e3 esp = 0x0026cdf8 ebp = 0x0026ce38 esi = 0x0026cdfe
13:09:11 INFO - edi = 0x00000000
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 2 mozglue.dll!moz_xmalloc [mozalloc.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 70 + 0x6]
13:09:11 INFO - eip = 0x72fd9273 esp = 0x0026ce40 ebp = 0x0026ce48 esi = 0x00010000
13:09:11 INFO - edi = 0x639d5040
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 3 xul.dll!void CCGraphBuilder::NoteJSChild(const class JS::GCCellPtr & const) [nsCycleCollector.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 0 + 0xb]
13:09:11 INFO - eip = 0x5778a061 esp = 0x0026ce50 ebp = 0x0026ce74 esi = 0x75605000
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 4 xul.dll!void TraversalTracer::onChild(const class JS::GCCellPtr & const) [CycleCollectedJSRuntime.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 416 + 0x7]
13:09:11 INFO - eip = 0x5777c9a2 esp = 0x0026ce7c ebp = 0x0026cf5c ebx = 0x0026cfa8
13:09:11 INFO - esi = 0x0026d1f8 edi = 0x0026cf68
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 5 xul.dll!JS::CallbackTracer::onObjectEdge(JSObject * *) [TracingAPI.h:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 157 + 0x6]
13:09:11 INFO - eip = 0x57784342 esp = 0x0026cf64 ebp = 0x0026cf70 ebx = 0x0026cfa8
13:09:11 INFO - esi = 0x0026d1f8 edi = 0x5a9de077
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 6 xul.dll!DoCallback<JSObject>(JS::CallbackTracer *,JSObject * *,char const *) [Tracer.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 45 + 0x5]
13:09:11 INFO - eip = 0x56c1f15f esp = 0x0026cf78 ebp = 0x0026cf88
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 7 xul.dll!js::ObjectGroup::traceChildren(JSTracer *) [Marking.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 1375 + 0x17]
13:09:11 INFO - eip = 0x56bf8a6c esp = 0x0026cf90 ebp = 0x0026cfbc ebx = 0x639f5c40
13:09:11 INFO - esi = 0x639d2100 edi = 0x0026d1fc
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 8 xul.dll!js::gc::TraceCycleCollectorChildren(JS::CallbackTracer *,js::ObjectGroup *) [Tracer.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 164 + 0xe]
13:09:11 INFO - eip = 0x56c1f187 esp = 0x0026cfc4 ebp = 0x0026cfc8 ebx = 0x639f5c40
13:09:11 INFO - esi = 0x0026d1f8 edi = 0x0026d0cc
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 9 xul.dll!JS_TraceObjectGroupCycleCollectorChildren(JS::CallbackTracer *,JS::GCCellPtr) [jsfriendapi.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 219 + 0x9]
13:09:11 INFO - eip = 0x56bbdf52 esp = 0x0026cfd0 ebp = 0x0026cfd8
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 10 xul.dll!void TraversalTracer::onChild(const class JS::GCCellPtr & const) [CycleCollectedJSRuntime.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 425 + 0x7]
13:09:11 INFO - eip = 0x5777ca88 esp = 0x0026cfe0 ebp = 0x0026d0c0
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 11 xul.dll!JS::CallbackTracer::onObjectGroupEdge(js::ObjectGroup * *) [TracingAPI.h:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 170 + 0x6]
13:09:11 INFO - eip = 0x577844c5 esp = 0x0026d0c8 ebp = 0x0026d0d4 ebx = 0x639f5c40
13:09:11 INFO - esi = 0x0026d1f8 edi = 0x00000000
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 12 xul.dll!DoCallback<js::ObjectGroup>(JS::CallbackTracer *,js::ObjectGroup * *,char const *) [Tracer.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 45 + 0x6]
13:09:11 INFO - eip = 0x56c1f660 esp = 0x0026d0dc ebp = 0x0026d0ec
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 13 xul.dll!js::gc::TraceEdgeInternal<js::ObjectGroup *>(JSTracer *,js::ObjectGroup * *,char const *) [Marking.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 568 + 0xa]
13:09:11 INFO - eip = 0x56bfe0d7 esp = 0x0026d0f4 ebp = 0x0026d114 ebx = 0x0026d1fc
13:09:11 INFO - esi = 0x0026d1f8 edi = 0x639f5c40
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 14 xul.dll!JSObject::traceChildren(JSTracer *) [JSObject.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 4026 + 0xc]
13:09:11 INFO - eip = 0x56a48654 esp = 0x0026d11c ebp = 0x0026d150 ebx = 0x0026d1fc
13:09:11 INFO - esi = 0x0026d1fc edi = 0x639f5c40
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 15 xul.dll!js::TraceChildren(JSTracer *,void *,JS::TraceKind) [Tracer.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 95 + 0xa]
13:09:11 INFO - eip = 0x56c1ea56 esp = 0x0026d158 ebp = 0x0026d15c ebx = 0x22222200
13:09:11 INFO - esi = 0x0026d1fc edi = 0x75605000
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 16 xul.dll!JS::TraceChildren(JSTracer *,JS::GCCellPtr) [Tracer.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 90 + 0x8]
13:09:11 INFO - eip = 0x56c1ea1a esp = 0x0026d164 ebp = 0x0026d178
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 17 xul.dll!void mozilla::CycleCollectedJSRuntime::TraverseGCThing(mozilla::CycleCollectedJSRuntime::TraverseSelect, class JS::GCCellPtr, class nsCycleCollectionTraversalCallback & const) [CycleCollectedJSRuntime.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 690 + 0x5f]
13:09:11 INFO - eip = 0x5777c369 esp = 0x0026d180 ebp = 0x0026d2e8 esi = 0x00c5d000
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 18 xul.dll!nsresult mozilla::JSGCThingParticipant::TraverseNative(void *, class nsCycleCollectionTraversalCallback & const) [CycleCollectedJSRuntime.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 359 + 0x7]
13:09:11 INFO - eip = 0x5777c23e esp = 0x0026d2f0 ebp = 0x0026d304 ebx = 0x639f5c40
13:09:11 INFO - esi = 0x00c5d000 edi = 0x75605000
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 19 xul.dll!bool CCGraphBuilder::BuildGraph(class js::SliceBudget & const) [nsCycleCollector.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 2061 + 0x9]
13:09:11 INFO - eip = 0x577895a1 esp = 0x0026d30c ebp = 0x0026d338 ebx = 0x75605000
13:09:11 INFO - esi = 0x639f5c40 edi = 0x00c5d014
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 20 xul.dll!void nsCycleCollector::MarkRoots(class js::SliceBudget & const) [nsCycleCollector.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 2670 + 0x5]
13:09:11 INFO - eip = 0x5778b837 esp = 0x0026d340 ebp = 0x0026d350 ebx = 0x0026d520
13:09:11 INFO - esi = 0x00c0b1c0 edi = 0x0026d520
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 21 xul.dll!nsCycleCollector::Collect(ccType,js::SliceBudget &,nsICycleCollectorListener ,bool) [nsCycleCollector.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 3415 + 0x9]
13:09:11 INFO - eip = 0x5778be8c esp = 0x0026d358 ebp = 0x0026d42c esi = 0x00000000
13:09:11 INFO - edi = 0x00c0b1c0
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 22 xul.dll!nsCycleCollector_collectSlice(js::SliceBudget &,bool) [nsCycleCollector.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 3960 + 0xe]
13:09:11 INFO - eip = 0x5778efeb esp = 0x0026d434 ebp = 0x0026d458 ebx = 0x00000001
13:09:11 INFO - esi = 0x00c0c040 edi = 0x00c072d0
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 23 xul.dll!static void nsJSContext::RunCycleCollectorSlice(class mozilla::TimeStamp) [nsJSEnvironment.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 1488 + 0x11]
13:09:11 INFO - eip = 0x583e15c1 esp = 0x0026d460 ebp = 0x0026d5c8 ebx = 0x8000001d
13:09:11 INFO - esi = 0x0000001d edi = 0x00000001
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 24 xul.dll!ICCRunnerFired(mozilla::TimeStamp) [nsJSEnvironment.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 1539 + 0x28]
13:09:11 INFO - eip = 0x583e1b62 esp = 0x0026d5d0 ebp = 0x0026d614 ebx = 0x00000000
13:09:11 INFO - esi = 0x6d6e2be0 edi = 0x6d6e2c30
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 25 xul.dll!std::_Func_impl_no_alloc<bool ()(mozilla::TimeStamp),bool,mozilla::TimeStamp>::_Do_call(mozilla::TimeStamp &&) + 0x2b
13:09:11 INFO - eip = 0x567ab76b esp = 0x0026d61c ebp = 0x0026d634 ebx = 0x00000000
13:09:11 INFO - esi = 0x6d6e2be0 edi = 0x6d6e2c30
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 26 xul.dll!nsresult mozilla::IdleTaskRunner::Run() [IdleTaskRunner.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 58 + 0x15]
13:09:11 INFO - eip = 0x567ab5f5 esp = 0x0026d63c ebp = 0x0026d6b0
13:09:11 INFO - Found by: previous frame's frame pointer
13:09:11 INFO - 27 xul.dll!nsJSContext::RunNextCollectorTimer(JS::GCReason,mozilla::TimeStamp) [nsJSEnvironment.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 1947 + 0x8]
13:09:11 INFO - eip = 0x583e3bcc esp = 0x0026d6b8 ebp = 0x0026d6f8 ebx = 0x00000000
13:09:11 INFO - esi = 0x6d6e2be0 edi = 0x5a440ff4
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 28 xul.dll!nsresult nsDOMWindowUtils::RunNextCollectorTimer() [nsDOMWindowUtils.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 1072 + 0x30]
13:09:11 INFO - eip = 0x582c9e38 esp = 0x0026d700 ebp = 0x0026d75c esi = 0x5a440ff4
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 29 xul.dll!NS_InvokeByIndex + 0x27
13:09:11 INFO - eip = 0x5a2b7987 esp = 0x0026d764 ebp = 0x0026d768
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 30 xul.dll!static bool XPCWrappedNative::CallMethod(class XPCCallContext & const, XPCWrappedNative::CallMode) [XPCWrappedNative.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 1157 + 0x29b]
13:09:11 INFO - eip = 0x5611e45b esp = 0x0026d770 ebp = 0x0026d91c
13:09:11 INFO - Found by: previous frame's frame pointer
13:09:11 INFO - 31 xul.dll!static bool XPC_WN_CallMethod(struct JSContext *, unsigned int, union JS::Value *) [XPCWrappedNativeJSOps.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 943 + 0x8]
13:09:11 INFO - eip = 0x5611d804 esp = 0x0026d924 ebp = 0x0026d9a0 ebx = 0x00000000
13:09:11 INFO - esi = 0x07fa2000 edi = 0x07fa2130
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 32 0x1536fecb
13:09:11 INFO - eip = 0x1536fecb esp = 0x0026d9a8 ebp = 0x0026d9d8 ebx = 0x0a2a06d0
13:09:11 INFO - esi = 0xffffff8c edi = 0x380a7618
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 33 0x380a7618
13:09:11 INFO - eip = 0x380a7618 esp = 0x0026d9e0 ebp = 0x0026da2c
13:09:11 INFO - Found by: previous frame's frame pointer
13:09:11 INFO - 34 0x1536fce0
13:09:11 INFO - eip = 0x1536fce0 esp = 0x0026da34 ebp = 0x0026da68
13:09:11 INFO - Found by: previous frame's frame pointer
13:09:11 INFO - 35 0x380a61a8
13:09:11 INFO - eip = 0x380a61a8 esp = 0x0026da70 ebp = 0x0026dadc
13:09:11 INFO - Found by: previous frame's frame pointer
13:09:11 INFO - 36 0x1536fce0
13:09:11 INFO - eip = 0x1536fce0 esp = 0x0026dae4 ebp = 0x0026db08
13:09:11 INFO - Found by: previous frame's frame pointer
13:09:11 INFO - 37 0x62205210
13:09:11 INFO - eip = 0x62205210 esp = 0x0026db10 ebp = 0x0026db6c
13:09:11 INFO - Found by: previous frame's frame pointer
13:09:11 INFO - 38 0x153006ab
13:09:11 INFO - eip = 0x153006ab esp = 0x0026db74 ebp = 0x0026dba8
13:09:11 INFO - Found by: previous frame's frame pointer
13:09:11 INFO - 39 xul.dll!js::jit::MaybeEnterJit(JSContext *,js::RunState &) [Jit.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 195 + 0x161]
13:09:11 INFO - eip = 0x5738cf92 esp = 0x0026dbb0 ebp = 0x0026ddd8
13:09:11 INFO - Found by: previous frame's frame pointer
13:09:11 INFO - 40 xul.dll!js::RunScript(JSContext *,js::RunState &) [Interpreter.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 408 + 0x7]
13:09:11 INFO - eip = 0x56918429 esp = 0x0026dde0 ebp = 0x0026de08 ebx = 0x0a247c40
13:09:11 INFO - esi = 0x0610f800 edi = 0x0026de28
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 41 xul.dll!js::InternalCallOrConstruct(JSContext *,JS::CallArgs const &,js::MaybeConstruct) [Interpreter.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 563 + 0xa]
13:09:11 INFO - eip = 0x56923957 esp = 0x0026de10 ebp = 0x0026de74 ebx = 0x0610f810
13:09:11 INFO - esi = 0x0026ded8 edi = 0x0610f800
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 42 xul.dll!static bool InternalCall(struct JSContext *, const class js::AnyInvokeArgs & const) [Interpreter.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 590 + 0x9]
13:09:11 INFO - eip = 0x56923fdc esp = 0x0026de7c ebp = 0x0026dea4 ebx = 0x0026df20
13:09:11 INFO - esi = 0x0610f800 edi = 0x0026ded8
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 43 xul.dll!js::Call(JSContext *,JS::Handle<JS::Value>,JS::Handle<JS::Value>,js::AnyInvokeArgs const &,JS::MutableHandle<JS::Value>) [Interpreter.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 606 + 0x7]
13:09:11 INFO - eip = 0x5692402e esp = 0x0026deac ebp = 0x0026deb4 ebx = 0x0026df80
13:09:11 INFO - esi = 0x0026ded8 edi = 0x0026df20
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 44 xul.dll!JS::Call(JSContext *,JS::Handle<JS::Value>,JS::Handle<JS::Value>,JS::HandleValueArray const &,JS::MutableHandle<JS::Value>) [jsapi.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 2647 + 0x14]
13:09:11 INFO - eip = 0x56b8cfa0 esp = 0x0026debc ebp = 0x0026df64 esi = 0x00000001
13:09:11 INFO - edi = 0x0026dfe0
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 45 xul.dll!mozilla::dom::MessageListener::ReceiveMessage(JSContext *,JS::Handle<JS::Value>,mozilla::dom::ReceiveMessageArgument const &,JS::MutableHandle<JS::Value>,mozilla::ErrorResult &) [MessageManagerBinding.cpp: : 7025 + 0xa]
13:09:11 INFO - eip = 0x565fe3f0 esp = 0x0026df6c ebp = 0x0026e034 ebx = 0x0610f800
13:09:11 INFO - esi = 0x0610f834 edi = 0x0026df80
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 46 xul.dll!nsFrameMessageManager::ReceiveMessage(nsISupports *,nsFrameLoader *,bool,nsTSubstring<char16_t> const &,bool,mozilla::dom::ipc::StructuredCloneData *,mozilla::jsipc::CpowHolder *,nsIPrincipal *,nsTArray<mozilla::dom::ipc::StructuredCloneData> *,mozilla::ErrorResult &) [nsFrameMessageManager.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 763 + 0x9e]
13:09:11 INFO - eip = 0x565fc6be esp = 0x0026e03c ebp = 0x0026e2b0 ebx = 0x00000000
13:09:11 INFO - esi = 0x0026e118 edi = 0x0610f834
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 47 xul.dll!class mozilla::ipc::IPCResult mozilla::dom::BrowserChild::RecvAsyncMessage(const class nsTString<char16_t> & const, class nsTArray<mozilla::jsipc::CpowEntry> *, class nsIPrincipal *, const class mozilla::dom::ClonedMessageData & const) [BrowserChild.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 2183 + 0x2d]
13:09:11 INFO - eip = 0x5925e18a esp = 0x0026e2b8 ebp = 0x0026e404 ebx = 0x07fec920
13:09:11 INFO - esi = 0x0026e31c edi = 0x07f6e830
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 48 xul.dll!mozilla::dom::PBrowserChild::OnMessageReceived(IPC::Message const &) [PBrowserChild.cpp: : 3241 + 0x1a]
13:09:11 INFO - eip = 0x57dc9a03 esp = 0x0026e40c ebp = 0x0026e684 ebx = 0x07f6e830
13:09:11 INFO - esi = 0x0026e420 edi = 0x0026e440
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 49 xul.dll!mozilla::dom::PContentChild::OnMessageReceived(IPC::Message const &) [PContentChild.cpp: : 6482 + 0x8]
13:09:11 INFO - eip = 0x57bf6f1f esp = 0x0026e68c ebp = 0x0026ee54 ebx = 0x00c5bc10
13:09:11 INFO - esi = 0x0026e690 edi = 0x09d67828
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 50 xul.dll!void mozilla::ipc::MessageChannel::DispatchMessage(class IPC::Message *) [MessageChannel.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 2078 + 0x50]
13:09:11 INFO - eip = 0x561faa30 esp = 0x0026ee5c ebp = 0x0026ef18 ebx = 0x00000001
13:09:11 INFO - esi = 0x00000000 edi = 0x00c6c120
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 51 xul.dll!nsresult mozilla::ipc::MessageChannel::MessageTask::Run() [MessageChannel.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 1968 + 0x9a]
13:09:11 INFO - eip = 0x561fa5dd esp = 0x0026ef20 ebp = 0x0026ef48 ebx = 0x09d67828
13:09:11 INFO - esi = 0x00c013a0 edi = 0x09d67800
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 52 xul.dll!nsresult mozilla::SchedulerGroup::Runnable::Run() [SchedulerGroup.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 295 + 0x6]
13:09:11 INFO - eip = 0x577d8159 esp = 0x0026ef50 ebp = 0x0026ef5c ebx = 0x00c0b100
13:09:11 INFO - esi = 0x0026ef68 edi = 0x0aff9600
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 53 xul.dll!nsThread::ProcessNextEvent(bool,bool *) [nsThread.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 1180 + 0x9]
13:09:11 INFO - eip = 0x560aa2e9 esp = 0x0026ef64 ebp = 0x0026f43c esi = 0x0026ef68
13:09:11 INFO - edi = 0x00000000
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 54 xul.dll!NS_ProcessNextEvent(nsIThread *,bool) [nsThreadUtils.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 486 + 0x11]
13:09:11 INFO - eip = 0x560a9ddb esp = 0x0026f444 ebp = 0x0026f460 ebx = 0x00c50101
13:09:11 INFO - esi = 0x0026f453 edi = 0x00c012f0
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 55 xul.dll!void mozilla::ipc::MessagePump::Run(class base::MessagePump::Delegate *) [MessagePump.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 88 + 0xa]
13:09:11 INFO - eip = 0x562688a7 esp = 0x0026f468 ebp = 0x0026f488 ebx = 0x00c50101
13:09:11 INFO - esi = 0x00c012e0
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 56 xul.dll!MessageLoop::RunHandler() [message_loop.cc:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 308 + 0x8]
13:09:11 INFO - eip = 0x5607f664 esp = 0x0026f490 ebp = 0x0026f4c0 ebx = 0x00c5bc00
13:09:11 INFO - esi = 0x0026f5a4 edi = 0x00ca53a0
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 57 xul.dll!MessageLoop::Run() [message_loop.cc:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 290 + 0x5]
13:09:11 INFO - eip = 0x560a9a71 esp = 0x0026f4c8 ebp = 0x0026f4e0 ebx = 0x00c5bc00
13:09:11 INFO - esi = 0x00c0b100 edi = 0x00ca53a0
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 58 xul.dll!nsBaseAppShell::Run() [nsBaseAppShell.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 137 + 0xc]
13:09:11 INFO - eip = 0x56268795 esp = 0x0026f4e8 ebp = 0x0026f4f0
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 59 xul.dll!nsAppShell::Run() [nsAppShell.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 412 + 0x6]
13:09:11 INFO - eip = 0x562660f0 esp = 0x0026f4f8 ebp = 0x0026f500 esi = 0x00ca53a0
13:09:11 INFO - edi = 0x00c5bc00
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 60 xul.dll!XRE_RunAppShell() [nsEmbedFunctions.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 919 + 0x6]
13:09:11 INFO - eip = 0x5a255637 esp = 0x0026f508 ebp = 0x0026f518 esi = 0x00c012e0
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 61 xul.dll!mozilla::ipc::MessagePumpForChildProcess::Run(base::MessagePump::Delegate *) [MessagePump.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 238 + 0x5]
13:09:11 INFO - eip = 0x57b68bb8 esp = 0x0026f520 ebp = 0x0026f530 esi = 0x00c012e0
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 62 xul.dll!MessageLoop::RunHandler() [message_loop.cc:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 308 + 0x8]
13:09:11 INFO - eip = 0x5607f664 esp = 0x0026f538 ebp = 0x0026f568 ebx = 0x00c5bc00
13:09:11 INFO - esi = 0x0026f5a4 edi = 0x00c5bc00
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 63 xul.dll!MessageLoop::Run() [message_loop.cc:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 290 + 0x5]
13:09:11 INFO - eip = 0x560a9a71 esp = 0x0026f570 ebp = 0x0026f588 ebx = 0x00c5bc00
13:09:11 INFO - esi = 0x80004005 edi = 0x00c5bc00
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 64 xul.dll!XRE_InitChildProcess(int,char * * const,XREChildData const *) [nsEmbedFunctions.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 757 + 0x5]
13:09:11 INFO - eip = 0x5a25531e esp = 0x0026f590 ebp = 0x0026f6a4
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 65 xul.dll!nsresult mozilla::BootstrapImpl::XRE_InitChildProcess(int, char * *, const struct XREChildData *) [Bootstrap.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 67 + 0xe]
13:09:11 INFO - eip = 0x5a259291 esp = 0x0026f6ac ebp = 0x0026f6b8 ebx = 0x00c03040
13:09:11 INFO - esi = 0x0000001a edi = 0x00c07118
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 66 firefox.exe!static int content_process_main(class mozilla::Bootstrap *, int, char * *) [plugin-container.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 56 + 0xd]
13:09:11 INFO - eip = 0x00f914bc esp = 0x0026f6c0 ebp = 0x0026f6e8
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 67 firefox.exe!static int NS_internal_main(int, char * *, char * *) [nsBrowserApp.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 263 + 0xa]
13:09:11 INFO - eip = 0x00f91411 esp = 0x0026f6f0 ebp = 0x0026f754 ebx = 0x00c030b0
13:09:11 INFO - esi = 0x0000001b edi = 0x00c03040
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 68 firefox.exe!wmain [nsWindowsWMain.cpp:39af0d6ccd3d911cae7d0ddadb0b0baae979046d : 131 + 0x12]
13:09:11 INFO - eip = 0x00f910e2 esp = 0x0026f75c ebp = 0x0026f778 ebx = 0x00c030b0
13:09:11 INFO - esi = 0x0000007f edi = 0x00c03040
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 69 firefox.exe!static int __scrt_common_main_seh() [exe_common.inl : 288 + 0x1c]
13:09:11 INFO - eip = 0x00fcad6c esp = 0x0026f780 ebp = 0x0026f7c0 ebx = 0x7ffda000
13:09:11 INFO - esi = 0x6da9f0b0 edi = 0x005d3258
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 70 kernel32.dll!BemFreeContract + 0x135b9
13:09:11 INFO - eip = 0x76413c45 esp = 0x0026f7c8 ebp = 0x0026f7cc
13:09:11 INFO - Found by: call frame info
13:09:11 INFO - 71 ntdll.dll!BemFreeContract + 0x23169
13:09:11 INFO - eip = 0x778937f5 esp = 0x0026f7d4 ebp = 0x0026f80c
13:09:11 INFO - Found by: previous frame's frame pointer
13:09:11 INFO - 72 ntdll.dll!BemFreeContract + 0x2313c
13:09:11 INFO - eip = 0x778937c8 esp = 0x0026f814 ebp = 0x0026f824
13:09:11 INFO - Found by: previous frame's frame pointer
13:09:11 INFO -
13:09:11 INFO - Thread 1

Summary: Intermittent file:///Z:/task_1556882394/build/tests/jsreftest/tests/jsreftest.html?test=test262<random path> | application crashed [@ mozalloc_abort] → Intermittent Windows 7 Js Reftest /build/tests/jsreftest/tests/jsreftest.html?test=test262<random path> | application crashed [@ mozalloc_abort]

Could you please take a look at this bug?

Also, this the the earliest occurrence I could find : https://treeherder.mozilla.org/#/jobs?repo=mozilla-beta&revision=31a6f6acd33fe33e331688cdf7c5ac490e4aa122&selectedJob=240543866

Flags: needinfo?(jcoppeard)

This an OOM during cycle collection. I'm not sure what we can do for this specifically.

Component: JavaScript Engine → XPCOM
Flags: needinfo?(jcoppeard) → needinfo?(continuation)

Comment 0 has this line in it:
13:08:54 INFO - out of memory: 0x0000000000010000 bytes requested

We're OOMing because the JS reftest is running out of memory.

Olli, could your CC scheduling changes have caused this?

It might also be something on the JS engine side. I haven't seen if we've had a lot of other OOMs crop up yet, but I haven't looked at all of my bugmail yet.

Flags: needinfo?(continuation) → needinfo?(bugs)

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

Oh, I meant to talk to you about this before. I saw that there was a large build up of live windows when running the JS reftests and I looked into whether we were failing to trigger GC. I found that GC was running but was not collecting anything. I tried putting in extra CCs but this didn't help. I came to the conclusion that we are somehow leaking all these windows until the end of the test. This could explain why we are getting these OOMs.

For example, this run creates a window for every test and then frees 222 in one go right at the end: https://taskcluster-artifacts.net/LCwkJJLeSLmavDqzI533jw/0/public/logs/live_backing.log

That is possible. Thinking what to do here...

that was answer to mccr8.

But if doing extra CCs doesn't help, I wonder what the issue is.
Did you ensure CC actually got run?

Flags: needinfo?(bugs)

Perhaps we should let CC to run always after GC, and limit only consecutive CC runs a bit.

(In reply to Jon Coppeard (:jonco) from comment #9)

Oh, I meant to talk to you about this before. I saw that there was a large build up of live windows when running the JS reftests and I looked into whether we were failing to trigger GC. I found that GC was running but was not collecting anything. I tried putting in extra CCs but this didn't help. I came to the conclusion that we are somehow leaking all these windows until the end of the test. This could explain why we are getting these OOMs.

It is possible that the test harness is leaking windows. Like maybe the main page of the test opens iframes, and somehow keeps references to them. There was an issue like this in some other test framework a while ago. To investigate this, you'd need to do something like get GC/CC logs from while the test is running, and see what is holding alive a window that you'd have expected to be cleaned up already.

Pushed by opettay@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/96d4edaabc71
let CC to run sooner after GC, r=mccr8

FWIW, that patch is a guess fix for this. At least it lets us CC more often if GC is busy too.

https://hg.mozilla.org/mozilla-central/rev/96d4edaabc71

Status: NEW → RESOLVED
Closed: 5 years ago
status-firefox68: --- → fixed
Resolution: --- → FIXED
Target Milestone: --- → mozilla68
Assignee: nobody → bugs

Based on comment 6, my recent changes shouldn't have caused this. But I still wonder if the patch in this bug helps.

(telemetry tells that CC scheduling works now as I expected. 5% CYCLE_COLLECTOR_TIME_BETWEEN went recently to 6ms, but change here dropped it to 4ms, because of NS_GC_DELAY being 4ms)

Regressions: 1550433

Seems like there hasn't been intermittent failure reports here for awhile.

(doing some end-of-the-year clearance on really old needinfos)

Flags: needinfo?(bugs)
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: