Closed Bug 1235803 Opened 8 years ago Closed 8 years ago

Intermittent crash test_animation-computed-timing.html,test_bug541668_table_event_delivery.html,test_locale_aware_indexes.html,test_viewport.html,test_animation-currenttime.html | application crashed [@ mozilla::gfx::VRManagerParent::DeferredDestroy]

Categories

(Core :: Graphics, defect)

defect
Not set
normal

Tracking

()

RESOLVED FIXED
mozilla46
Tracking Status
firefox46 --- fixed

People

(Reporter: aryx, Assigned: kip)

References

Details

(Keywords: crash, intermittent-failure)

Attachments

(1 file)

https://treeherder.mozilla.org/logviewer.html#?job_id=19107644&repo=mozilla-inbound B2G ICS Emulator debug

03:55:06     INFO -  257 INFO TEST-START | dom/html/test/test_viewport.html
03:55:10     INFO -  MEMORY STAT | vsize 158MB | residentFast 71MB | heapAllocated 24MB
03:55:10     INFO -  258 INFO TEST-OK | dom/html/test/test_viewport.html | took 4785ms
03:55:17     INFO -  -*- SettingsManager: Received: inner-window-destroyed for valid innerWindowID=2, cleanup.
03:55:17     INFO -  [Parent 789] WARNING: An event was posted to a thread that will never run it (rejected): file /builds/slave/b2g_m-in_emu-d_dep-00000000000/build/gecko/xpcom/threads/nsThread.cpp, line 578
03:55:17     INFO -  [Parent 789] WARNING: unable to post continuation event: file /builds/slave/b2g_m-in_emu-d_dep-00000000000/build/gecko/xpcom/io/nsStreamUtils.cpp, line 479
03:55:18     INFO -  1451476518116	Marionette	INFO	Closed connection conn1
03:55:18     INFO -  AlarmService: observe(): profile-change-teardown
03:55:18     INFO -  AlarmService: uninit()
03:55:36     INFO -  [Parent 789] WARNING: Unexpected timer!: file /builds/slave/b2g_m-in_emu-d_dep-00000000000/build/gecko/dom/ipc/ProcessPriorityManager.cpp, line 1033
03:55:36     INFO -  [Child 932] WARNING: NS_ENSURE_TRUE(context) failed: file /builds/slave/b2g_m-in_emu-d_dep-00000000000/build/gecko/xpcom/threads/nsThread.cpp, line 794
03:55:36     INFO -  [Child 804] WARNING: content process _exit()ing: file ../../../gecko/dom/ipc/ContentChild.cpp, line 2252
03:55:37     INFO -  [Parent 789] WARNING: Unable to terminate process.: file /builds/slave/b2g_m-in_emu-d_dep-00000000000/build/gecko/ipc/chromium/src/base/process_util_posix.cc, line 92
03:55:37     INFO -  [Parent 789] WARNING: failed to kill subprocess!: file /builds/slave/b2g_m-in_emu-d_dep-00000000000/build/gecko/dom/ipc/ContentParent.cpp, line 3705
03:55:37     INFO -  [Parent 789] WARNING: waitpid failed pid:804 errno:10: file /builds/slave/b2g_m-in_emu-d_dep-00000000000/build/gecko/ipc/chromium/src/base/process_util_posix.cc, line 267
03:55:38     INFO -  [Parent 789] WARNING: waitpid failed pid:804 errno:10: file /builds/slave/b2g_m-in_emu-d_dep-00000000000/build/gecko/ipc/chromium/src/base/process_util_posix.cc, line 267
03:55:48     INFO -  [Parent 789] WARNING: '!IsOpen()', file ../../../gecko/ipc/unixfd/UnixFdWatcher.cpp, line 36
03:55:49     INFO -  [Parent 789] WARNING: 'aRv.Failed()', file /builds/slave/b2g_m-in_emu-d_dep-00000000000/build/gecko/dom/indexedDB/IDBDatabase.cpp, line 643
03:55:49     INFO -  JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code.
03:55:54     INFO -  [Child 894] WARNING: MsgDropped in ContentChild: file ../../../gecko/dom/ipc/ContentChild.cpp, line 2221
03:55:54     INFO -  [Child 894] WARNING: MsgDropped in ContentChild: file ../../../gecko/dom/ipc/ContentChild.cpp, line 2221
03:55:54     INFO -  Assertion failed at ../../../../../gecko/gfx/cairo/cairo/src/cairo-ft-font.c:444: unscaled->face == NULL
03:55:54     INFO -  WARNING: YOU ARE LEAKING THE WORLD (at least one JSRuntime and everything alive inside it, that is) AT JS_ShutDown TIME.  FIX THIS!
03:55:54     INFO -  [Child 932] WARNING: Exiting child process early!: file /builds/slave/b2g_m-in_emu-d_dep-00000000000/build/gecko/xpcom/build/XPCOMInit.cpp, line 1063
03:55:54     INFO -  Leaked URLs:
03:55:54     INFO -    file:///system/b2g/omni.ja
03:55:54     INFO -    resource://gre-resources/counterstyles.css
03:55:54     INFO -    resource://gre-resources/html.css
03:55:54     INFO -    chrome://global/content/minimal-xul.css
03:55:54     INFO -    resource://gre-resources/quirk.css
03:55:54     INFO -    resource://gre/res/svg.css
03:55:54     INFO -    resource://gre-resources/ua.css
03:55:54     INFO -    chrome://global/content/xul.css
03:55:54     INFO -    chrome://b2g/content/content.css
03:55:54     INFO -    chrome://pluginproblem/content/pluginFinderBinding.css
03:55:54     INFO -    chrome://pluginproblem/content/pluginProblemBinding.css
03:55:54     INFO -    chrome://global/skin/scrollbars.css
03:55:54     INFO -    resource://gre-resources/number-control.css
03:55:54     INFO -    resource://gre-resources/forms.css
03:55:54     INFO -    resource://gre-resources/noscript.css
03:55:54     INFO -    chrome://global/content/bindings/scrollbar.xml#scrollbar
03:55:54     INFO -    chrome://global/content/bindings/scrollbar.xml
03:55:54     INFO -    x:///chrome/toolkit/content/global/bindings/scrollbar.xml
03:55:54     INFO -    chrome://global/content/bindings/scrollbar.xml
03:55:54     INFO -    chrome://global/content/bindings/scrollbar.xml#thumb
03:55:54     INFO -    chrome://global/content/bindings/scrollbar.xml
03:55:54     INFO -    chrome://global/content/bindings/scrollbar.xml#scrollbar-base
03:55:54     INFO -    chrome://global/content/bindings/scrollbar.xml#scrollbar
03:55:54     INFO -    chrome://global/content/bindings/scrollbar.xml#scrollbar-base
03:55:54     INFO -    chrome://global/content/bindings/scrollbar.xml#thumb
03:55:54     INFO -    chrome://global/content/bindings/scrollbar.xml#scrollbar-base
03:55:54     INFO -  nsStringStats
03:55:54     INFO -   => mAllocCount:          28313
03:55:54     INFO -   => mReallocCount:        10166
03:55:54     INFO -   => mFreeCount:           25519  --  LEAKED 2794 !!!
03:55:54     INFO -   => mShareCount:          15959
03:55:54     INFO -   => mAdoptCount:            729
03:55:54     INFO -   => mAdoptFreeCount:        729
03:55:54     INFO -   => Process ID: 932, Thread ID: 1074488428
03:55:55     INFO -  [Child 894] WARNING: NS_ENSURE_TRUE(context) failed: file /builds/slave/b2g_m-in_emu-d_dep-00000000000/build/gecko/xpcom/threads/nsThread.cpp, line 794
03:55:57     INFO -  Segmentation fault
03:56:11  WARNING -  PROCESS-CRASH | dom/html/test/test_viewport.html | application crashed [@ mozilla::gfx::VRManagerParent::DeferredDestroy]
03:56:11     INFO -  Crash dump filename: /tmp/tmpZMihCx/2943739e-7c6f-7e06-702172fc-272c6797.dmp
03:56:11     INFO -  Operating system: Android
03:56:11     INFO -                    0.0.0 Linux 2.6.29-g41a03df #22 Thu Jun 26 10:59:09 CST 2014 armv7l Android/full/generic:4.0.4.0.4.0.4/OPENMASTER/eng.cltbld.20151230.042457:eng/test-keys
03:56:11     INFO -  CPU: arm
03:56:11     INFO -       ARMv0
03:56:11     INFO -       0 CPUs
03:56:11     INFO -  Crash reason:  SIGSEGV
03:56:11     INFO -  Crash address: 0x0
03:56:11     INFO -  Process uptime: not available
03:56:11     INFO -  Thread 18 (crashed)
03:56:11     INFO -   0  libxul.so!mozilla::gfx::VRManagerParent::DeferredDestroy [VRManagerParent.cpp:cb7ddeee6364 : 109 + 0x1a]
03:56:11     INFO -       r0 = 0x43043f6b    r1 = 0x489ff888    r2 = 0x0257ea93    r3 = 0x0000006d
03:56:11     INFO -       r4 = 0x47f241c0    r5 = 0x00000000    r6 = 0x4546f8f0    r7 = 0x00000001
03:56:11     INFO -       r8 = 0x489ffdfc    r9 = 0x42f3b152   r10 = 0x48364730   r12 = 0x4234e099
03:56:11     INFO -       fp = 0x00000001    sp = 0x489ffce8    lr = 0x412906ad    pc = 0x412906ae
03:56:11     INFO -      Found by: given as instruction pointer in context
03:56:11     INFO -   1  libxul.so!RunnableMethod<FdWatcher, void (FdWatcher::*)(), mozilla::Tuple<> >::Run [task.h:cb7ddeee6364 : 28 + 0x13]
03:56:11     INFO -       r4 = 0x4029bd80    r5 = 0x489ffdf0    r6 = 0x4546f8f0    r7 = 0x00000001
03:56:11     INFO -       r8 = 0x489ffdfc    r9 = 0x42f3b152   r10 = 0x48364730    fp = 0x00000001
03:56:11     INFO -       sp = 0x489ffcf8    pc = 0x40bd8dd7
03:56:11     INFO -      Found by: call frame info
03:56:11     INFO -   2  libxul.so!MessageLoop::RunTask [message_loop.cc:cb7ddeee6364 : 364 + 0x7]
03:56:11     INFO -       r3 = 0x40bd8db9    r4 = 0x4029bd80    r5 = 0x489ffdf0    r6 = 0x4546f8f0
03:56:11     INFO -       r7 = 0x00000001    r8 = 0x489ffdfc    r9 = 0x42f3b152   r10 = 0x48364730
03:56:11     INFO -       fp = 0x00000001    sp = 0x489ffd00    pc = 0x40e0aba5
03:56:11     INFO -      Found by: call frame info
03:56:11     INFO -   3  libxul.so!MessageLoop::DeferOrRunPendingTask [message_loop.cc:cb7ddeee6364 : 372 + 0x3]
03:56:11     INFO -       r4 = 0x00000001    r5 = 0x489ffd40    r6 = 0x4546f8f0    r7 = 0x00000001
03:56:11     INFO -       r8 = 0x489ffdfc    r9 = 0x42f3b152   r10 = 0x48364730    fp = 0x00000001
03:56:11     INFO -       sp = 0x489ffd20    pc = 0x40e0deb7
03:56:11     INFO -      Found by: call frame info
03:56:11     INFO -   4  libxul.so!MessageLoop::DoWork [message_loop.cc:cb7ddeee6364 : 459 + 0x3]
03:56:11     INFO -       r3 = 0x00000000    r4 = 0x489ffdf0    r5 = 0x489ffd40    r6 = 0x4546f8f0
03:56:11     INFO -       r7 = 0x00000001    r8 = 0x489ffdfc    r9 = 0x42f3b152   r10 = 0x48364730
03:56:11     INFO -       fp = 0x00000001    sp = 0x489ffd30    pc = 0x40e0fd79
03:56:11     INFO -      Found by: call frame info
03:56:11     INFO -   5  libxul.so!base::MessagePumpDefault::Run [message_pump_default.cc:cb7ddeee6364 : 34 + 0x7]
03:56:11     INFO -       r4 = 0x48364720    r5 = 0x489ffd68    r6 = 0x489ffdf0    r7 = 0x48383e94
03:56:11     INFO -       r8 = 0x42f3b152    r9 = 0x42f3b152   r10 = 0x48364730    fp = 0x00000001
03:56:11     INFO -       sp = 0x489ffd60    pc = 0x40e0b4a9
03:56:11     INFO -      Found by: call frame info
03:56:11     INFO -   6  libxul.so!MessageLoop::RunInternal [message_loop.cc:cb7ddeee6364 : 234 + 0xf]
03:56:11     INFO -       r4 = 0x489ffdf0    r5 = 0x489ffdf0    r6 = 0x48383e80    r7 = 0x48383e94
03:56:11     INFO -       r8 = 0x40e0fda1    r9 = 0x48383e80   r10 = 0x00100000    fp = 0x00000001
03:56:11     INFO -       sp = 0x489ffda8    pc = 0x40e0b3fd
03:56:11     INFO -      Found by: call frame info
03:56:11     INFO -   7  libxul.so!MessageLoop::Run [message_loop.cc:cb7ddeee6364 : 227 + 0x5]
03:56:11     INFO -       r0 = 0x489ffdf0    r1 = 0x489ffdf0    r2 = 0x00000000    r3 = 0x00000000
03:56:11     INFO -       r4 = 0x489ffdf0    r5 = 0x489ffdf0    r6 = 0x48383e80    r7 = 0x48383e94
03:56:12     INFO -       r8 = 0x40e0fda1    r9 = 0x48383e80   r10 = 0x00100000    fp = 0x00000001
03:56:12     INFO -       sp = 0x489ffdc0    pc = 0x40e0b417
03:56:12     INFO -      Found by: call frame info
03:56:12     INFO -   8  libxul.so!base::Thread::ThreadMain [thread.cc:cb7ddeee6364 : 172 + 0x5]
03:56:12     INFO -       r0 = 0x00000001    r1 = 0x400af300    r2 = 0x489ffdf0    r3 = 0x00000000
03:56:12     INFO -       r4 = 0x48383e80    r5 = 0x489ffdf0    r6 = 0x48383e80    r7 = 0x48383e94
03:56:12     INFO -       r8 = 0x40e0fda1    r9 = 0x48383e80   r10 = 0x00100000    fp = 0x00000001
03:56:12     INFO -       sp = 0x489ffdd8    pc = 0x40e11e2d
03:56:12     INFO -      Found by: call frame info
03:56:12     INFO -   9  libxul.so!ThreadFunc [platform_thread_posix.cc:cb7ddeee6364 : 36 + 0x5]
03:56:12     INFO -       r4 = 0x489fff00    r5 = 0x40e0fda1    r6 = 0x48383e80    r7 = 0x00000078
03:56:12     INFO -       r8 = 0x40e0fda1    r9 = 0x48383e80   r10 = 0x00100000    fp = 0x00000001
03:56:12     INFO -       sp = 0x489ffee8    pc = 0x40e0fda9
03:56:12     INFO -      Found by: call frame info
03:56:12     INFO -  10  libc.so!__thread_entry [pthread.c : 217 + 0x6]
03:56:12     INFO -       r3 = 0x00000040    r4 = 0x489fff00    r5 = 0x40e0fda1    r6 = 0x48383e80
03:56:12     INFO -       r7 = 0x00000078    r8 = 0x40e0fda1    r9 = 0x48383e80   r10 = 0x00100000
03:56:12     INFO -       fp = 0x00000001    sp = 0x489ffef0    pc = 0x40079e2c
03:56:12     INFO -      Found by: call frame info
03:56:12     INFO -  11  libc.so!pthread_create [pthread.c : 357 + 0xe]
03:56:12     INFO -       r4 = 0x489fff00    r5 = 0x00034450    r6 = 0xbef973a8    r7 = 0x00000078
03:56:12     INFO -       r8 = 0x40e0fda1    r9 = 0x48383e80   r10 = 0x00100000    fp = 0x00000001
03:56:12     INFO -       sp = 0x489fff00    pc = 0x4007997c
03:56:12     INFO -      Found by: call frame info
Summary: Intermittent crash test_viewport.html | application crashed [@ mozilla::gfx::VRManagerParent::DeferredDestroy] → Intermittent crash test_animation-computed-timing.html, test_bug541668_table_event_delivery.html, test_locale_aware_indexes.html, test_viewport.html | application crashed [@ mozilla::gfx::VRManagerParent::DeferredDestroy]
Fascinating: this assertion is the reason I backed Kip out, and we did merge the backout, https://hg.mozilla.org/mozilla-central/rev/46da19b55672, rather than merging the landing and then realizing that oops there was a backout above there, but apparently my theory that we were still seeing these on mozilla-inbound above the backout was just because debug emulator needed a clobber wasn't correct, since https://treeherder.mozilla.org/logviewer.html#?job_id=3646493&repo=b2g-inbound

No idea how enabling it could cause permanent assertions that hadn't been seen before, and then redisabling it could cause the assertions to be intermittent. Does the b2g emulator fail to properly create a clean new profile from the build being tested, so we have stale profiles from the period while it was on m-i being used for test runs on b-i?
Oh, the light dawns at last. Bug 1182048 landed 15 seconds before bug 1218482, so we didn't get the b2g debug build which runs the tests on the former, it got coalesced onto the latter and we didn't see tests run on just the former. So enabling WebVR caused permaorange assertions, but making it possible to have both WebVR and e10s enabled caused intermittent assertions even when it isn't enabled.
Blocks: 1182048
Summary: Intermittent crash test_animation-computed-timing.html, test_bug541668_table_event_delivery.html, test_locale_aware_indexes.html, test_viewport.html | application crashed [@ mozilla::gfx::VRManagerParent::DeferredDestroy] → Intermittent crash test_animation-computed-timing.html,test_bug541668_table_event_delivery.html,test_locale_aware_indexes.html,test_viewport.html,test_animation-currenttime.html | application crashed [@ mozilla::gfx::VRManagerParent::DeferredDestroy]
Blocks: 1218482
This assert() is erroneous, a remnant of debugging, and can be removed.
Assignee: nobody → kgilbert
Comment on attachment 8703012 [details]
MozReview Request: Bug 1235803 - Remove erroneous assertion

https://reviewboard.mozilla.org/r/29255/#review26053

kip was looking for someone to r+, in #gfx; I'll rubber-stamp this, on kip's assurance that this pointer can be already-null at this point without causing any issues. (and kip's in a good positition to make that assessment, as he was the one to add this code & add this assertion)
Attachment #8703012 - Flags: review+
https://hg.mozilla.org/mozilla-central/rev/8b4a0eff4106
Status: NEW → RESOLVED
Closed: 8 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla46
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: