Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_quit_restart.py TestQuitRestart.test_in_app_restart_with_callback | application crashed [@ js::GCMarker::processMarkStackTop(js::SliceBudget&)]
Categories
(Core :: JavaScript: GC, defect, P5)
Tracking
()
People
(Reporter: intermittent-bug-filer, Unassigned)
References
(Blocks 1 open bug)
Details
(Keywords: crash, intermittent-failure, regression)
Crash Data
Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=258539679&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/M4OU_4r5TVyXQNMv1sOURA/runs/0/artifacts/public/logs/live_backing.log
[task 2019-07-26T18:55:07.685Z] 18:55:07 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_quit_restart.py TestQuitRestart.test_in_app_restart_with_callback
[task 2019-07-26T18:55:07.685Z] 18:55:07 INFO - 1564167307674 Marionette DEBUG 1 -> [0,6,"WebDriver:DeleteSession",{}]
[task 2019-07-26T18:55:07.686Z] 18:55:07 INFO - 1564167307676 Marionette DEBUG 1 <- [1,6,null,{"value":null}]
[task 2019-07-26T18:55:07.731Z] 18:55:07 INFO - 1564167307684 Marionette DEBUG Closed connection 1
[task 2019-07-26T18:55:07.731Z] 18:55:07 INFO - 1564167307684 Marionette DEBUG Accepted connection 2 from 127.0.0.1:52579
[task 2019-07-26T18:55:07.731Z] 18:55:07 INFO - 1564167307686 Marionette DEBUG 2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2019-07-26T18:55:07.731Z] 18:55:07 INFO - 1564167307688 Marionette TRACE [4294967297] Frame script loaded
[task 2019-07-26T18:55:07.732Z] 18:55:07 INFO - 1564167307689 Marionette TRACE [4294967297] Frame script registered
[task 2019-07-26T18:55:07.732Z] 18:55:07 INFO - 1564167307691 Marionette DEBUG 2 <- [1,1,null,{"sessionId":"1308dd26-ca5a-1546-ba41-f5a278327f4b","capabilities":{"browserName":"firefox","browserVersion":"70.0a ... /T/tmpmiGDDH.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2019-07-26T18:55:07.732Z] 18:55:07 INFO - 1564167307692 Marionette DEBUG 2 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2019-07-26T18:55:07.732Z] 18:55:07 INFO - 1564167307692 Marionette DEBUG 2 <- [1,2,null,{"value":null}]
[task 2019-07-26T18:55:07.732Z] 18:55:07 INFO - 1564167307693 Marionette DEBUG 2 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2019-07-26T18:55:07.732Z] 18:55:07 INFO - 1564167307694 Marionette DEBUG 2 <- [1,3,null,{"value":null}]
[task 2019-07-26T18:55:07.732Z] 18:55:07 INFO - 1564167307695 Marionette DEBUG 2 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2019-07-26T18:55:07.733Z] 18:55:07 INFO - 1564167307696 Marionette DEBUG 2 <- [1,4,null,{"value":null}]
[task 2019-07-26T18:55:07.733Z] 18:55:07 INFO - 1564167307697 Marionette DEBUG 2 -> [0,5,"Marionette:GetContext",{}]
[task 2019-07-26T18:55:07.733Z] 18:55:07 INFO - 1564167307698 Marionette DEBUG 2 <- [1,5,null,{"value":"content"}]
[task 2019-07-26T18:55:07.733Z] 18:55:07 INFO - 1564167307698 Marionette DEBUG 2 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-07-26T18:55:07.733Z] 18:55:07 INFO - 1564167307699 Marionette DEBUG 2 <- [1,6,null,{"value":null}]
[task 2019-07-26T18:55:07.733Z] 18:55:07 INFO - 1564167307700 Marionette DEBUG 2 -> [0,7,"WebDriver:ExecuteScript",{"script":"Components.utils.import("resource://gre/modules/Preferences.jsm");\n\n ... cified"],"filename":"../../venv/lib/python2.7/site-packages/marionette_driver/marionette.py","sandbox":"default","line":732}]
[task 2019-07-26T18:55:07.733Z] 18:55:07 INFO - 1564167307702 Marionette DEBUG 2 <- [1,7,null,{"value":"https://www.mozilla.org/projects/firefox/%VERSION%/firstrun/"}]
[task 2019-07-26T18:55:07.733Z] 18:55:07 INFO - 1564167307703 Marionette DEBUG 2 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2019-07-26T18:55:07.734Z] 18:55:07 INFO - 1564167307704 Marionette DEBUG 2 <- [1,8,null,{"value":null}]
[task 2019-07-26T18:55:07.734Z] 18:55:07 INFO - 1564167307705 Marionette DEBUG 2 -> [0,9,"Marionette:GetContext",{}]
[task 2019-07-26T18:55:07.734Z] 18:55:07 INFO - 1564167307705 Marionette DEBUG 2 <- [1,9,null,{"value":"content"}]
[task 2019-07-26T18:55:07.734Z] 18:55:07 INFO - 1564167307705 Marionette DEBUG 2 -> [0,10,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-07-26T18:55:07.734Z] 18:55:07 INFO - 1564167307706 Marionette DEBUG 2 <- [1,10,null,{"value":null}]
[task 2019-07-26T18:55:07.734Z] 18:55:07 INFO - 1564167307707 Marionette DEBUG 2 -> [0,11,"WebDriver:ExecuteScript",{"script":"Components.utils.import("resource://gre/modules/Preferences.jsm");\n\n ... ",false],"filename":"../../venv/lib/python2.7/site-packages/marionette_driver/marionette.py","sandbox":"default","line":766}]
[task 2019-07-26T18:55:07.734Z] 18:55:07 INFO - 1564167307709 Marionette DEBUG 2 <- [1,11,null,{"value":null}]
[task 2019-07-26T18:55:07.734Z] 18:55:07 INFO - 1564167307709 Marionette DEBUG 2 -> [0,12,"Marionette:SetContext",{"value":"content"}]
[task 2019-07-26T18:55:07.734Z] 18:55:07 INFO - 1564167307710 Marionette DEBUG 2 <- [1,12,null,{"value":null}]
[task 2019-07-26T18:55:07.734Z] 18:55:07 INFO - 1564167307711 Marionette DEBUG 2 -> [0,13,"Marionette:GetContext",{}]
[task 2019-07-26T18:55:07.735Z] 18:55:07 INFO - 1564167307712 Marionette DEBUG 2 <- [1,13,null,{"value":"content"}]
[task 2019-07-26T18:55:07.735Z] 18:55:07 INFO - 1564167307713 Marionette DEBUG 2 -> [0,14,"Marionette:AcceptConnections",{"value":false}]
[task 2019-07-26T18:55:07.735Z] 18:55:07 INFO - 1564167307713 Marionette INFO Stopped listening on port 2828
[task 2019-07-26T18:56:28.509Z] 18:56:28 INFO - PROCESS-CRASH | testing/marionette/harness/marionette_harness/tests/unit/test_quit_restart.py TestQuitRestart.test_in_app_restart_with_callback | application crashed [@ js::GCMarker::processMarkStackTop(js::SliceBudget&)]
[task 2019-07-26T18:56:28.509Z] 18:56:28 INFO - Crash dump filename: /var/folders/4z/gmjb5mfn7m15rdy406rr641h000017/T/tmpmiGDDH.mozrunner/minidumps/462C1A65-4532-4F97-A06D-A9E710A2D3BA.dmp
[task 2019-07-26T18:56:28.509Z] 18:56:28 INFO - Operating system: Mac OS X
[task 2019-07-26T18:56:28.509Z] 18:56:28 INFO - 10.14.5 18F132
[task 2019-07-26T18:56:28.509Z] 18:56:28 INFO - CPU: amd64
[task 2019-07-26T18:56:28.509Z] 18:56:28 INFO - family 6 model 69 stepping 1
[task 2019-07-26T18:56:28.509Z] 18:56:28 INFO - 4 CPUs
[task 2019-07-26T18:56:28.509Z] 18:56:28 INFO -
[task 2019-07-26T18:56:28.509Z] 18:56:28 INFO - GPU: UNKNOWN
[task 2019-07-26T18:56:28.509Z] 18:56:28 INFO -
[task 2019-07-26T18:56:28.510Z] 18:56:28 INFO - Crash reason: EXC_BAD_ACCESS / EXC_I386_GPFLT
[task 2019-07-26T18:56:28.510Z] 18:56:28 INFO - Crash address: 0x0
[task 2019-07-26T18:56:28.510Z] 18:56:28 INFO - Process uptime: 7 seconds
[task 2019-07-26T18:56:28.510Z] 18:56:28 INFO -
[task 2019-07-26T18:56:28.510Z] 18:56:28 INFO - Thread 39 (crashed)
[task 2019-07-26T18:56:28.510Z] 18:56:28 INFO - 0 XUL!js::GCMarker::processMarkStackTop(js::SliceBudget&) [HeapAPI.h:8cf07d8ce4ea82efd09f88300f818f318b05da8e : 478 + 0x0]
[task 2019-07-26T18:56:28.510Z] 18:56:28 INFO - rax = 0x00014a850fcfffe8 rdx = 0x00014a850fc00000
[task 2019-07-26T18:56:28.510Z] 18:56:28 INFO - rcx = 0xfffdffffffffffff rbx = 0x0000000000000000
[task 2019-07-26T18:56:28.510Z] 18:56:28 INFO - rsi = 0x0000000000000006 rdi = 0x000016dc5fe27280
[task 2019-07-26T18:56:28.510Z] 18:56:28 INFO - rbp = 0x0000700005a01190 rsp = 0x0000700005a01130
[task 2019-07-26T18:56:28.510Z] 18:56:28 INFO - r8 = 0x0000100000000001 r9 = 0x0000700005a015d0
[task 2019-07-26T18:56:28.510Z] 18:56:28 INFO - r10 = 0x000000012662d180 r11 = 0xfffe000000000000
[task 2019-07-26T18:56:28.510Z] 18:56:28 INFO - r12 = 0x000000010b098e10 r13 = 0x00000006fd151158
[task 2019-07-26T18:56:28.510Z] 18:56:28 INFO - r14 = 0x00014a850fc08548 r15 = 0xfffb000000000000
[task 2019-07-26T18:56:28.510Z] 18:56:28 INFO - rip = 0x000000010a6ea358
[task 2019-07-26T18:56:28.510Z] 18:56:28 INFO - Found by: given as instruction pointer in context
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - 1 XUL!js::GCMarker::markUntilBudgetExhausted(js::SliceBudget&) [Marking.cpp:8cf07d8ce4ea82efd09f88300f818f318b05da8e : 1627 + 0xb]
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - rbp = 0x0000700005a011e0 rsp = 0x0000700005a011a0
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - rip = 0x000000010a6d250b
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - 2 XUL!js::gc::GCRuntime::markGrayReferencesInCurrentGroup(js::FreeOp*, js::SliceBudget&) [GC.cpp:8cf07d8ce4ea82efd09f88300f818f318b05da8e : 5588 + 0x29]
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - rbp = 0x0000700005a01250 rsp = 0x0000700005a011f0
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - rip = 0x000000010a6cc6e5
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - 3 XUL!sweepaction::SweepActionSequence::run(js::gc::SweepAction::Args&) [GC.cpp:8cf07d8ce4ea82efd09f88300f818f318b05da8e : 6592 + 0x9]
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - rbp = 0x0000700005a01280 rsp = 0x0000700005a01260
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - rip = 0x000000010a6f96ac
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - 4 XUL!sweepaction::SweepActionForEach<js::gc::SweepGroupsIter, JSRuntime*>::run(js::gc::SweepAction::Args&) [GC.cpp:8cf07d8ce4ea82efd09f88300f818f318b05da8e : 6627 + 0xd]
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - rbp = 0x0000700005a012f0 rsp = 0x0000700005a01290
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - rip = 0x000000010a6f432d
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - 5 XUL!js::gc::GCRuntime::incrementalSlice(js::SliceBudget&, mozilla::Maybe<JSGCInvocationKind> const&, JS::GCReason, js::gc::AutoGCSession&) [GC.cpp:8cf07d8ce4ea82efd09f88300f818f318b05da8e : 7293 + 0x14d]
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - rbp = 0x0000700005a01530 rsp = 0x0000700005a01300
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - rip = 0x000000010a6da272
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - 6 XUL!js::gc::GCRuntime::gcCycle(bool, js::SliceBudget, mozilla::Maybe<JSGCInvocationKind> const&, JS::GCReason) [GC.cpp:8cf07d8ce4ea82efd09f88300f818f318b05da8e : 7674 + 0x11]
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - rbp = 0x0000700005a015c0 rsp = 0x0000700005a01540
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - rip = 0x000000010a6df370
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - 7 XUL!js::gc::GCRuntime::collect(bool, js::SliceBudget, mozilla::Maybe<JSGCInvocationKind> const&, JS::GCReason) [GC.cpp:8cf07d8ce4ea82efd09f88300f818f318b05da8e : 7858 + 0x4c]
[task 2019-07-26T18:56:28.511Z] 18:56:28 INFO - rbp = 0x0000700005a016a0 rsp = 0x0000700005a015d0
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - rip = 0x000000010a6dfb9a
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - 8 XUL!JS_GC(JSContext*, JS::GCReason) [jsapi.cpp:8cf07d8ce4ea82efd09f88300f818f318b05da8e : 1206 + 0x62]
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - rbp = 0x0000700005a01710 rsp = 0x0000700005a016b0
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - rip = 0x000000010a4f4487
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - 9 XUL!mozilla::dom::workerinternals::(anonymous namespace)::WorkerThreadPrimaryRunnable::Run() [RuntimeService.cpp:8cf07d8ce4ea82efd09f88300f818f318b05da8e : 2353 + 0xd]
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - rbp = 0x0000700005a018f0 rsp = 0x0000700005a01720
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - rip = 0x00000001086819b0
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - 10 XUL!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:8cf07d8ce4ea82efd09f88300f818f318b05da8e : 1224 + 0x6]
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - rbp = 0x0000700005a01de0 rsp = 0x0000700005a01900
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - rip = 0x00000001056304f3
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - 11 XUL!NS_ProcessNextEvent(nsIThread*, bool) [nsThreadUtils.cpp:8cf07d8ce4ea82efd09f88300f818f318b05da8e : 486 + 0xd]
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - rbp = 0x0000700005a01e10 rsp = 0x0000700005a01df0
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - rip = 0x0000000105632ef9
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - 12 XUL!mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) [MessagePump.cpp:8cf07d8ce4ea82efd09f88300f818f318b05da8e : 333 + 0xd]
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - rbp = 0x0000700005a01e60 rsp = 0x0000700005a01e20
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - rip = 0x0000000105d7358a
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - 13 XUL!nsThread::ThreadFunc(void*) [nsThread.cpp:8cf07d8ce4ea82efd09f88300f818f318b05da8e : 458 + 0x3c]
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - rbp = 0x0000700005a01ec0 rsp = 0x0000700005a01e70
[task 2019-07-26T18:56:28.512Z] 18:56:28 INFO - rip = 0x000000010562d8a2
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - 14 libnss3.dylib!_pt_root [ptthread.c:8cf07d8ce4ea82efd09f88300f818f318b05da8e : 198 + 0x8]
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - rbp = 0x0000700005a01f10 rsp = 0x0000700005a01ed0
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - rip = 0x000000010458e745
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - 15 libsystem_pthread.dylib!_pthread_body + 0x7e
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - rbp = 0x0000700005a01f30 rsp = 0x0000700005a01f20
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - rip = 0x00007fff705462eb
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - 16 libsystem_pthread.dylib!_pthread_start + 0x42
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - rbp = 0x0000700005a01f50 rsp = 0x0000700005a01f40
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - rip = 0x00007fff70549249
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - 17 libsystem_pthread.dylib!thread_start + 0xd
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - rbp = 0x0000700005a01f78 rsp = 0x0000700005a01f60
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - rip = 0x00007fff7054540d
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - Found by: previous frame's frame pointer
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - 18 libnss3.dylib + 0x18e5e0
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - rsp = 0x0000700005a02090 rip = 0x000000010458e5e0
[task 2019-07-26T18:56:28.513Z] 18:56:28 INFO - Found by: stack scanning
|
||
Updated•5 years ago
|
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•5 years ago
|
||
The priority flag is not set for this bug.
:jonco, could you have a look please?
For more information, please visit auto_nag documentation.
|
||
Comment 3•5 years ago
|
||
Only happened once, marking P5 for now.
|
||
Updated•5 years ago
|
Description
•