Closed Bug 1237528 Opened 9 years ago Closed 4 years ago

Intermittent test_cache_orphaned_body.html | application crashed [@ mozilla::dom::indexedDB::::ConnectionPool::Dispatch]

Categories

(Core :: DOM: Core & HTML, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: nigelb, Unassigned)

References

()

Details

(Keywords: intermittent-failure)

https://treeherder.mozilla.org/logviewer.html#?job_id=6471156&repo=fx-team 13:59:02 INFO - 2 INFO TEST-OK | dom/cache/test/mochitest/test_cache_delete.html | took 94717ms 13:59:08 INFO - 3 INFO TEST-START | dom/cache/test/mochitest/test_cache_orphaned_body.html 13:59:32 INFO - [Child 909] WARNING: No inner window available!: file ../../../gecko/dom/base/nsGlobalWindow.cpp, line 9258 13:59:35 INFO - [Parent 806] WARNING: 'NS_FAILED( directory->GetDirectoryEntries(getter_AddRefs(entries)))', file /builds/slave/b2g_fx-team_emu-d_dep-00000000/build/gecko/dom/quota/ActorsParent.cpp, line 5750 13:59:56 INFO - -*- SettingsRequestManager: Transaction for lock {045af88e-877f-49ad-8f6c-82d7ec98d7e7} aborted 13:59:59 INFO - Segmentation fault 13:59:59 INFO - [Child 944] WARNING: shutting down early because of crash!: file ../../../gecko/dom/ipc/ContentChild.cpp, line 2180 13:59:59 INFO - [Child 944] WARNING: content process _exit()ing: file ../../../gecko/dom/ipc/ContentChild.cpp, line 2253 13:59:59 INFO - [Child 822] WARNING: shutting down early because of crash!: file ../../../gecko/dom/ipc/ContentChild.cpp, line 2180 13:59:59 INFO - [Child 822] WARNING: content process _exit()ing: file ../../../gecko/dom/ipc/ContentChild.cpp, line 2253 14:00:04 INFO - mozcrash Copy/paste: /builds/slave/test/build/linux64-minidump_stackwalk /tmp/tmpdGhbua/56912a22-3dd9-875e-6b7df0aa-7656b1e4.dmp /builds/slave/test/build/symbols 14:00:15 WARNING - PROCESS-CRASH | dom/cache/test/mochitest/test_cache_orphaned_body.html | application crashed [@ mozilla::dom::indexedDB::::ConnectionPool::Dispatch] 14:00:15 INFO - Crash dump filename: /tmp/tmpdGhbua/56912a22-3dd9-875e-6b7df0aa-7656b1e4.dmp 14:00:15 INFO - Operating system: Android 14:00:15 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.20160106.150720:eng/test-keys 14:00:15 INFO - CPU: arm 14:00:15 INFO - ARMv0 14:00:15 INFO - 0 CPUs 14:00:15 INFO - Crash reason: SIGSEGV 14:00:15 INFO - Crash address: 0x0 14:00:15 INFO - Process uptime: not available 14:00:15 INFO - Thread 15 (crashed) 14:00:15 INFO - 0 libxul.so!mozilla::dom::indexedDB::::ConnectionPool::Dispatch [ActorsParent.cpp:256e3e81fed7 : 11230 + 0x1a] 14:00:15 INFO - r0 = 0x4321b372 r1 = 0x4739f718 r2 = 0xa16299c7 r3 = 0x445b8a6c 14:00:15 INFO - r4 = 0x00000000 r5 = 0x00002bde r6 = 0x476c8bc0 r7 = 0x4739fb84 14:00:15 INFO - r8 = 0x4739fbe0 r9 = 0x4739fc88 r10 = 0x00000000 r12 = 0x400660fc 14:00:15 INFO - fp = 0x484f71c0 sp = 0x4739fb78 lr = 0x41c33e19 pc = 0x41c33e18 14:00:15 INFO - Found by: given as instruction pointer in context 14:00:15 INFO - 1 libxul.so!mozilla::dom::indexedDB::::TransactionDatabaseOperationBase::DispatchToConnectionPool [ActorsParent.cpp:256e3e81fed7 : 21886 + 0x1b] 14:00:15 INFO - r4 = 0x476c8bc0 r5 = 0x476c8bfc r6 = 0x49d79ac0 r7 = 0x4739fbdc 14:00:15 INFO - r8 = 0x4739fbe0 r9 = 0x4739fc88 r10 = 0x00000000 fp = 0x484f71c0 14:00:15 INFO - sp = 0x4739fba8 pc = 0x41c346d9 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 2 libxul.so!mozilla::dom::indexedDB::::TransactionBase::StartRequest [ActorsParent.cpp:256e3e81fed7 : 14565 + 0x5] 14:00:15 INFO - r0 = 0x476c8bc0 r1 = 0x00000018 r4 = 0x476c8c0c r5 = 0x476c8bc0 14:00:15 INFO - r6 = 0x00000001 r7 = 0x4739fbdc r8 = 0x4739fbe0 r9 = 0x4739fc88 14:00:15 INFO - r10 = 0x00000000 fp = 0x484f71c0 sp = 0x4739fbc0 pc = 0x41c34fcf 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 3 libxul.so!mozilla::dom::indexedDB::PBackgroundIDBTransactionParent::OnMessageReceived [PBackgroundIDBTransactionParent.cpp : 430 + 0xb] 14:00:15 INFO - r4 = 0x49ae86d0 r5 = 0x476c8c0c r6 = 0x4739fbf0 r7 = 0x4739fbdc 14:00:15 INFO - r8 = 0x4739fbe0 r9 = 0x4739fc88 r10 = 0x00000000 fp = 0x484f71c0 14:00:15 INFO - sp = 0x4739fbd0 pc = 0x40f111ff 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 4 libxul.so!mozilla::ipc::PBackgroundParent::OnMessageReceived [PBackgroundParent.cpp : 1276 + 0x7] 14:00:15 INFO - r4 = 0x4739fd0c r5 = 0x49076000 r6 = 0x00000000 r7 = 0x00000000 14:00:15 INFO - r8 = 0xffffffff r9 = 0x4739fc88 r10 = 0x00000000 fp = 0x484f71c0 14:00:15 INFO - sp = 0x4739fc50 pc = 0x40f69b67 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 5 libxul.so!mozilla::ipc::MessageChannel::DispatchAsyncMessage [MessageChannel.cpp:256e3e81fed7 : 1384 + 0xd] 14:00:15 INFO - r3 = 0x40f69b3d r4 = 0x49076038 r5 = 0x4739fd0c r6 = 0x00000000 14:00:15 INFO - r7 = 0x00000000 r8 = 0xffffffff r9 = 0x4739fc88 r10 = 0x00000000 14:00:15 INFO - fp = 0x484f71c0 sp = 0x4739fc60 pc = 0x40e25f07 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 6 libxul.so!mozilla::ipc::MessageChannel::DispatchMessage [MessageChannel.cpp:256e3e81fed7 : 1304 + 0x7] 14:00:15 INFO - r0 = 0x49076038 r1 = 0x4739fd0c r2 = 0x00000000 r4 = 0x49076038 14:00:15 INFO - r5 = 0x4739fd0c r6 = 0x4739fc84 r7 = 0x4739fc8c r8 = 0xffffffff 14:00:15 INFO - r9 = 0x4739fc88 r10 = 0x00000000 fp = 0x484f71c0 sp = 0x4739fc80 14:00:15 INFO - pc = 0x40e2edbd 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 7 libxul.so!mozilla::ipc::MessageChannel::OnMaybeDequeueOne [MessageChannel.cpp:256e3e81fed7 : 1275 + 0x7] 14:00:15 INFO - r4 = 0x4739fd0c r5 = 0x49076038 r6 = 0x00000001 r7 = 0x00000001 14:00:15 INFO - r8 = 0x4026382c r9 = 0x4739fe2f r10 = 0x4739fdd4 fp = 0x00000000 14:00:15 INFO - sp = 0x4739fd00 pc = 0x40e2fa79 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 8 libxul.so!RunnableMethod<FdWatcher, void (FdWatcher::*)(), mozilla::Tuple<> >::Run [task.h:256e3e81fed7 : 28 + 0x13] 14:00:15 INFO - r4 = 0x454ccd20 r5 = 0x40263820 r6 = 0x45514170 r7 = 0x00000001 14:00:15 INFO - r8 = 0x4026382c r9 = 0x4739fe2f r10 = 0x4739fdd4 fp = 0x00000000 14:00:15 INFO - sp = 0x4739fd38 pc = 0x40bd8aa3 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 9 libxul.so!mozilla::ipc::MessageChannel::DequeueTask::Run [MessageChannel.h : 472 + 0x5] 14:00:15 INFO - r3 = 0x40bd8a85 r4 = 0x454ccd20 r5 = 0x40263820 r6 = 0x45514170 14:00:15 INFO - r7 = 0x00000001 r8 = 0x4026382c r9 = 0x4739fe2f r10 = 0x4739fdd4 14:00:15 INFO - fp = 0x00000000 sp = 0x4739fd40 pc = 0x40e21aef 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 10 libxul.so!MessageLoop::RunTask [message_loop.cc:256e3e81fed7 : 364 + 0x7] 14:00:15 INFO - r3 = 0x40e21add r4 = 0x454ccd20 r5 = 0x40263820 r6 = 0x45514170 14:00:15 INFO - r7 = 0x00000001 r8 = 0x4026382c r9 = 0x4739fe2f r10 = 0x4739fdd4 14:00:15 INFO - fp = 0x00000000 sp = 0x4739fd48 pc = 0x40e0b565 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 11 libxul.so!MessageLoop::DeferOrRunPendingTask [message_loop.cc:256e3e81fed7 : 372 + 0x3] 14:00:15 INFO - r4 = 0x00000001 r5 = 0x4739fd88 r6 = 0x45514170 r7 = 0x00000001 14:00:15 INFO - r8 = 0x4026382c r9 = 0x4739fe2f r10 = 0x4739fdd4 fp = 0x00000000 14:00:15 INFO - sp = 0x4739fd68 pc = 0x40e0e877 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 12 libxul.so!MessageLoop::DoWork [message_loop.cc:256e3e81fed7 : 459 + 0x3] 14:00:15 INFO - r3 = 0x00000000 r4 = 0x40263820 r5 = 0x4739fd88 r6 = 0x45514170 14:00:15 INFO - r7 = 0x00000001 r8 = 0x4026382c r9 = 0x4739fe2f r10 = 0x4739fdd4 14:00:15 INFO - fp = 0x00000000 sp = 0x4739fd78 pc = 0x40e10739 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 13 libxul.so!mozilla::ipc::DoWorkRunnable::Run [MessagePump.cpp:256e3e81fed7 : 220 + 0x7] 14:00:15 INFO - r4 = 0x40263820 r5 = 0x00000001 r6 = 0x4739fdd8 r7 = 0x00000001 14:00:15 INFO - r8 = 0x00000001 r9 = 0x4739fe2f r10 = 0x4739fdd4 fp = 0x00000000 14:00:15 INFO - sp = 0x4739fda8 pc = 0x40e214b7 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 14 libxul.so!nsThread::ProcessNextEvent [nsThread.cpp:256e3e81fed7 : 989 + 0xb] 14:00:15 INFO - r3 = 0x40e21479 r4 = 0x45741160 r5 = 0x4739fdf0 r6 = 0x4739fdd8 14:00:15 INFO - r7 = 0x00000001 r8 = 0x00000001 r9 = 0x4739fe2f r10 = 0x4739fdd4 14:00:15 INFO - fp = 0x00000000 sp = 0x4739fdb8 pc = 0x40c1ec0f 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 15 libxul.so!NS_ProcessNextEvent [nsThreadUtils.cpp:256e3e81fed7 : 297 + 0xd] 14:00:15 INFO - r4 = 0x45741160 r5 = 0x00000001 r6 = 0x45752c88 r7 = 0x45752c80 14:00:15 INFO - r8 = 0x00000000 r9 = 0x45741170 r10 = 0xffffffff fp = 0x00000001 14:00:15 INFO - sp = 0x4739fe28 pc = 0x40c3a7ef 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 16 libxul.so!mozilla::ipc::MessagePumpForNonMainThreads::Run [MessagePump.cpp:256e3e81fed7 : 355 + 0x7] 14:00:15 INFO - r0 = 0x45741160 r1 = 0x01000001 r2 = 0x45744300 r4 = 0x45752c70 14:00:15 INFO - r5 = 0x40263820 r6 = 0x45752c88 r7 = 0x45752c80 r8 = 0x00000000 14:00:15 INFO - r9 = 0x45741170 r10 = 0xffffffff fp = 0x00000001 sp = 0x4739fe40 14:00:15 INFO - pc = 0x40e272dd 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 17 libxul.so!MessageLoop::RunInternal [message_loop.cc:256e3e81fed7 : 234 + 0xf] 14:00:15 INFO - r0 = 0x442c43c8 r1 = 0x444ec174 r2 = 0x00000000 r3 = 0x00000000 14:00:15 INFO - r4 = 0x40263820 r5 = 0x4739fea8 r6 = 0x4739fea0 r7 = 0x4739fea4 14:00:15 INFO - r8 = 0x40263820 r9 = 0x45741170 r10 = 0xffffffff fp = 0x00000001 14:00:15 INFO - sp = 0x4739fe68 pc = 0x40e0bdbd 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 18 libxul.so!MessageLoop::Run [message_loop.cc:256e3e81fed7 : 227 + 0x5] 14:00:15 INFO - r0 = 0x40263820 r1 = 0x40263820 r2 = 0x00000000 r3 = 0x00000000 14:00:15 INFO - r4 = 0x40263820 r5 = 0x4739fea8 r6 = 0x4739fea0 r7 = 0x4739fea4 14:00:15 INFO - r8 = 0x40263820 r9 = 0x45741170 r10 = 0xffffffff fp = 0x00000001 14:00:15 INFO - sp = 0x4739fe80 pc = 0x40e0bdd7 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 19 libxul.so!nsThread::ThreadFunc [nsThread.cpp:256e3e81fed7 : 401 + 0x3] 14:00:15 INFO - r0 = 0x00000001 r1 = 0x00000000 r2 = 0x40263820 r3 = 0x00000000 14:00:15 INFO - r4 = 0x45741160 r5 = 0x4739fea8 r6 = 0x4739fea0 r7 = 0x4739fea4 14:00:15 INFO - r8 = 0x40263820 r9 = 0x45741170 r10 = 0xffffffff fp = 0x00000001 14:00:15 INFO - sp = 0x4739fe98 pc = 0x40c1f14f 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 20 libnss3.so!_pt_root [ptthread.c:256e3e81fed7 : 212 + 0x5] 14:00:15 INFO - r4 = 0x45744100 r5 = 0x407e732c r6 = 0x00000000 r7 = 0x00000000 14:00:15 INFO - r8 = 0x00034108 r9 = 0x00000000 r10 = 0x00100000 fp = 0x00000001 14:00:15 INFO - sp = 0x4739fed0 pc = 0x40779511 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 21 libc.so!__thread_entry [pthread.c : 217 + 0x6] 14:00:15 INFO - r3 = 0x00000040 r4 = 0x4739ff00 r5 = 0x40779469 r6 = 0x45744100 14:00:15 INFO - r7 = 0x00000078 r8 = 0x40779469 r9 = 0x45744100 r10 = 0x00100000 14:00:15 INFO - fp = 0x00000001 sp = 0x4739fef0 pc = 0x40079e2c 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - 22 libc.so!pthread_create [pthread.c : 357 + 0xe] 14:00:15 INFO - r4 = 0x4739ff00 r5 = 0x00034108 r6 = 0xbed1c480 r7 = 0x00000078 14:00:15 INFO - r8 = 0x40779469 r9 = 0x45744100 r10 = 0x00100000 fp = 0x00000001 14:00:15 INFO - sp = 0x4739ff00 pc = 0x4007997c 14:00:15 INFO - Found by: call frame info 14:00:15 INFO - Thread 0 14:00:15 INFO - 0 libc.so!__futex_syscall3 + 0xc 14:00:15 INFO - r0 = 0x00000001 r1 = 0x00000081 r2 = 0x00000001 r3 = 0xffffff46 14:00:15 INFO - r4 = 0x483a6be4 r5 = 0x00000000 r6 = 0x00000000 r7 = 0x000000f0 14:00:15 INFO - r8 = 0xbed1bdb8 r9 = 0x407e7c58 r10 = 0xbed1bdb8 r12 = 0xffffff44 14:00:15 INFO - fp = 0x407d063c sp = 0xbed1bd70 lr = 0x40078d6c pc = 0x400745a8 14:00:15 INFO - Found by: given as instruction pointer in context 14:00:15 INFO - 1 libc.so!__pthread_cond_pulse [pthread.c : 1450 + 0x12] 14:00:15 INFO - r4 = 0x483a6be4 r5 = 0x00000000 r6 = 0x00000000 r7 = 0x00000001 14:00:15 INFO - r8 = 0xbed1bdb8 r9 = 0x407e7c58 r10 = 0xbed1bdb8 fp = 0x407d063c 14:00:15 INFO - sp = 0xbed1bd78 pc = 0x40078d6c 14:00:15 INFO - Found by: call frame info
Not that this is happening terrible frequently, but https://treeherder.mozilla.org/logviewer.html#?job_id=6471156&repo=fx-team#L1862 and https://treeherder.mozilla.org/logviewer.html#?job_id=6471156&repo=fx-team#L1865 seem potentially relevant: INFO - [Child 909] WARNING: A control runnable was posted to a worker that is already shutting down!: file /builds/slave/b2g_fx-team_emu-d_dep-00000000/build/gecko/dom/workers/WorkerPrivate.cpp, line 2337 INFO - [Parent 806] WARNING: 'NS_FAILED( directory->GetDirectoryEntries(getter_AddRefs(entries)))', file /builds/slave/b2g_fx-team_emu-d_dep-00000000/build/gecko/dom/quota/ActorsParent.cpp, line 5750 ... of course, followed by "INFO - Segmentation fault".
Flags: needinfo?(bkelly)
This test does not use IDB directly, but the log suggests there is overlapping operations with another test: 13:57:28 INFO - [Child 909] WARNING: TabChild::SetFocus not supported in TabChild: file /builds/slave/b2g_fx-team_emu-d_dep-00000000/build/gecko/dom/ipc/TabChild.cpp, line 982 13:57:34 INFO - LogShake.jsm: Disabling QA Mode 13:57:59 INFO - [Child 909] WARNING: Unable to printf the requested string due to error.: file /builds/slave/b2g_fx-team_emu-d_dep-00000000/build/gecko/ipc/chromium/src/base/string_util.cc, line 427 13:58:38 INFO - [Child 909] WARNING: A control runnable was posted to a worker that is already shutting down!: file /builds/slave/b2g_fx-team_emu-d_dep-00000000/build/gecko/dom/workers/WorkerPrivate.cpp, line 2337 13:58:38 INFO - [Parent 806] WARNING: 'NS_FAILED( directory->GetDirectoryEntries(getter_AddRefs(entries)))', file /builds/slave/b2g_fx-team_emu-d_dep-00000000/build/gecko/dom/quota/ActorsParent.cpp, line 5750 13:58:39 INFO - Skipping running the test in SW until bug 1137683 gets fixed. 13:58:39 INFO - [Parent 806] WARNING: 'NS_FAILED( directory->GetDirectoryEntries(getter_AddRefs(entries)))', file /builds/slave/b2g_fx-team_emu-d_dep-00000000/build/gecko/dom/quota/ActorsParent.cpp, line 5750 13:58:58 INFO - [Parent 806] WARNING: 'NS_FAILED( directory->GetDirectoryEntries(getter_AddRefs(entries)))', file /builds/slave/b2g_fx-team_emu-d_dep-00000000/build/gecko/dom/quota/ActorsParent.cpp, line 5750 13:58:59 INFO - MEMORY STAT vsizeMaxContiguous not supported in this build configuration. 13:59:00 INFO - MEMORY STAT | vsize 136MB | residentFast 57MB | heapAllocated 19MB 13:59:01 INFO - AUS:Settings: Not a dogfooder! 13:59:02 INFO - 2 INFO TEST-OK | dom/cache/test/mochitest/test_cache_delete.html | took 94717ms 13:59:08 INFO - 3 INFO TEST-START | dom/cache/test/mochitest/test_cache_orphaned_body.html 13:59:32 INFO - [Child 909] WARNING: No inner window available!: file ../../../gecko/dom/base/nsGlobalWindow.cpp, line 9258 13:59:35 INFO - [Parent 806] WARNING: 'NS_FAILED( directory->GetDirectoryEntries(getter_AddRefs(entries)))', file /builds/slave/b2g_fx-team_emu-d_dep-00000000/build/gecko/dom/quota/ActorsParent.cpp, line 5750 13:59:56 INFO - -*- SettingsRequestManager: Transaction for lock {045af88e-877f-49ad-8f6c-82d7ec98d7e7} aborted 13:59:59 INFO - Segmentation fault 13:59:59 INFO - [Child 944] WARNING: shutting down early because of crash!: file ../../../gecko/dom/ipc/ContentChild.cpp, line 2180 13:59:59 INFO - [Child 944] WARNING: content process _exit()ing: file ../../../gecko/dom/ipc/ContentChild.cpp, line 2253 13:59:59 INFO - [Child 822] WARNING: shutting down early because of crash!: file ../../../gecko/dom/ipc/ContentChild.cpp, line 2180 13:59:59 INFO - [Child 822] WARNING: content process _exit()ing: file ../../../gecko/dom/ipc/ContentChild.cpp, line 2253 14:00:04 INFO - mozcrash Copy/paste: /builds/slave/test/build/linux64-minidump_stackwalk /tmp/tmpdGhbua/56912a22-3dd9-875e-6b7df0aa-7656b1e4.dmp /builds/slave/test/build/symbols 14:00:15 WARNING - PROCESS-CRASH | dom/cache/test/mochitest/test_cache_orphaned_body.html | application crashed [@ mozilla::dom::indexedDB::::ConnectionPool::Dispatch] 14:00:15 INFO - Crash dump filename: /tmp/tmpdGhbua/56912a22-3dd9-875e-6b7df0aa-7656b1e4.dmp The test does use QuotaManager to wipe storage. That could break other IDB transactions possible. Jan, any ideas?
Flags: needinfo?(bkelly) → needinfo?(jvarga)
It looks like gConnectionPool is null. That's ... unexpected.
Hm, this seems to be gone.
Flags: needinfo?(jvarga)
Bulk assigning P3 to all open intermittent bugs without a priority set in Firefox components per bug 1298978.
Priority: -- → P3
Priority: P3 → P5
Component: DOM → DOM: Core & HTML
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.