From nobody@FreeBSD.org Fri May 1 17:28:03 2009 Return-Path: Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id E4367106564A for ; Fri, 1 May 2009 17:28:03 +0000 (UTC) (envelope-from nobody@FreeBSD.org) Received: from www.freebsd.org (www.freebsd.org [IPv6:2001:4f8:fff6::21]) by mx1.freebsd.org (Postfix) with ESMTP id C700F8FC1B for ; Fri, 1 May 2009 17:28:03 +0000 (UTC) (envelope-from nobody@FreeBSD.org) Received: from www.freebsd.org (localhost [127.0.0.1]) by www.freebsd.org (8.14.3/8.14.3) with ESMTP id n41HS3xg021887 for ; Fri, 1 May 2009 17:28:03 GMT (envelope-from nobody@www.freebsd.org) Received: (from nobody@localhost) by www.freebsd.org (8.14.3/8.14.3/Submit) id n41HS39g021885; Fri, 1 May 2009 17:28:03 GMT (envelope-from nobody) Message-Id: <200905011728.n41HS39g021885@www.freebsd.org> Date: Fri, 1 May 2009 17:28:03 GMT From: xw To: freebsd-gnats-submit@FreeBSD.org Subject: 7.2-PRERELEASE (STABLE) panic, vfs_cache X-Send-Pr-Version: www-3.1 X-GNATS-Notify: >Number: 134142 >Category: kern >Synopsis: [vfs] [panic] 7.2-PRERELEASE (STABLE) panic, vfs_cache >Confidential: no >Severity: serious >Priority: high >Responsible: kan >State: closed >Quarter: >Keywords: >Date-Required: >Class: sw-bug >Submitter-Id: current-users >Arrival-Date: Fri May 01 17:30:04 UTC 2009 >Closed-Date: Thu May 07 12:57:51 UTC 2009 >Last-Modified: Thu May 07 12:57:51 UTC 2009 >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@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@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: State-Changed-From-To: open->closed State-Changed-By: kan State-Changed-When: Thu May 7 12:55:54 UTC 2009 State-Changed-Why: This is believed to be fixed in before 7.2 was shipped. The originator has caught source tree at bad time. Responsible-Changed-From-To: freebsd-bugs->kan Responsible-Changed-By: kan Responsible-Changed-When: Thu May 7 12:55:54 UTC 2009 Responsible-Changed-Why: Mine. http://www.freebsd.org/cgi/query-pr.cgi?pr=134142 >Unformatted: