Skip site navigation (1)Skip section navigation (2)
Date:      Sun, 3 Nov 2013 13:51:19 GMT
From:      Mykhaylo Yehorov <yehorov@gmail.com>
To:        freebsd-gnats-submit@FreeBSD.org
Subject:   kern/183618: [panic][mfi] System on Dell PERC H710 Mini freezes and panics
Message-ID:  <201311031351.rA3DpJE2072412@oldred.freebsd.org>
Resent-Message-ID: <201311031400.rA3E00mf044654@freefall.freebsd.org>

next in thread | raw e-mail | index | archive | help

>Number:         183618
>Category:       kern
>Synopsis:       [panic][mfi] System on Dell PERC H710 Mini freezes and panics
>Confidential:   no
>Severity:       non-critical
>Priority:       low
>Responsible:    freebsd-bugs
>State:          open
>Quarter:        
>Keywords:       
>Date-Required:
>Class:          sw-bug
>Submitter-Id:   current-users
>Arrival-Date:   Sun Nov 03 14:00:00 UTC 2013
>Closed-Date:
>Last-Modified:
>Originator:     Mykhaylo Yehorov
>Release:        FreeBSD 9.2-STABLE
>Organization:
>Environment:
FreeBSD nwtbilling.interexc.com 9.2-STABLE FreeBSD 9.2-STABLE #0: Fri Oct 18 23:11:25 UTC 2013     mika@nwtbilling.interexc.com:/usr/obj/usr/src/sys/GENERIC  amd64
>Description:
We have FreeBSD 9.2-STABLE installed on Dell PowerEdge R620 with PERC H710 Mini RAID contoller.
On high I/O load the system becomes unresponsive and the following messages appear in dmesg.
g_vfs_done():mfid0p5[WRITE(offset=585938796544, length=32768)]error = 11
g_vfs_done():mfid0p5[WRITE(offset=585991651328, length=32768)]error = 11
g_vfs_done():mfid0p5[WRITE(offset=585999679488, length=32768)]error = 11
g_vfs_done():mfid0p5[WRITE(offset=586085498880, length=32768)]error = 11
g_vfs_done():mfid0p5[WRITE(offset=586089299968, length=32768)]error = 11
g_vfs_done():mfid0p5[WRITE(offset=586090577920, length=32768)]error = 11
g_vfs_done():mfid0p5[WRITE(offset=586094182400, length=32768)]error = 11
g_vfs_done():mfid0p5[WRITE(offset=586095067136, length=32768)]error = 11
g_vfs_done():mfid0p5[WRITE(offset=586095820800, length=32768)]error = 11
g_vfs_done():mfid0p5[WRITE(offset=586096672768, length=32768)]error = 11

Some days ago the system crashed.
# kgdb /boot/kernel/kernel.symbols /usr/crash/vmcore.0 
GNU gdb 6.1.1 [FreeBSD]
Copyright 2004 Free Software Foundation, Inc.
GDB is free software, covered by the GNU General Public License, and you are
welcome to change it and/or distribute copies of it under certain conditions.
Type "show copying" to see the conditions.
There is absolutely no warranty for GDB.  Type "show warranty" for details.
This GDB was configured as "amd64-marcel-freebsd"...

Unread portion of the kernel message buffer:
eady in queue awaiting acceptance
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
g_vfs_done():mfid0p5[WRITE(offset=236974866432, length=32768)]error = 11
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
g_vfs_done():mfid0p5[WRITE(offset=236974964736, length=65536)]error = 11
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
g_vfs_done():mfid0p5[WRITE(offset=236975063040, length=131072)]error = 11
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
g_vfs_done():mfid0p5[WRITE(offset=236975194112, length=131072)]error = 11
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
g_vfs_done():mfid0p5[WRITE(offset=236975325184, length=131072)]error = 11
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
<7>sonewconn: pcb 0xfffffe000d784c40: Listen queue overflow: 76 already in queue awaiting acceptance
g_vfs_done():mfid0p5[WRITE(offset=236976635904, length=131072)]error = 11
/usr: got error 11 while accessing filesystem
panic: softdep_deallocate_dependencies: unrecovered I/O error
cpuid = 4
KDB: stack backtrace:
#0 0xffffffff80948f56 at kdb_backtrace+0x66
#1 0xffffffff8090eb2e at panic+0x1ce
#2 0xffffffff80b41840 at clear_remove+0
#3 0xffffffff80991165 at brelse+0x75
#4 0xffffffff80991f88 at bufdone+0x68
#5 0xffffffff8099840d at cluster_callback+0x4d
#6 0xffffffff80991f58 at bufdone+0x38
#7 0xffffffff8098de3e at biodone+0xae
#8 0xffffffff8087456c at g_io_schedule_up+0xac
#9 0xffffffff80874ccc at g_up_procbody+0x5c
#10 0xffffffff808dcdcf at fork_exit+0x11f
#11 0xffffffff80cddc2e at fork_trampoline+0xe
Uptime: 1d1h14m34s
Dumping 1422 out of 24509 MB:..2%..11%..21%..31%..41%..51%..61%..71%..81%..92%

Reading symbols from /boot/kernel/pflog.ko...Reading symbols from /boot/kernel/pflog.ko.symbols...done.
done.
Loaded symbols for /boot/kernel/pflog.ko
Reading symbols from /boot/kernel/pf.ko...Reading symbols from /boot/kernel/pf.ko.symbols...done.
done.
Loaded symbols for /boot/kernel/pf.ko
Reading symbols from /boot/kernel/ipfw.ko...Reading symbols from /boot/kernel/ipfw.ko.symbols...done.
done.
Loaded symbols for /boot/kernel/ipfw.ko
#0  doadump (textdump=<value optimized out>) at pcpu.h:234
234	pcpu.h: No such file or directory.
	in pcpu.h
(kgdb) bt
#0  doadump (textdump=<value optimized out>) at pcpu.h:234
#1  0xffffffff8090e606 in kern_reboot (howto=260) at /usr/src/sys/kern/kern_shutdown.c:449
#2  0xffffffff8090eb07 in panic (fmt=0x1 <Address 0x1 out of bounds>)
    at /usr/src/sys/kern/kern_shutdown.c:637
#3  0xffffffff80b41840 in softdep_deallocate_dependencies (bp=<value optimized out>)
    at /usr/src/sys/ufs/ffs/ffs_softdep.c:13593
#4  0xffffffff80991165 in brelse (bp=0xffffff85cee25fe8) at buf.h:430
#5  0xffffffff80991f88 in bufdone (bp=0xffffff85cee25fe8) at /usr/src/sys/kern/vfs_bio.c:3846
#6  0xffffffff8099840d in cluster_callback (bp=0xffffff85cda6d3b0)
    at /usr/src/sys/kern/vfs_cluster.c:573
#7  0xffffffff80991f58 in bufdone (bp=0xffffff85cda6d3b0) at /usr/src/sys/kern/vfs_bio.c:3840
#8  0xffffffff8098de3e in biodone (bp=0xfffffe04eaf2eba0) at /usr/src/sys/kern/vfs_bio.c:3664
#9  0xffffffff8087456c in g_io_schedule_up (tp=<value optimized out>)
    at /usr/src/sys/geom/geom_io.c:805
#10 0xffffffff80874ccc in g_up_procbody (arg=<value optimized out>)
    at /usr/src/sys/geom/geom_kern.c:97
#11 0xffffffff808dcdcf in fork_exit (callout=0xffffffff80874c70 <g_up_procbody>, arg=0x0, 
    frame=0xffffff8000308b00) at /usr/src/sys/kern/kern_fork.c:990
#12 0xffffffff80cddc2e in fork_trampoline () at /usr/src/sys/amd64/amd64/exception.S:606
#13 0x0000000000000000 in ?? ()
(kgdb) 


# ps -axl -M /usr/crash/vmcore.0 
 UID   PID  PPID CPU PRI NI     VSZ RSS MWCHAN   STAT TT         TIME COMMAND
   0     0     0   0 -52  0       0   0 -        DLs  ??     41:58.04 [kernel]
   0     1     0   0  20  0    6276   0 wait     DLs  ??      0:00.06 [init]
   0     2     0   0 -16  0       0   0 waiting_ DL   ??      0:00.00 [sctp_iterator]
   0     3     0   0 -16  0       0   0 ccb_scan DL   ??      0:00.00 [xpt_thrd]
   0     4     0   0 -16  0       0   0 psleep   DL   ??      0:08.47 [pagedaemon]
   0     5     0   0 -16  0       0   0 psleep   DL   ??      0:00.00 [vmdaemon]
   0     6     0   0 155  0       0   0 pgzero   DL   ??      0:00.00 [pagezero]
   0     7     0   0 -16  0       0   0 psleep   DL   ??      1:03.60 [bufdaemon]
   0     8     0   0  16  0       0   0 getblk   DL   ??      1:09.77 [syncer]
   0     9     0   0 -16  0       0   0 vlruwt   DL   ??      0:00.51 [vnlru]
   0    10     0   0 -16  0       0   0 audit_wo DL   ??      0:00.00 [audit]
   0    11     0   0 155  0       0   0 -        RL   ??  10039:33.65 [idle]
   0    12     0   0 -76  0       0   0 -        WL   ??      1:39.45 [intr]
   0    13     0   0  -8  0       0   0 g_d_tra  DL   ??      2:20.97 [geom]
   0    14     0   0 -16  0       0   0 -        DL   ??      1:39.06 [yarrow]
   0    15     0   0 -68  0       0   0 -        DL   ??      0:08.22 [usb]
   0    16     0   0 -16  0       0   0 sdflush  DL   ??      0:08.10 [softdepflush]
   0  1287     1   0  52  0   14268   0 select   Ds   ??      0:00.00 [moused]
   0  1308     1   0  52  0   14268   0 select   Ds   ??      0:00.00 [moused]
   0  1333     1   0  20  0   10376   0 select   Ds   ??      0:00.03 [devd]
   0  1340     0   0 -16  0       0   0 pftm     DL   ??      0:01.15 [pfpurge]
   0  1345     1   0  52  0   12224   0 sbwait   Ds   ??      0:00.02 [pflogd]
  64  1355  1345   0  20  0   12224   0 bpf      D    ??      0:02.04 [pflogd]
   0  1566     1   0  20  0   12084   0 wdrain   Ds   ??      0:00.75 [syslogd]
  53  1653     1   0  52  0  109404   0 kqread   Ds   ??      0:00.18 [named]
 122  1700     1   0  24  0   20260   0 wait     D    ??      0:00.00 [zabbix_agentd]
 122  1705  1700   0  20  0   20260   0 nanslp   D    ??      0:08.80 [zabbix_agentd]
 122  1706  1700   0  20  0   20260   0 wait     D    ??      0:13.03 [zabbix_agentd]
 122  1707  1700   0  20  0   20260   0 wait     D    ??      0:12.91 [zabbix_agentd]
 122  1708  1700   0  20  0   20260   0 wait     D    ??      0:12.77 [zabbix_agentd]
 122  1709  1700   0  20  0   20260   0 nanslp   D    ??      0:02.93 [zabbix_agentd]
   0  1740     1   0  20  0   22264   0 select   Ds   ??      0:03.39 [ntpd]
   0  1766     1   0  20  0   56152   0 bpf      D    ??     12:31.72 [python]
   0  1770     1   0  52 10   14540   0 wait     DN   ??      0:00.01 [sh]
   0  1776     1   0  52 10   14540   0 wait     DN   ??      0:00.01 [sh]
   0  1781     1   0  21  0   14540   0 wait     D    ??      0:00.00 [sh]
1021  1852  1776   0  30 10  158852   0 sbwait   DN   ??     14:46.30 [mysqld]
1021  1854  1770   0  30 10 2466728   0 getblk   DN   ??    651:15.48 [mysqld]
   0  1855     1   0  44  0   14540   0 wait     D    ??      0:00.00 [sh]
   0  1861     1   0  20  0   14540   0 piperd   D    ??      0:03.32 [sh]
   0  1862     1   0  20  0   14540   0 wait     D    ??      0:01.43 [sh]
   0  1863     1   0  20  0   14540   0 piperd   D    ??      0:01.25 [sh]
   0  1864     1   0  20  0   14540   0 piperd   D    ??      0:01.28 [sh]
   0  1865     1   0  20  0   14540   0 piperd   D    ??      0:01.27 [sh]
   0  1866     1   0  20  0   14540   0 piperd   D    ??      0:01.25 [sh]
   0  1867     1   0  25  0   14540   0 wait     D    ??      0:01.25 [sh]
   0  1868     1   0  20  0   14540   0 piperd   D    ??      0:01.24 [sh]
   0  1869     1   0  20  0   14540   0 piperd   D    ??      0:01.25 [sh]
   0  1908  1855   0  20  0  826324   0 umtx     D    ??    296:38.87 [opengate_proxy]
   0  1943     1   0  20  0  236040   0 select   Ds   ??      0:03.02 [httpd]
   0  1952     1   0  20  0   46888   0 select   Ds   ??      0:00.40 [sshd]
   0  1955     1   0  20  0   20340   0 select   Ds   ??      0:01.66 [sendmail]
  25  1958     1   0  20  0   20340   0 pause    Ds   ??      0:00.02 [sendmail]
   0  1963     1   0  20  0   14180   0 nanslp   Ds   ??      0:01.07 [cron]
   0  2025     1   0  20  0   16248   0 select   Ds   ??      0:00.01 [inetd]
   0  2045     1   0  20  0   12088   0 ttyin    Ds+  ??      0:00.00 [getty]
   0  2046     1   0  52  0   12088   0 ttyin    Ds+  ??      0:00.00 [getty]
   0  2047     1   0  52  0   12088   0 ttyin    Ds+  ??      0:00.00 [getty]
   0  2048     1   0  52  0   12088   0 ttyin    Ds+  ??      0:00.00 [getty]
   0  2049     1   0  52  0   12088   0 ttyin    Ds+  ??      0:00.00 [getty]
   0  2050     1   0  52  0   12088   0 ttyin    Ds+  ??      0:00.00 [getty]
   0  2051     1   0  52  0   12088   0 ttyin    Ds+  ??      0:00.00 [getty]
   0  2052     1   0  52  0   12088   0 ttyin    Ds+  ??      0:00.00 [getty]
   0  2063  1781   0  20  0    5448   0 uwait    D    ??      0:00.14 [mediacnvr]
  80  8012  1943   0  20  0  240136   0 ufs      D    ??      0:00.50 [httpd]
  80 12102  1943   0  20  0  240136   0 select   D    ??      0:02.33 [httpd]
  80 15103  1943   0  28  0  240136   0 accept   D    ??      0:02.61 [httpd]
   0 15188  1963   0  21  0   14180   0 wait     D    ??      0:00.00 [cron]
   0 15193 15188   0  20  0   14540   0 wait     Ds   ??      0:00.01 [sh]
  80 16909  1943   0  20  0  240136   0 ufs      D    ??      0:00.14 [httpd]
   0 19139 15193   0  52  0   14540   0 wait     D    ??      0:00.08 [sh]
   0 20026  1867   0  20  0       0   0 getblk   DE   ??      0:00.00 [sleep]
   0 20908  1862   0  30  0   10224   0 sbwait   D    ??      0:00.11 [radconv]
   0 21007 19139   0  52  0   84632   0 getblk   D    ??      0:00.01 [rrdtool]
   0 21008  1861   0  20  0     284   0 getblk   D    ??      0:00.00 [uname]
   0 21009  1869   0  20  0     284   0 ufs      D    ??      0:00.00 [uname]
 122 21010  1708   0  20  0     416   0 ufs      D    ??      0:00.00 [sh]
   0 21011  1864   0  20  0     284   0 ufs      D    ??      0:00.00 [uname]
   0 21012  1863   0  20  0     284   0 ufs      D    ??      0:00.00 [uname]
   0 21013  1865   0  20  0     284   0 ufs      D    ??      0:00.00 [uname]
   0 21014  1868   0  20  0     284   0 ufs      D    ??      0:00.00 [uname]
   0 21015  1866   0  20  0     284   0 ufs      D    ??      0:00.00 [uname]
 122 21016  1707   0  20  0     416   0 ufs      D    ??      0:00.00 [sh]
 122 21017  1706   0  20  0     416   0 ufs      D    ??      0:00.00 [sh]
   0 21018  1963   0  20  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21019  1963   0  20  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21020  1963   0  20  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21021 21018   0  20  0   14180   0 getblk   DVs  ??      0:00.00 [cron]
   0 21022  1963   0  21  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21023 21019   0  20  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
   0 21024 21020   0  20  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
   0 21025  1963   0  21  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21026  1963   0  21  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21027 21022   0  21  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
   0 21028  1963   0  21  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21029 21025   0  21  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
   0 21030 21026   0  20  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
   0 21031  1963   0  21  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21032  1963   0  21  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21033 21028   0  20  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
   0 21034 21031   0  20  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
   0 21035  1963   0  21  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21036 21032   0  20  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
   0 21037  1963   0  21  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21038 21035   0  20  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
   0 21039 21037   0  21  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
   0 21040  1943   0  20  0  236040   0 getblk   D    ??      0:00.00 [httpd]
   0 21041  1943   0  20  0  236040   0 ufs      D    ??      0:00.00 [httpd]
   0 21042  1963   0  20  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21043  1963   0  20  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21044 21042   0  20  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
   0 21045 21043   0  20  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
   0 21046  1963   0  20  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21047  1963   0  20  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21048  1963   0  20  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21049 21046   0  20  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
   0 21050 21047   0  20  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
   0 21051 21048   0  20  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
   0 21052  1963   0  20  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21053  1963   0  20  0   14180   0 ppwait   D    ??      0:00.00 [cron]
   0 21054 21052   0  20  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
   0 21055 21053   0  20  0   14180   0 ufs      DVs  ??      0:00.00 [cron]
  80 49376  1943   0  20  0  240136   0 ufs      D    ??      0:06.75 [httpd]
  80 66086  1943   0  29  0  240136   0 accept   D    ??      0:04.03 [httpd]
  80 71120  1943   0  25  0  240136   0 accept   D    ??      0:10.93 [httpd]
  80 71125  1943   0  20  0  240136   0 getblk   D    ??      0:05.77 [httpd]
  80 71126  1943   0  20  0  240136   0 ufs      D    ??      0:07.09 [httpd]
  80 86143  1943   0  20  0  240136   0 ufs      D    ??      0:02.56 [httpd]
  80 91090  1943   0  32  0  256520   0 sbwait   D    ??      0:05.66 [httpd]


# mfiutil show adapter
mfi0 Adapter:
    Product Name: PERC H710 Mini
   Serial Number: 39Q009B
        Firmware: 21.2.0-0007
     RAID Levels: JBOD, RAID0, RAID1, RAID5, RAID6, RAID10, RAID50
  Battery Backup: present
           NVRAM: 32K
  Onboard Memory: 512M
  Minimum Stripe: 64k
  Maximum Stripe: 1M

# mfiutil show firmware
mfi0 Firmware Package Version: 21.2.0-0007
mfi0 Firmware Images:
Name  Version                        Date         Time         Status
BIOS  5.38.00_4.12.05.00_0x05260000  12/10/2012
  12/10/2012
  active
CTLR  4.03-0002                      Dec 17 2012  16:24:20     active
PCLI  05.00-03:#%00008               Feb 17 2011  14:03:12     active
APP   3.130.05-2086                  Dec 03 2012  14:21:01     active
NVDT  2.1108.03-0095                 Dec 17 2012  16:23:58     active
BTBL  2.03.00.00-0004                Jul 27 2012  16:39:29     active
BOOT  06.253.57.219                  9/9/2010     15:32:25     active

# cat /var/run/dmesg.boot | grep mfi
mfi0: <Dell PERC H710 Mini (monolithics)> port 0xfc00-0xfcff mem 0xddffc000-0xddffffff,0xddf80000-0xddfbffff irq 42 at device 0.0 on pci3
mfi0: Using MSI
mfi0: Megaraid SAS driver Ver 4.23 
mfi0: FW MaxCmds = 1008, limiting to 128
mfi0: MaxCmd = 1008, Drv MaxCmd = 128, MaxSgl = 70, state = 0xb73c03f0
mfi0: 2 (434123658s/0x0020/info) - Event log cleared
mfi0: 3 (434123658s/0x0020/info) - Test event: 'Event log adjusted, possibly due Firmware version incompatibility'
mfi0: 4 (434123659s/0x0020/DEAD) - Fatal firmware error: Line 3458 in ../../raid/raidpci.c
mfi0: 5 (boot + 6s/0x0020/info) - Firmware initialization started (PCI ID 005b/1000/1f38/1028)
mfi0: 6 (boot + 6s/0x0020/info) - Firmware version 3.130.05-2086
mfi0: 7 (boot + 7s/0x0008/info) - Battery Present
mfi0: 8 (boot + 7s/0x0020/info) - Package version 21.2.0-0007
mfi0: 9 (boot + 7s/0x0020/info) - Board Revision A03
mfi0: 10 (boot + 8s/0x0008/info) - Battery temperature is normal
mfi0: 11 (boot + 8s/0x0008/info) - Current capacity of the battery is above threshold
mfi0: 12 (boot + 41s/0x0002/info) - Unexpected sense: Encl PD 20 Path 6001f0f0e0549900, CDB: 1c 01 00 00 20 00, Sense: 6/29/00
mfi0: 13 (boot + 43s/0x0004/info) - Enclosure PD 20(c None/p1) communication restored
mfi0: 14 (boot + 43s/0x0002/info) - Inserted: Encl PD 20
mfi0: 15 (boot + 43s/0x0002/info) - Inserted: PD 20(c None/p1) Info: enclPd=20, scsiType=d, portMap=00, sasAddr=6001f0f0e0549900,0000000000000000
mfi0: 16 (boot + 43s/0x0002/info) - Inserted: PD 00(e0x20/s0)
mfi0: 17 (boot + 43s/0x0002/info) - Inserted: PD 00(e0x20/s0) Info: enclPd=20, scsiType=0, portMap=03, sasAddr=5000c50068403209,0000000000000000
mfi0: 18 (boot + 43s/0x0002/info) - Inserted: PD 01(e0x20/s1)
mfi0: 19 (boot + 43s/0x0002/info) - Inserted: PD 01(e0x20/s1) Info: enclPd=20, scsiType=0, portMap=04, sasAddr=5000c50068406469,0000000000000000
mfi0: 20 (boot + 43s/0x0002/info) - Inserted: PD 02(e0x20/s2)
mfi0: 21 (boot + 43s/0x0002/info) - Inserted: PD 02(e0x20/s2) Info: enclPd=20, scsiType=0, portMap=05, sasAddr=5000c50068402eed,0000000000000000
mfi0: 22 (boot + 43s/0x0002/info) - Inserted: PD 03(e0x20/s3)
mfi0: 23 (boot + 43s/0x0002/info) - Inserted: PD 03(e0x20/s3) Info: enclPd=20, scsiType=0, portMap=06, sasAddr=5000c50068402925,0000000000000000
mfi0: 24 (boot + 43s/0x0002/info) - Inserted: PD 04(e0x20/s4)
mfi0: 25 (boot + 43s/0x0002/info) - Inserted: PD 04(e0x20/s4) Info: enclPd=20, scsiType=0, portMap=00, sasAddr=5000c500684043f1,0000000000000000
mfi0: 26 (boot + 43s/0x0002/info) - Inserted: PD 05(e0x20/s5)
mfi0: 27 (boot + 43s/0x0002/info) - Inserted: PD 05(e0x20/s5) Info: enclPd=20, scsiType=0, portMap=01, sasAddr=5000c50068402be9,0000000000000000
mfi0: 28 (boot + 43s/0x0002/info) - Inserted: PD 06(e0x20/s6)
mfi0: 29 (boot + 43s/0x0002/info) - Inserted: PD 06(e0x20/s6) Info: enclPd=20, scsiType=0, portMap=02, sasAddr=5000c50068401ee5,0000000000000000
mfi0: 30 (boot + 43s/0x0042/info) - Global Hot Spare created on PD 06(e0x20/s6) (global,rev,ea)
mfi0: 31 (boot + 43s/0x0020/info) - Controller properties changed
mfi0: 32 (boot + 43s/0x0020/info) - Patrol Read properties changed
mfi0: 33 (boot + 43s/0x0020/info) - CC Schedule properties changed
mfi0: 34 (436680723s/0x0020/info) - Time established as 11/02/13  4:12:03; (47 seconds since power on)
mfi0: 35 (436680724s/0x0020/info) - Patrol Read started
mfi0: 36 (436680749s/0x0008/info) - Battery started charging
mfi0: 37 (436680814s/0x0008/info) - Battery charge complete
mfi0: 38 (436680814s/0x0008/info) - Battery relearn pending: Battery is under charge
mfi0: 39 (boot + 4s/0x0020/info) - Firmware initialization started (PCI ID 005b/1000/1f38/1028)
mfi0: 40 (boot + 4s/0x0020/info) - Firmware version 3.130.05-2086
mfi0: 41 (boot + 5s/0x0008/info) - Battery Present
mfi0: 42 (boot + 5s/0x0020/info) - Package version 21.2.0-0007
mfi0: 43 (boot + 5s/0x0020/info) - Board Revision A03
mfi0: 44 (boot + 6s/0x0008/info) - Battery temperature is normal
mfi0: 45 (boot + 6s/0x0008/info) - Current capacity of the battery is above threshold
mfi0: 46 (boot + 19s/0x0004/info) - Enclosure PD 20(c None/p1) communication restored
mfi0: 47 (boot + 19s/0x0002/info) - Inserted: Encl PD 20
mfi0: 48 (boot + 19s/0x0002/info) - Inserted: PD 20(c None/p1) Info: enclPd=20, scsiType=d, portMap=00, sasAddr=6001f0f0e0549900,0000000000000000
mfi0: 49 (boot + 19s/0x0002/info) - Inserted: PD 00(e0x20/s0)
mfi0: 50 (boot + 19s/0x0002/info) - Inserted: PD 00(e0x20/s0) Info: enclPd=20, scsiType=0, portMap=03, sasAddr=5000c50068403209,0000000000000000
mfi0: 51 (boot + 19s/0x0002/info) - Inserted: PD 01(e0x20/s1)
mfi0: 52 (boot + 19s/0x0002/info) - Inserted: PD 01(e0x20/s1) Info: enclPd=20, scsiType=0, portMap=04, sasAddr=5000c50068406469,0000000000000000
mfi0: 53 (boot + 19s/0x0002/info) - Inserted: PD 02(e0x20/s2)
mfi0: 54 (boot + 19s/0x0002/info) - Inserted: PD 02(e0x20/s2) Info: enclPd=20, scsiType=0, portMap=05, sasAddr=5000c50068402eed,0000000000000000
mfi0: 55 (boot + 19s/0x0002/info) - Inserted: PD 03(e0x20/s3)
mfi0: 56 (boot + 19s/0x0002/info) - Inserted: PD 03(e0x20/s3) Info: enclPd=20, scsiType=0, portMap=06, sasAddr=5000c50068402925,0000000000000000
mfi0: 57 (boot + 19s/0x0002/info) - Inserted: PD 04(e0x20/s4)
mfi0: 58 (boot + 19s/0x0002/info) - Inserted: PD 04(e0x20/s4) Info: enclPd=20, scsiType=0, portMap=00, sasAddr=5000c500684043f1,0000000000000000
mfi0: 59 (boot + 19s/0x0002/info) - Inserted: PD 05(e0x20/s5)
mfi0: 60 (boot + 19s/0x0002/info) - Inserted: PD 05(e0x20/s5) Info: enclPd=20, scsiType=0, portMap=01, sasAddr=5000c50068402be9,0000000000000000
mfi0: 61 (boot + 19s/0x0002/info) - Inserted: PD 06(e0x20/s6)
mfi0: 62 (boot + 19s/0x0002/info) - Inserted: PD 06(e0x20/s6) Info: enclPd=20, scsiType=0, portMap=02, sasAddr=5000c50068401ee5,0000000000000000
mfi0: 63 (boot + 19s/0x0042/info) - Global Hot Spare created on PD 06(e0x20/s6) (global,rev,ea)
mfi0: 64 (436680893s/0x0020/info) - Time established as 11/02/13  4:14:53; (45 seconds since power on)
mfi0: 65 (436680919s/0x0008/info) - Battery started charging
mfi0: 66 (436680951s/0x0020/info) - Patrol Read resumed
mfi0: 67 (436680984s/0x0008/info) - Battery relearn pending: Battery is under charge
mfi0: 68 (boot + 4s/0x0020/info) - Firmware initialization started (PCI ID 005b/1000/1f38/1028)
mfi0: 69 (boot + 4s/0x0020/info) - Firmware version 3.130.05-2086
mfi0: 70 (boot + 5s/0x0008/info) - Battery Present
mfi0: 71 (boot + 5s/0x0020/info) - Package version 21.2.0-0007
mfi0: 72 (boot + 5s/0x0020/info) - Board Revision A03
mfi0: 73 (boot + 6s/0x0008/info) - Battery temperature is normal
mfi0: 74 (boot + 6s/0x0008/info) - Current capacity of the battery is above threshold
mfi0: 75 (boot + 19s/0x0004/info) - Enclosure PD 20(c None/p1) communication restored
mfi0: 76 (boot + 19s/0x0002/info) - Inserted: Encl PD 20
mfid0 on mfi0
mfid0: 856704MB (1754529792 sectors) RAID volume 'Virtual Disk 0' is optimal
mfi0: 77 (boot + 19s/0x0002/info) - Inserted: PD 20(c None/p1) Info: enclPd=20, scsiType=d, portMap=00, sasAddr=6001f0f0e0549900,0000000000000000
mfi0: 78 (boot + 19s/0x0002/info) - Inserted: PD 00(e0x20/s0)
mfi0: 79 (boot + 19s/0x0002/info) - Inserted: PD 00(e0x20/s0) Info: enclPd=20, scsiType=0, portMap=03, sasAddr=5000c50068403209,0000000000000000
mfi0: 80 (boot + 19s/0x0002/info) - Inserted: PD 01(e0x20/s1)
mfi0: 81 (boot + 19s/0x0002/info) - Inserted: PD 01(e0x20/s1) Info: enclPd=20, scsiType=0, portMap=04, sasAddr=5000c50068406469,0000000000000000
mfi0: 82 (boot + 19s/0x0002/info) - Inserted: PD 02(e0x20/s2)
mfi0: 83 (boot + 19s/0x0002/info) - Inserted: PD 02(e0x20/s2) Info: enclPd=20, scsiType=0, portMap=05, sasAddr=5000c50068402eed,0000000000000000
mfi0: 84 (boot + 19s/0x0002/info) - Inserted: PD 03(e0x20/s3)
mfi0: 85 (boot + 19s/0x0002/info) - Inserted: PD 03(e0x20/s3) Info: enclPd=20, scsiType=0, portMap=06, sasAddr=5000c50068402925,0000000000000000
mfi0: 86 (boot + 19s/0x0002/info) - Inserted: PD 04(e0x20/s4)
mfi0: 87 (boot + 19s/0x0002/info) - Inserted: PD 04(e0x20/s4) Info: enclPd=20, scsiType=0, portMap=00, sasAddr=5000c500684043f1,0000000000000000
mfi0: 88 (boot + 19s/0x0002/info) - Inserted: PD 05(e0x20/s5)
mfi0: 89 (boot + 19s/0x0002/info) - Inserted: PD 05(e0x20/s5) Info: enclPd=20, scsiType=0, portMap=01, sasAddr=5000c50068402be9,0000000000000000
mfi0: 90 (boot + 19s/0x0002/info) - Inserted: PD 06(e0x20/s6)
mfi0: 91 (boot + 19s/0x0002/info) - Inserted: PD 06(e0x20/s6) Info: enclPd=20, scsiType=0, portMap=02, sasAddr=5000c50068401ee5,0000000000000000
mfi0: 92 (boot + 19s/0x0042/info) - Global Hot Spare created on PD 06(e0x20/s6) (global,rev,ea)
mfi0: 93 (436681089s/0x0020/info) - Time established as 11/02/13  4:18:09; (46 seconds since power on)
mfi0: 94 (436681114s/0x0008/info) - Battery started charging
mfi0: 95 (436681147s/0x0020/info) - Patrol Read resumed
mfi0: 96 (436681179s/0x0008/info) - Battery relearn pending: Battery is under charge
mfi0: 97 (436681205s/0x0020/info) - Host driver is loaded and operational
Trying to mount root from ufs:/dev/mfid0p3 [rw]...
mfi0: 98 (436681244s/0x0008/info) - Battery charge complete
mfi0: 99 (436681244s/0x0008/info) - Battery relearn started

>How-To-Repeat:

>Fix:


>Release-Note:
>Audit-Trail:
>Unformatted:



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?201311031351.rA3DpJE2072412>