From owner-freebsd-bugs@FreeBSD.ORG Sun Aug 30 10:00:10 2009 Return-Path: Delivered-To: freebsd-bugs@hub.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id 2E337106564A for ; Sun, 30 Aug 2009 10:00:10 +0000 (UTC) (envelope-from gnats@FreeBSD.org) Received: from freefall.freebsd.org (freefall.freebsd.org [IPv6:2001:4f8:fff6::28]) by mx1.freebsd.org (Postfix) with ESMTP id DBF218FC1D for ; Sun, 30 Aug 2009 10:00:09 +0000 (UTC) Received: from freefall.freebsd.org (gnats@localhost [127.0.0.1]) by freefall.freebsd.org (8.14.3/8.14.3) with ESMTP id n7UA09M9068513 for ; Sun, 30 Aug 2009 10:00:09 GMT (envelope-from gnats@freefall.freebsd.org) Received: (from gnats@localhost) by freefall.freebsd.org (8.14.3/8.14.3/Submit) id n7UA09cB068512; Sun, 30 Aug 2009 10:00:09 GMT (envelope-from gnats) Date: Sun, 30 Aug 2009 10:00:09 GMT Message-Id: <200908301000.n7UA09cB068512@freefall.freebsd.org> To: freebsd-bugs@FreeBSD.org From: Petr Salinger Cc: Subject: Re: kern/138002: [lor] Three lock order reversals: ufs/devfs, bufwait/dirhash, bufwait/ufs X-BeenThere: freebsd-bugs@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list Reply-To: Petr Salinger List-Id: Bug reports List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sun, 30 Aug 2009 10:00:10 -0000 The following reply was made to PR kern/138002; it has been noted by GNATS. From: Petr Salinger To: bug-followup@FreeBSD.org Cc: Subject: Re: kern/138002: [lor] Three lock order reversals: ufs/devfs, bufwait/dirhash, bufwait/ufs Date: Sun, 30 Aug 2009 12:29:01 +0200 (CEST) Similarly for me, 8.0 snapshot as of 20090829, in QEMU: lock order reversal: 1st 0xc3177250 bufwait (bufwait) @ kern/vfs_bio.c:2559 2nd 0xc35b2c00 dirhash (dirhash) @ ufs/ufs/ufs_dirhash.c:285 lock order reversal: 1st 0xc35f49c4 ufs (ufs) @ kern/vfs_mount.c:1200 2nd 0xc35f4df4 devfs (devfs) @ kern/vfs_subr.c:2083 Whole dmesg of boot and reboot: Copyright (c) 1992-2009 The FreeBSD Project. Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994 The Regents of the University of California. All rights reserved. FreeBSD is a registered trademark of The FreeBSD Foundation. #0 Sat Aug 29 21:10:23 CEST 2009 WARNING: WITNESS option enabled, expect reduced performance. Timecounter "i8254" frequency 1193182 Hz quality 0 CPU: QEMU Virtual CPU version 0.10.5 (2672.21-MHz 686-class CPU) Origin = "AuthenticAMD" Id = 0x623 Stepping = 3 Features=0x78bf3fd Features2=0x9 AMD Features=0xe0100800 AMD Features2=0x4 real memory = 536805376 (511 MB) avail memory = 507310080 (483 MB) ACPI APIC Table: MADT: Forcing active-low polarity and level trigger for SCI ioapic0 irqs 0-23 on motherboard kbd1 at kbdmux0 acpi0: on motherboard acpi0: [ITHREAD] Timecounter "ACPI-safe" frequency 3579545 Hz quality 850 acpi_timer0: <24-bit timer at 3.579545MHz> port 0xb008-0xb00b on acpi0 acpi_hpet0: iomem 0xfed00000-0xfed003ff on acpi0 Timecounter "HPET" frequency 100000000 Hz quality 900 pcib0: port 0xcf8-0xcff on acpi0 pci_link0: BIOS IRQ 9 does not match initial IRQ 11 pci0: on pcib0 Correcting Natoma config for non-SMP isab0: at device 1.0 on pci0 isa0: on isab0 atapci0: port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0xc000-0xc00f at device 1.1 on pci0 ata0: on atapci0 ata0: [ITHREAD] ata1: on atapci0 ata1: [ITHREAD] pci0: at device 1.3 (no driver attached) vgapci0: mem 0xc0000000-0xc1ffffff,0xc2000000-0xc2000fff at device 2.0 on pci0 ed0: port 0xc100-0xc1ff irq 11 at device 3.0 on pci0 ed0: ed_stop_hw RST never set ed0: WARNING: using obsoleted if_watchdog interface ed0: Ethernet address: 52:54:00:12:34:56 ed0: [ITHREAD] pci0: at device 4.0 (no driver attached) atrtc0: port 0x70-0x71,0x72-0x77 irq 8 on acpi0 atkbdc0: port 0x60,0x64 irq 1 on acpi0 atkbd0: irq 1 on atkbdc0 kbd0 at atkbd0 atkbd0: [GIANT-LOCKED] atkbd0: [ITHREAD] psm0: flags 0x100 irq 12 on atkbdc0 psm0: [GIANT-LOCKED] psm0: [ITHREAD] psm0: model IntelliMouse Explorer, device ID 4 ppc0: port 0x378-0x37f irq 7 on acpi0 ppc0: Generic chipset (NIBBLE-only) in COMPATIBLE mode ppc0: [ITHREAD] ppbus0: on ppc0 uart0: port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0 uart0: [FILTER] cpu0: on acpi0 pmtimer0 on isa0 sc0: at flags 0x100 on isa0 sc0: VGA <16 virtual consoles, flags=0x300> vga0: at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0 Timecounter "TSC" frequency 2672214032 Hz quality 800 Timecounters tick every 1.000 msec driver bug: Unable to set devclass (devname: (null)) ad0: 4000MB at ata0-master WDMA2 acd0: CDROM at ata1-master WDMA2 WARNING: WITNESS option enabled, expect reduced performance. Trying to mount root from ufs:/dev/ad0s1a Trying to mount root from ufs:/dev/ad0s1a (probe0:ata1:0:0:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 (probe0:ata1:0:0:0): CAM Status: SCSI Status Error (probe0:ata1:0:0:0): SCSI Status: Check Condition (probe0:ata1:0:0:0): NOT READY asc:3a,0 (probe0:ata1:0:0:0): Medium not present (probe0:ata1:0:0:0): Unretryable error cd0 at ata1 bus 0 target 0 lun 0 cd0: Removable CD-ROM SCSI-0 device cd0: 16.000MB/s transfers cd0: Attempt to query device size failed: NOT READY, Medium not present lock order reversal: 1st 0xc3177250 bufwait (bufwait) @ kern/vfs_bio.c:2559 2nd 0xc35b2c00 dirhash (dirhash) @ ufs/ufs/ufs_dirhash.c:285 KDB: stack backtrace: db_trace_self_wrapper(d63a1a60,c08dfe38,c0c80821,c0c284c4,d63a1ac8,...) at db_trace_self_wrapper+0x26 _witness_debugger(c0c19b98,c35b2c00,c0c284c4,c342fce0,c0c80821,...) at _witness_debugger+0x25 witness_checkorder(c35b2c00,9,c0c80818,11d,0,...) at witness_checkorder+0x7f6 _sx_xlock(c35b2c00,0,c0c80818,11d,cd637490,...) at _sx_xlock+0x52 ufsdirhash_acquire(d63a1b2c,0,0,c00,490,...) at ufsdirhash_acquire+0x35 ufsdirhash_remove(c36e5bc8,cd637490,490,d63a1b60,d63a1b5c,...) at ufsdirhash_remove+0x1d ufs_dirremove(c35f4324,c370315c,500800c,0,c35f4324,...) at ufs_dirremove+0x180 ufs_remove(d63a1c34,d63a1c44,d63a1c0c,d63a1c0c,c3726860,...) at ufs_remove+0x81 VOP_REMOVE_APV(c0d56ec0,d63a1c34,c3726860,d63a1c0c,bfbfeeec,...) at VOP_REMOVE_APV+0x5d kern_unlinkat(c3719900,ffffff9c,bfbfeeec,0,d63a1c80,...) at kern_unlinkat+0x23d kern_unlink(c3719900,bfbfeeec,0,d63a1d2c,c0b87b67,...) at kern_unlink+0x27 unlink(c3719900,d63a1cf8,4,c0c282d2,c0d36218,...) at unlink+0x22 syscall(d63a1d38) at syscall+0x177 Xint0x80_syscall() at Xint0x80_syscall+0x20 --- syscall (10, FreeBSD ELF32, unlink), eip = 0x28135c07, esp = 0xbfbfebbc, ebp = 0xbfbfecb8 --- ed0: ed_stop_hw RST never set ed0: ed_stop_hw RST never set Waiting (max 60 seconds) for system process `vnlru' to stop...done Waiting (max 60 seconds) for system process `bufdaemon' to stop...done Waiting (max 60 seconds) for system process `syncer' to stop... Syncing disks, vnodes remaining...0 done All buffers synced. lock order reversal: 1st 0xc35f49c4 ufs (ufs) @ kern/vfs_mount.c:1200 2nd 0xc35f4df4 devfs (devfs) @ kern/vfs_subr.c:2083 KDB: stack backtrace: db_trace_self_wrapper(d636e9e4,c08dfe38,c0c1e124,c0c11fcc,d636ea4c,...) at db_trace_self_wrapper+0x26 _witness_debugger(c0c19b98,c35f4df4,c0c11fcc,c342fba8,c0c1e124,...) at _witness_debugger+0x25 witness_checkorder(c35f4df4,9,c0c1e11b,823,0,...) at witness_checkorder+0x7f6 __lockmgr_args(c35f4df4,80100,c35f4e10,0,0,...) at __lockmgr_args+0xf00 vop_stdlock(d636eb20,3,c35b0994,80000,c35f4d9c,...) at vop_stdlock+0x54 VOP_LOCK1_APV(c0d32b00,d636eb20,c342ba40,c0d6f720,c35f4d9c,...) at VOP_LOCK1_APV+0x7f _vn_lock(c35f4d9c,80100,c0c1e11b,823,15f,...) at _vn_lock+0x49 vget(c35f4d9c,80100,c36f5b40,15e,9d,...) at vget+0x66 devfs_allocv(c36dd080,c36df000,d636ebb4,9d,c35f496c,...) at devfs_allocv+0x110 devfs_root(c36df000,80000,d636ebf0,4f5,c31b0df0,...) at devfs_root+0x4a dounmount(c36df000,80000,c36f5b40,c36f5b40,c3176230,...) at dounmount+0x53e vfs_unmountall(c31763e0,0,c0c174e4,129,0,...) at vfs_unmountall+0x50 boot(c0da3a30,0,c0c174e4,ac,c0d36100,...) at boot+0x757 reboot(c36f5b40,d636ecf8,4,c0c1add0,c0d36704,...) at reboot+0x77 syscall(d636ed38) at syscall+0x177 Xint0x80_syscall() at Xint0x80_syscall+0x20 --- syscall (55, FreeBSD ELF32, reboot), eip = 0x28132687, esp = 0xbfbfed3c, ebp = 0x1 --- Uptime: 25m7s Rebooting...