kern/134142: 7.2-PRERELEASE (STABLE) panic, vfs_cache

xw xorquewasp at googlemail.com
Fri May 1 17:30:05 UTC 2009


>Number:         134142
>Category:       kern
>Synopsis:       7.2-PRERELEASE (STABLE) panic, vfs_cache
>Confidential:   no
>Severity:       serious
>Priority:       high
>Responsible:    freebsd-bugs
>State:          open
>Quarter:        
>Keywords:       
>Date-Required:
>Class:          sw-bug
>Submitter-Id:   current-users
>Arrival-Date:   Fri May 01 17:30:04 UTC 2009
>Closed-Date:
>Last-Modified:
>Originator:     xw
>Release:        7.2-PRERELEASE (-STABLE from early march)
>Organization:
n/a
>Environment:
FreeBSD viper.internal.network 7.2-PRERELEASE FreeBSD 7.2-PRERELEASE #0: Thu Jan  1 01:11:54 GMT 2009     root at viper.internal.network:/usr/obj/usr/src/sys/LDEBUG001  amd64

>Description:
Reproducible panic when compiling a package using tinderbox.

cpuid        = 0
curthread    = 0xffffff0005b4d000: pid 75381 "sh"
curpcb       = 0xffffffff7a3d4d40
fpcurthread  = 0xffffff0005b4d000: pid 75381 "sh"
idlethread   = 0xffffff00019546e0: pid 18 "idle: cpu0"
spin locks held:

Tracing pid 75381 tid 100132 td 0xffffff0005b4d000
_mtx_lock_flags() at _mtx_lock_flags+0x39
cache_lookup() at cache_lookup+0x231
vfs_cache_lookup() at vfs_cache_lookup+0xc3
VOP_LOOKUP_APV() at VOP_LOOKUP_APV+0xb7
lookup() at lookup+0x523
namei() at namei+0x2c8
kern_stat() at kern_stat+0x61
stat() at stat+0x2a
syscall() at syscall+0x1f6
Xfast_syscall() at Xfast_syscall+0xab
--- syscall (188, FreeBSD ELF64, stat), rip = 0x80098929c, rsp = 0x7fffffffdbf8, rbp = 0x800b04400 ---

  pid  ppid  pgrp   uid   state   wmesg         wchan        cmd
75381  7105 25128     0  R+      CPU 0                       sh
 7105 56120 25128     0  S+      piperd   0xffffff00211048b8 sh
56120 49276 25128     0  S+      wait     0xffffff0005d7a000 sh
49276 44738 25128     0  S+      wait     0xffffff000596d478 sh
44738 25128 25128     0  S+      select   0xffffffff80be8eb0 make
25128 23025 25128     0  S+      wait     0xffffff0005a9d000 sh
70003 35973    64     0  S       select   0xffffffff80be8eb0 sshd
23025 16403 23025     0  S+      pause    0xffffff00058c10c0 mksh
16403 43121 16403 11001  S+      pause    0xffffff0005d7a9b0 mksh
10654 66328 10654    70  SsJ     select   0xffffffff80be8eb0 postgres
 2688 66328  2688    70  SsJ     select   0xffffffff80be8eb0 postgres
99547 66328 99547    70  SsJ     select   0xffffffff80be8eb0 postgres
89869 66328 89869    70  SsJ     select   0xffffffff80be8eb0 postgres
81755     1 81755     0  Ss+     ttyin    0xffffff0005885410 getty
72045     1 72045     0  Ss+     ttyin    0xffffff0005883c10 getty
67193     1 67193     0  Ss+     ttyin    0xffffff0005884810 getty
63139     1 63139     0  Ss+     ttyin    0xffffff0005884010 getty
56585     1 56585     0  Ss+     ttyin    0xffffff0005882810 getty
52162     1 52162     0  Ss+     ttyin    0xffffff0005882410 getty
51523     1 51523     0  Ss+     ttyin    0xffffff000587c810 getty
43121     1 43121     0  Ss+     wait     0xffffff0005adb000 login
75283     1 75283     0  Ss      nanslp   0xffffffff80b53bc8 cron
71468 62225    64     0  S+J     piperd   0xffffff0005a998b8 multilog
66328 59833    64    70  S+J     select   0xffffffff80be8eb0 initial thread
62225 47984    64     0  S+J     select   0xffffffff80be8eb0 supervise
59833 47984    64     0  S+J     select   0xffffffff80be8eb0 supervise
55811     1    64     0  S+J     piperd   0xffffff0005de8ba0 multilog
47984     1    64     0  S+J     nanslp   0xffffffff80b53bc8 svscan
35973 16198    64     0  S+      select   0xffffffff80be8eb0 supervise
21922     1    64     0  S+      piperd   0xffffff0005de82e8 multilog
16198     1    64     0  S+      nanslp   0xffffffff80b53bc8 svscan
 7283     1  7283     0  Ss      select   0xffffffff80be8eb0 syslogd
86110     1 86110     0  Ss      select   0xffffffff80be8eb0 devd
73913 64492 64492    64  S       bpf      0xffffff0005b7aa00 pflogd
64492     1 64492     0  Ss      sbwait   0xffffff0005b429cc pflogd
49304     0     0     0  SL      pftm     0xffffffff9c02db90 [pfpurge]
   57     0     0     0  RL      CPU 5                       [softdepflush]
   56     0     0     0  SL      syncer   0xffffffff80b53880 [syncer]
   55     0     0     0  SL      vlruwt   0xffffff00058c2000 [vnlru]
   54     0     0     0  SL      psleep   0xffffffff80be973c [bufdaemon]
   53     0     0     0  SL      pgzero   0xffffffff80bfb76c [pagezero]
   52     0     0     0  SL      psleep   0xffffffff80bfaae8 [vmdaemon]
   51     0     0     0  SL      psleep   0xffffffff80bfaaac [pagedaemon]
   50     0     0     0  SL      waiting_ 0xffffffff80bece68 [sctp_iterator]
   49     0     0     0  WL                                  [swi0: sio]
   48     0     0     0  WL                                  [irq1: atkbd0]
   47     0     0     0  SL      -        0xffffff0005804248 [fdc0]
   46     0     0     0  SL      tzpoll   0xffffffff80b200d0 [acpi_thermal]
   45     0     0     0  SL      -        0xfffffffe80242000 [fw0_probe]
   44     0     0     0  SL      -        0xffffff00055dec80 [fw0_taskq]
   43     0     0     0  SL      usbevt   0xffffff0004c36420 [usb7]
   42     0     0     0  SL      usbevt   0xfffffffe80240420 [usb6]
   41     0     0     0  SL      usbevt   0xfffffffe8023e420 [usb5]
   40     0     0     0  SL      usbevt   0xfffffffe8023c420 [usb4]
   39     0     0     0  WL                                  [irq23: uhci3 ehci1]
   38     0     0     0  SL      usbevt   0xffffff0004c2f420 [usb3]
   37     0     0     0  WL                                  [irq18: ehci0 uhci5]
   36     0     0     0  SL      usbevt   0xfffffffe80234420 [usb2]
   35     0     0     0  WL                                  [irq19: fwohci0++++]
   34     0     0     0  SL      usbevt   0xfffffffe80232420 [usb1]
   33     0     0     0  WL                                  [irq21: uhci1]
   32     0     0     0  SL      usbtsk   0xffffffff80b4f108 [usbtask-dr]
   31     0     0     0  SL      usbtsk   0xffffffff80b4f0e0 [usbtask-hc]
   30     0     0     0  SL      usbevt   0xfffffffe80230420 [usb0]
   29     0     0     0  WL                                  [irq16: uhci0+]
   28     0     0     0  WL                                  [irq9: acpi0]
   27     0     0     0  SL      -        0xffffff0001a87a80 [acpi_task_2]
    9     0     0     0  SL      -        0xffffff0001a87a80 [acpi_task_1]
    8     0     0     0  SL      -        0xffffff0001a87a80 [acpi_task_0]
   26     0     0     0  WL                                  [swi6: task queue]
   25     0     0     0  WL                                  [swi6: Giant taskq]
    7     0     0     0  SL      -        0xffffff0001a87d00 [thread taskq]
   24     0     0     0  WL                                  [swi5: +]
   23     0     0     0  WL                                  [swi2: cambio]
    6     0     0     0  SL      ccb_scan 0xffffffff80b1bae0 [xpt_thrd]
    5     0     0     0  SL      -        0xffffff0001aa9600 [kqueue taskq]
   22     0     0     0  SL      -        0xffffffff80b53888 [yarrow]
    4     0     0     0  SL      -        0xffffffff80b4f838 [g_down]
    3     0     0     0  SL      -        0xffffffff80b4f830 [g_up]
    2     0     0     0  SL      -        0xffffffff80b4f820 [g_event]
   21     0     0     0  WL                                  [swi1: net]
   20     0     0     0  WL                                  [swi3: vm]
   19     0     0     0  WL                                  [swi4: clock sio]
   18     0     0     0  RL                                  [idle: cpu0]
   17     0     0     0  RL      CPU 1                       [idle: cpu1]
   16     0     0     0  RL      CPU 2                       [idle: cpu2]
   15     0     0     0  RL      CPU 3                       [idle: cpu3]
   14     0     0     0  RL      CPU 4                       [idle: cpu4]
   13     0     0     0  RL                                  [idle: cpu5]
   12     0     0     0  RL      CPU 6                       [idle: cpu6]
   11     0     0     0  RL      CPU 7                       [idle: cpu7]
    1     0     1     0  SLs     wait     0xffffff000194f8f0 [init]
   10     0     0     0  SL      audit_wo 0xffffffff80bf9140 [audit]
    0     0     0     0  SLs     sched    0xffffffff80b4f940 [swapper]
db> show alllocks;

Process 75381 (sh) thread 0xffffff0005b4d000 (100132)
Process 64492 (pflogd) thread 0xffffff0005b4a000 (100090)
db> show lockedvnods;

Locked vnodes

Kernel page fault with the following non-sleepable locks held:
exclusive sleep mutex Name Cache r = 0 (0xffffffff80be9940) locked @ /usr/src/sys/kern/vfs_cache.c:345
KDB: stack backtrace:
db_trace_self_wrapper() at db_trace_self_wrapper+0x2a
witness_warn() at witness_warn+0x248
trap() at trap+0x25e
calltrap() at calltrap+0x8
--- trap 0xc, rip = 0xffffffff80509409, rsp = 0xffffffff7a3d46b0, rbp = 0xffffffff7a3d46e0 ---
_mtx_lock_flags() at _mtx_lock_flags+0x39
cache_lookup() at cache_lookup+0x231
vfs_cache_lookup() at vfs_cache_lookup+0xc3
VOP_LOOKUP_APV() at VOP_LOOKUP_APV+0xb7
lookup() at lookup+0x523
namei() at namei+0x2c8
kern_stat() at kern_stat+0x61
stat() at stat+0x2a
syscall() at syscall+0x1f6
Xfast_syscall() at Xfast_syscall+0xab
--- syscall (188, FreeBSD ELF64, stat), rip = 0x80098929c, rsp = 0x7fffffffdbf8, rbp = 0x800b04400 ---


Fatal trap 12: page fault while in kernel mode
cpuid = 0; apic id = 00
fault virtual address	= 0x1a0
fault code		= supervisor read data, page not present
instruction pointer	= 0x8:0xffffffff80509409
stack pointer	        = 0x10:0xffffffff7a3d46b0
frame pointer	        = 0x10:0xffffffff7a3d46e0
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		= 75381 (sh)
exclusive sleep mutex Name Cache r = 0 (0xffffffff80be9940) locked @ /usr/src/sys/kern/vfs_cache.c:345
exclusive sleep mutex Name Cache r = 0 (0xffffffff80be9940) locked @ /usr/src/sys/kern/vfs_cache.c:345
exclusive sx so_rcv_sx r = 0 (0xffffff0005b42970) locked @ /usr/src/sys/kern/uipc_sockbuf.c:148
FreeBSD 7.2-PRERELEASE #0: Thu Jan  1 01:11:54 GMT 2009
    root at viper.internal.network:/usr/obj/usr/src/sys/LDEBUG001

Machine is a core i7-920, biostar t-power x58, 12gb ocz memory,
western digital SATA HD.


>How-To-Repeat:
cd /usr/local/tinderbox/scripts
./tc tinderbuild -b 7.2-PRERELEASE-amd64-viper -nullfs sysutils/lmmon
>Fix:


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


More information about the freebsd-bugs mailing list