ZFS Panic on 10.0-RELEASE - again

Mike Carlson mike at bayphoto.com
Tue Jul 15 05:50:22 UTC 2014 

I posted to the list about a month ago where our ZFS pool panic upon 
mounting

Well, after backing up and restoring from some snapshots, I rebuilt the 
server on July 4th and restored the data.

Today, competely unrelated to zfs, our root volume (UFS2) was running a 
portsnap fetch extract and trigger a separate panic

I assumed the ZFS data was fine, as it was unrelated. That was not the 
case. After re-installing and performing a zpool import, our pool panic 
yet again.

This is a completely different situation, and again, I've lost around 
20TB of data.

Here is the vmcore.0's backtrace:

Fatal trap 12: page fault while in kernel mode
cpuid = 2; apic id = 12
fault virtual address    = 0x50
fault code        = supervisor read data, page not present
instruction pointer    = 0x20:0xffffffff81a85246
stack pointer            = 0x28:0xfffffe104cb5aab0
frame pointer            = 0x28:0xfffffe104cb5aac0
code segment        = base 0x0, limit 0xfffff, type 0x1b
             = DPL 0, pres 1, long 1, def32 0, gran 1
processor eflags    = interrupt enabled, resume, IOPL = 0
current process        = 0 (system_taskq_7)
trap number        = 12
panic: page fault
cpuid = 2
KDB: stack backtrace:
#0 0xffffffff808e7dd0 at kdb_backtrace+0x60
#1 0xffffffff808af8b5 at panic+0x155
#2 0xffffffff80c8e692 at trap_fatal+0x3a2
#3 0xffffffff80c8e969 at trap_pfault+0x2c9
#4 0xffffffff80c8e0f6 at trap+0x5e6
#5 0xffffffff80c75392 at calltrap+0x8
#6 0xffffffff81a8b710 at vdev_mirror_child_select+0x70
#7 0xffffffff81a8b254 at vdev_mirror_io_start+0x234
#8 0xffffffff81aa52d4 at zio_vdev_io_start+0x184
#9 0xffffffff81aa26a6 at zio_execute+0x136
#10 0xffffffff81a32dec at arc_read+0x87c
#15 0xffffffff81a4aee3 at traverse_visitbp+0x393
#16 0xffffffff81a4aee3 at traverse_visitbp+0x393
#17 0xffffffff81a4aee3 at traverse_visitbp+0x393
Uptime: 4m27s
Dumping 2286 out of 65496 
MB:..1%..11%..21%..31%..41%..51%..61%..71%..81%..91%

Reading symbols from /boot/kernel/ums.ko.symbols...done.
Loaded symbols for /boot/kernel/ums.ko.symbols
Reading symbols from /boot/kernel/zfs.ko.symbols...done.
Loaded symbols for /boot/kernel/zfs.ko.symbols
Reading symbols from /boot/kernel/opensolaris.ko.symbols...done.
Loaded symbols for /boot/kernel/opensolaris.ko.symbols
#0  doadump (textdump=<value optimized out>) at pcpu.h:219
219        __asm("movq %%gs:%1,%0" : "=r" (td)
(kgdb) bt
#0  doadump (textdump=<value optimized out>) at pcpu.h:219
#1  0xffffffff808af530 in kern_reboot (howto=260) at 
/usr/src/sys/kern/kern_shutdown.c:447
#2  0xffffffff808af8f4 in panic (fmt=<value optimized out>) at 
/usr/src/sys/kern/kern_shutdown.c:754
#3  0xffffffff80c8e692 in trap_fatal (frame=<value optimized out>, 
eva=<value optimized out>) at /usr/src/sys/amd64/amd64/trap.c:882
#4  0xffffffff80c8e969 in trap_pfault (frame=0xfffffe104cb5aa00, 
usermode=0) at /usr/src/sys/amd64/amd64/trap.c:699
#5  0xffffffff80c8e0f6 in trap (frame=0xfffffe104cb5aa00) at 
/usr/src/sys/amd64/amd64/trap.c:463
#6  0xffffffff80c75392 in calltrap () at 
/usr/src/sys/amd64/amd64/exception.S:232
#7  0xffffffff81a85246 in vdev_validate (vd=0xfffff80116622c10, 
strict=<value optimized out>) at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/vdev.c:1451
#8  0xffffffff81a8b710 in vdev_mirror_io_done (zio=0x20) at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/vdev_mirror.c:397
#9  0xffffffff81a8b254 in vdev_mirror_io_start (zio=0xfffff80116622c00) 
at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/vdev_mirror.c:90
#10 0xffffffff81aa52d4 in zio_vdev_io_start (zio=0xfffff80015db43b0) at 
time.h:63
#11 0xffffffff81aa26a6 in zio_execute (zio=0xfffff80015db43b0) at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/zio.c:1319
#12 0xffffffff81a32dec in arc_read (pio=0x0, spa=0xfffff80015e0a000, 
bp=<value optimized out>, done=0x2, private=0x0, priority=6, 
zio_flags=0, arc_flags=<value optimized out>,
     zb=0xfffff80116d7b048) at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/arc.c:3371
#13 0xffffffff81a4b8f1 in traverse_prefetcher (spa=0xfffff80015e0a000, 
zilog=0xf01ff, bp=<value optimized out>, zb=<value optimized out>, 
dnp=0xfffff80116622c00, arg=<value optimized out>)
     at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/dmu_traverse.c:451
#14 0xffffffff81a4ad14 in traverse_visitbp (td=0xfffffe104cb5b900, 
dnp=0xfffffe00112ba800, bp=0xfffffe00112ba980, zb=0xfffffe104cb5ae88)
     at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/dmu_traverse.c:250
#15 0xffffffff81a4b77f in traverse_dnode (td=0xfffffe104cb5b900, 
dnp=0xfffffe00112ba800, objset=203, object=24767564)
     at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/dmu_traverse.c:417
#16 0xffffffff81a4b487 in traverse_visitbp (td=0xfffffe104cb5b900, 
dnp=0xfffffe00112b9000, bp=0xfffffe0012145100, zb=0xfffffe104cb5b0a8)
     at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/dmu_traverse.c:309
#17 0xffffffff81a4aee3 in traverse_visitbp (td=0xfffffe104cb5b900, 
dnp=0xfffff80010f5d800, bp=0xfffffe00121d9f00, zb=0xfffffe104cb5b1d8)
     at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/dmu_traverse.c:284
#18 0xffffffff81a4aee3 in traverse_visitbp (td=0xfffffe104cb5b900, 
dnp=0xfffff80010f5d800, bp=0xfffffe001057b780, zb=0xfffffe104cb5b308)
     at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/dmu_traverse.c:284
#19 0xffffffff81a4aee3 in traverse_visitbp (td=0xfffffe104cb5b900, 
dnp=0xfffff80010f5d800, bp=0xfffffe000eb3c000, zb=0xfffffe104cb5b438)
     at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/dmu_traverse.c:284
#20 0xffffffff81a4aee3 in traverse_visitbp (td=0xfffffe104cb5b900, 
dnp=0xfffff80010f5d800, bp=0xfffffe000eb28000, zb=0xfffffe104cb5b568)
     at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/dmu_traverse.c:284
#21 0xffffffff81a4aee3 in traverse_visitbp (td=0xfffffe104cb5b900, 
dnp=0xfffff80010f5d800, bp=0xfffffe0011694000, zb=0xfffffe104cb5b698)
     at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/dmu_traverse.c:284
#22 0xffffffff81a4aee3 in traverse_visitbp (td=0xfffffe104cb5b900, 
dnp=0xfffff80010f5d800, bp=0xfffff80010f5d840, zb=0xfffffe104cb5b758)
     at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/dmu_traverse.c:284
#23 0xffffffff81a4b714 in traverse_dnode (td=0xfffffe104cb5b900, 
dnp=0xfffff80010f5d800, objset=203, object=0)
     at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/dmu_traverse.c:407
#24 0xffffffff81a4b190 in traverse_visitbp (td=0xfffffe104cb5b900, 
dnp=0x0, bp=0xfffff80116b3c880, zb=0xfffffe104cb5b8e0)
     at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/dmu_traverse.c:338
#25 0xffffffff81a4aaf6 in traverse_prefetch_thread 
(arg=0xfffffe104ca9a3a0) at 
/usr/src/sys/modules/zfs/../../cddl/contrib/opensolaris/uts/common/fs/zfs/dmu_traverse.c:470
#26 0xffffffff81a21c00 in taskq_run (arg=0xfffff80116cb5780, 
pending=983551) at 
/usr/src/sys/modules/zfs/../../cddl/compat/opensolaris/kern/opensolaris_taskq.c:109
#27 0xffffffff808f5b66 in taskqueue_run_locked 
(queue=0xfffff8001535e800) at /usr/src/sys/kern/subr_taskqueue.c:333
#28 0xffffffff808f63e8 in taskqueue_thread_loop (arg=<value optimized 
out>) at /usr/src/sys/kern/subr_taskqueue.c:535
#29 0xffffffff8088198a in fork_exit (callout=0xffffffff808f6340 
<taskqueue_thread_loop>, arg=0xfffff80015025e80, 
frame=0xfffffe104cb5ba40) at /usr/src/sys/kern/kern_fork.c:995
#30 0xffffffff80c758ce in fork_trampoline () at 
/usr/src/sys/amd64/amd64/exception.S:606
#31 0x0000000000000000 in ?? ()
Current language:  auto; currently minimal


Pool creation info:
zpool create data raidz /dev/da2.nop /dev/da3.nop /dev/da4.nop /dev/da5.nop
zpool add data raidz /dev/da6.nop /dev/da7.nop /dev/da8.nop /dev/da9.nop
zpool add data raidz /dev/da10.nop /dev/da11.nop /dev/da12.nop /dev/da13.nop
zpool add data raidz /dev/da14.nop /dev/da15.nop /dev/da16.nop /dev/da17.nop
zpool add data raidz /dev/da18.nop /dev/da19.nop /dev/da20.nop /dev/da21.nop
zpool add data raidz /dev/da22.nop /dev/da23.nop /dev/da24.nop /dev/da25.nop
zpool add data spare /dev/da26.nop /dev/da27.nop
zpool add data log /dev/gpt/log.nop
zpool add data cache /dev/gpt/cache.nop


The pool has a log and cache, and I suspect the log device is corrupt.

All zfs commands cause a panic (zdb, zfs list, zpool status, etc...)

I'm also wondering if the LSI controller itself is buggy:
mps0: <LSI SAS2004> port 0xc000-0xc0ff mem 
0xfe83c000-0xfe83ffff,0xfe840000-0xfe87ffff irq 28 at device 0.0 on pci1
mps0: Firmware: 15.00.00.00, Driver: 16.00.00.00-fbsd
mps0: IOCCapabilities: 
185c<ScsiTaskFull,DiagTrace,SnapBuf,EEDP,TransRetry,IR>


-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/pkcs7-signature
Size: 6054 bytes
Desc: S/MIME Cryptographic Signature
URL: <http://lists.freebsd.org/pipermail/freebsd-fs/attachments/20140714/05002574/attachment.bin>

More information about the freebsd-fs mailing list