* lockdep: bdev->bd_mutex deadlock
@ 2006-07-06 21:31 Peter Jones
2006-07-06 22:07 ` Dave Jones
2006-07-07 7:34 ` Arjan van de Ven
0 siblings, 2 replies; 5+ messages in thread
From: Peter Jones @ 2006-07-06 21:31 UTC (permalink / raw)
To: linux-kernel; +Cc: Dave Jones
[-- Attachment #1: Type: text/plain, Size: 1587 bytes --]
I got this traceback from lockdep while running last night's Fedora Core
(rawhide) kernel, which is version 2.6.17-1.2356.fc6 . (I believe it's
based on a fairly current git pull, davej will know for sure.)
I'm not 100% sure if I'm doing something wrong in userland, or is this a
kernel bug, but I'm pretty sure I shouldn't see lockdep complain either
way.
<4>=============================================
<4>[ INFO: possible recursive locking detected ]
<4>---------------------------------------------
<4>anaconda/451 is trying to acquire lock:
<4> (&bdev->bd_mutex){--..}, at: [<c0463f46>] __blkdev_put+0x22/0x123
<4>
<4>but task is already holding lock:
<4> (&bdev->bd_mutex){--..}, at: [<c0464237>] do_open+0x68/0x385
<4>
<4>other info that might help us debug this:
<4>1 lock held by anaconda/451:
<4> #0: (&bdev->bd_mutex){--..}, at: [<c0464237>] do_open+0x68/0x385
<4>
<4>stack backtrace:
<4> [<c040373c>] show_trace_log_lvl+0x54/0xfd
<4> [<c0403cba>] show_trace+0xd/0x10
<4> [<c0403d57>] dump_stack+0x19/0x1b
<4> [<c042c3ba>] __lock_acquire+0x760/0x987
<4> [<c042cb52>] lock_acquire+0x4b/0x6d
<4> [<c05ec50c>] mutex_lock_nested+0xcc/0x243
<4> [<c0463f46>] __blkdev_put+0x22/0x123
<4> [<c0464060>] blkdev_put+0xa/0xc
<4> [<c04644e2>] do_open+0x313/0x385
<4> [<c0464759>] blkdev_open+0x1f/0x48
<4> [<c045bda0>] __dentry_open+0xb8/0x186
<4> [<c045bedc>] nameidata_to_filp+0x1c/0x2e
<4> [<c045bf1d>] do_filp_open+0x2f/0x36
<4> [<c045bf64>] do_sys_open+0x40/0xbb
<4> [<c045c00b>] sys_open+0x16/0x18
<4> [<c0402dc7>] syscall_call+0x7/0xb
The full syslog is attached.
--
Peter
[-- Attachment #2: syslog.txt --]
[-- Type: text/plain, Size: 24069 bytes --]
<5>Linux version 2.6.17-1.2356.fc6 (brewbuilder@hs20-bc2-2.build.redhat.com) (gcc version 4.1.1 20060629 (Red Hat 4.1.1-6)) #1 Thu Jul 6 01:15:09 EDT 2006
<6>BIOS-provided physical RAM map:
<4> BIOS-e820: 0000000000000000 - 000000000009fc00 (usable)
<4> BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved)
<4> BIOS-e820: 00000000000e4000 - 0000000000100000 (reserved)
<4> BIOS-e820: 0000000000100000 - 000000007ff30000 (usable)
<4> BIOS-e820: 000000007ff30000 - 000000007ff40000 (ACPI data)
<4> BIOS-e820: 000000007ff40000 - 000000007fff0000 (ACPI NVS)
<4> BIOS-e820: 000000007fff0000 - 0000000080000000 (reserved)
<4> BIOS-e820: 00000000fff80000 - 0000000100000000 (reserved)
<4>Warning only 896MB will be used.
<4>Use a HIGHMEM enabled kernel.
<5>896MB LOWMEM available.
<4>Using x86 segment limits to approximate NX protection
<7>On node 0 totalpages: 229376
<7> DMA zone: 4096 pages, LIFO batch:0
<7> Normal zone: 225280 pages, LIFO batch:31
<6>DMI 2.3 present.
<7>ACPI: RSDP (v002 ACPIAM ) @ 0x000fa850
<7>ACPI: XSDT (v001 A M I OEMXSDT 0x06000517 MSFT 0x00000097) @ 0x7ff30100
<7>ACPI: FADT (v003 A M I OEMFACP 0x06000517 MSFT 0x00000097) @ 0x7ff30290
<7>ACPI: MADT (v001 A M I OEMAPIC 0x06000517 MSFT 0x00000097) @ 0x7ff30390
<7>ACPI: OEMB (v001 A M I OEMBIOS 0x06000517 MSFT 0x00000097) @ 0x7ff40040
<7>ACPI: DSDT (v001 A0058 A0058002 0x00000002 MSFT 0x0100000d) @ 0x00000000
<6>ACPI: PM-Timer IO Port: 0x808
<4>Allocating PCI resources starting at 88000000 (gap: 80000000:7ff80000)
<4>Detected 2002.597 MHz processor.
<4>Built 1 zonelists. Total pages: 229376
<5>Kernel command line: initrd=pjones1/rawhide-i386.initrd.img --loglevel=debug nokill ksdevice=bootif ks=http://devserv.devel.redhat.com/~pjones/ks/rawhide-i386.ks.cfg noipv6 text BOOT_IMAGE=pjones1/rawhide-i386.vmlinuz BOOTIF=01-00-0e-a6-70-34-22
<6>Enabling fast FPU save and restore... done.
<6>Enabling unmasked SIMD FPU exception support... done.
<6>Initializing CPU#0
<4>CPU 0 irqstacks, hard=c0791000 soft=c0790000
<4>PID hash table entries: 4096 (order: 12, 16384 bytes)
<4>Console: colour VGA+ 80x25
<4>Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
<4>... MAX_LOCKDEP_SUBCLASSES: 8
<4>... MAX_LOCK_DEPTH: 30
<4>... MAX_LOCKDEP_KEYS: 2048
<4>... CLASSHASH_SIZE: 1024
<4>... MAX_LOCKDEP_ENTRIES: 8192
<4>... MAX_LOCKDEP_CHAINS: 8192
<4>... CHAINHASH_SIZE: 4096
<4> memory used by lock dependency info: 696 kB
<4> per task-struct memory footprint: 1200 bytes
<4>Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
<4>Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
<6>Memory: 897048k/917504k available (1984k kernel code, 19932k reserved, 977k data, 196k init, 0k highmem)
<4>Checking if this processor honours the WP bit even in supervisor mode... Ok.
<4>Calibrating delay using timer specific routine.. 4010.40 BogoMIPS (lpj=8020801)
<6>Security Framework v1.0.0 initialized
<6>SELinux: Initializing.
<6>SELinux: Starting in permissive mode
<6>selinux_register_security: Registering secondary module capability
<6>Capability LSM initialized as secondary
<4>Mount-cache hash table entries: 512
<7>CPU: After generic identify, caps: 078bfbff e1d3fbff 00000000 00000000 00000000 00000000 00000000
<7>CPU: After vendor identify, caps: 078bfbff e1d3fbff 00000000 00000000 00000000 00000000 00000000
<6>CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line)
<6>CPU: L2 Cache: 512K (64 bytes/line)
<7>CPU: After all inits, caps: 078bf3ff e1d3fbff 00000000 00000410 00000000 00000000 00000000
<6>Intel machine check architecture supported.
<6>Intel machine check reporting enabled on CPU#0.
<4>CPU: AMD Athlon(tm) 64 Processor 3000+ stepping 00
<6>Checking 'hlt' instruction... OK.
<6>ACPI: Core revision 20060623
<4>ACPI: setting ELCR to 0200 (from 0c28)
<6>checking if image is initramfs... it is
<6>Freeing initrd memory: 6629k freed
<7>PM: Adding info for No Bus:platform
<6>NET: Registered protocol family 16
<6>ACPI: bus type pci registered
<6>PCI: PCI BIOS revision 2.10 entry at 0xf0031, last bus=1
<4>Setting up standard PCI resources
<6>ACPI: Interpreter enabled
<6>ACPI: Using PIC for interrupt routing
<7>PM: Adding info for acpi:acpi
<6>ACPI: PCI Root Bridge [PCI0] (0000:00)
<7>PCI: Probing PCI hardware (bus 00)
<7>PM: Adding info for No Bus:pci0000:00
<6>PCI: enabled onboard AC97/MC97 devices
<7>Boot video device is 0000:01:00.0
<7>ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
<7>PM: Adding info for pci:0000:00:00.0
<7>PM: Adding info for pci:0000:00:01.0
<7>PM: Adding info for pci:0000:00:0a.0
<7>PM: Adding info for pci:0000:00:0d.0
<7>PM: Adding info for pci:0000:00:0e.0
<7>PM: Adding info for pci:0000:00:0f.0
<7>PM: Adding info for pci:0000:00:0f.1
<7>PM: Adding info for pci:0000:00:10.0
<7>PM: Adding info for pci:0000:00:10.1
<7>PM: Adding info for pci:0000:00:10.2
<7>PM: Adding info for pci:0000:00:10.3
<7>PM: Adding info for pci:0000:00:10.4
<7>PM: Adding info for pci:0000:00:11.0
<7>PM: Adding info for pci:0000:00:11.5
<7>PM: Adding info for pci:0000:00:11.6
<7>PM: Adding info for pci:0000:00:18.0
<7>PM: Adding info for pci:0000:00:18.1
<7>PM: Adding info for pci:0000:00:18.2
<7>PM: Adding info for pci:0000:00:18.3
<7>PM: Adding info for pci:0000:01:00.0
<4>ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 7 10 *11 14 15)
<4>ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 7 *10 11 14 15)
<4>ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 *5 7 10 11 14 15)
<4>ACPI: PCI Interrupt Link [LNKD] (IRQs *3 4 5 7 10 11 14 15)
<4>ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 7 10 11 14 15) *0, disabled.
<4>ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 7 10 11 14 15) *0, disabled.
<4>ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 7 10 11 14 15) *0, disabled.
<4>ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 7 10 11 14 15) *0, disabled.
<6>Linux Plug and Play Support v0.97 (c) Adam Belay
<6>pnp: PnP ACPI init
<7>PM: Adding info for No Bus:pnp0
<7>PM: Adding info for pnp:00:00
<7>PM: Adding info for pnp:00:01
<7>PM: Adding info for pnp:00:02
<7>PM: Adding info for pnp:00:03
<7>PM: Adding info for pnp:00:04
<7>PM: Adding info for pnp:00:05
<7>PM: Adding info for pnp:00:06
<7>PM: Adding info for pnp:00:07
<7>PM: Adding info for pnp:00:08
<7>PM: Adding info for pnp:00:09
<7>PM: Adding info for pnp:00:0a
<6>pnp: PnP ACPI: found 11 devices
<6>usbcore: registered new driver usbfs
<6>usbcore: registered new driver hub
<6>PCI: Using ACPI for IRQ routing
<6>PCI: If a device doesn't work, try "pci=routeirq". If it helps, post a report
<6>PCI: Bridge: 0000:00:01.0
<6> IO window: disabled.
<6> MEM window: faf00000-fd0fffff
<6> PREFETCH window: e2e00000-f2dfffff
<7>Device `[P0P1]' is not power manageable<7>PCI: Setting latency timer of device 0000:00:01.0 to 64
<6>NET: Registered protocol family 2
<4>IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
<4>TCP established hash table entries: 65536 (order: 9, 2621440 bytes)
<4>TCP bind hash table entries: 32768 (order: 8, 1441792 bytes)
<6>TCP: Hash tables configured (established 65536 bind 32768)
<6>TCP reno registered
<7>PM: Adding info for platform:pcspkr
<6>apm: BIOS version 1.2 Flags 0x03 (Driver version 1.16ac)
<5>apm: overridden by ACPI.
<4>Initializing RT-Tester: OK
<6>audit: initializing netlink socket (disabled)
<5>audit(1152220559.776:1): initialized
<4>Total HugeTLB memory allocated, 0
<5>VFS: Disk quotas dquot_6.5.1
<4>Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
<6>SELinux: Registering netfilter hooks
<6>Initializing Cryptographic API
<6>io scheduler noop registered
<6>io scheduler anticipatory registered
<6>io scheduler deadline registered
<6>io scheduler cfq registered (default)
<7>PM: Adding info for platform:vesafb.0
<7>PM: Adding info for No Bus:pnp1
<6>isapnp: Scanning for PnP cards...
<6>isapnp: No Plug & Play device found
<6>Real Time Clock Driver v1.12ac
<6>Non-volatile memory driver v1.2
<6>Linux agpgart interface v0.101 (c) Dave Jones
<6>Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
<7>PM: Adding info for platform:serial8250
<6>serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
<6>00:09: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
<7>PM: Adding info for No Bus:isa
<4>RAMDISK driver initialized: 16 RAM disks of 16384K size 1024 blocksize
<6>Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
<6>ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
<6>VP_IDE: IDE controller at PCI slot 0000:00:0f.1
<7>Device `[IDEC]' is not power manageableACPI: PCI Interrupt Link [LNKA] enabled at IRQ 11
<7>PCI: setting IRQ 11 as level-triggered
<6>ACPI: PCI Interrupt 0000:00:0f.1[A] -> Link [LNKA] -> GSI 11 (level, low) -> IRQ 11
<6>PCI: VIA IRQ fixup for 0000:00:0f.1, from 255 to 11
<6>VP_IDE: chipset revision 6
<6>VP_IDE: not 100% native mode: will probe irqs later
<6>VP_IDE: VIA vt8237 (rev 00) IDE UDMA133 controller on pci0000:00:0f.1
<6> ide0: BM-DMA at 0xfc00-0xfc07, BIOS settings: hda:pio, hdb:pio
<6> ide1: BM-DMA at 0xfc08-0xfc0f, BIOS settings: hdc:DMA, hdd:pio
<7>Probing IDE interface ide0...
<7>Probing IDE interface ide1...
<4>hdc: TEAC DW-552G, ATAPI CD/DVD-ROM drive
<7>PM: Adding info for No Bus:ide1
<4>ide1 at 0x170-0x177,0x376 on irq 15
<7>PM: Adding info for ide:1.0
<7>Probing IDE interface ide0...
<4>ide-floppy driver 0.99.newide
<6>usbcore: registered new driver libusual
<6>usbcore: registered new driver hiddev
<6>usbcore: registered new driver usbhid
<6>drivers/usb/input/hid-core.c: v2.6:USB HID core driver
<6>PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12
<7>PM: Adding info for platform:i8042
<6>serio: i8042 AUX port at 0x60,0x64 irq 12
<6>serio: i8042 KBD port at 0x60,0x64 irq 1
<7>PM: Adding info for serio:serio0
<6>mice: PS/2 mouse device common for all mice
<6>md: md driver 0.90.3 MAX_MD_DEVS=256, MD_SB_DISKS=27
<6>md: bitmap version 4.39
<6>TCP bic registered
<6>Initializing IPsec netlink socket
<6>NET: Registered protocol family 1
<6>NET: Registered protocol family 17
<4>Using IPI Shortcut mode
<7>PM: Adding info for serio:serio1
<6>ACPI: (supports S0 S1 S3 S4<6>Time: tsc clocksource has been installed.
<4> S5)
<6>Freeing unused kernel memory: 196k freed
<4>Write protecting the kernel read-only data: 358k
<6>input: AT Translated Set 2 keyboard as /class/input/input0
<6>loop: loaded (max 8 devices)
<6>input: PS/2 Generic Mouse as /class/input/input1
<4>floppy0: no floppy controllers found
<6>BIOS EDD facility v0.16 2004-Jun-25, 1 devices found
<6>input: PC Speaker as /class/input/input2
<6>squashfs: version 3.0 (2006/03/15) Phillip Lougher
<6>hdc: ATAPI 52X DVD-ROM CD-R/RW drive, 2048kB Cache, UDMA(33)
<6>Uniform CD-ROM driver Revision: 3.20
<5>SCSI subsystem initialized
<7>Device `[EHCI]' is not power manageableACPI: PCI Interrupt Link [LNKC] enabled at IRQ 5
<7>PCI: setting IRQ 5 as level-triggered
<6>ACPI: PCI Interrupt 0000:00:10.4[C] -> Link [LNKC] -> GSI 5 (level, low) -> IRQ 5
<6>ehci_hcd 0000:00:10.4: EHCI Host Controller
<6>ehci_hcd 0000:00:10.4: new USB bus registered, assigned bus number 1
<6>ehci_hcd 0000:00:10.4: irq 5, io mem 0xfd900000
<6>ehci_hcd 0000:00:10.4: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
<7>PM: Adding info for usb:usb1
<7>PM: Adding info for No Bus:usbdev1.1_ep00
<6>usb usb1: configuration #1 chosen from 1 choice
<7>PM: Adding info for usb:1-0:1.0
<6>hub 1-0:1.0: USB hub found
<6>hub 1-0:1.0: 8 ports detected
<7>PM: Adding info for No Bus:usbdev1.1_ep81
<7>PM: Adding info for No Bus:usbdev1.1
<6>USB Universal Host Controller Interface driver v3.0
<7>Device `[USB1]' is not power manageable<6>ACPI: PCI Interrupt 0000:00:10.0[A] -> Link [LNKA] -> GSI 11 (level, low) -> IRQ 11
<6>uhci_hcd 0000:00:10.0: UHCI Host Controller
<6>uhci_hcd 0000:00:10.0: new USB bus registered, assigned bus number 2
<6>uhci_hcd 0000:00:10.0: irq 11, io base 0x0000ec00
<7>PM: Adding info for usb:usb2
<7>PM: Adding info for No Bus:usbdev2.1_ep00
<6>usb usb2: configuration #1 chosen from 1 choice
<7>PM: Adding info for usb:2-0:1.0
<6>hub 2-0:1.0: USB hub found
<6>hub 2-0:1.0: 2 ports detected
<7>PM: Adding info for No Bus:usbdev2.1_ep81
<7>PM: Adding info for No Bus:usbdev2.1
<7>Device `[USB2]' is not power manageable<6>ACPI: PCI Interrupt 0000:00:10.1[A] -> Link [LNKA] -> GSI 11 (level, low) -> IRQ 11
<6>uhci_hcd 0000:00:10.1: UHCI Host Controller
<6>uhci_hcd 0000:00:10.1: new USB bus registered, assigned bus number 3
<6>uhci_hcd 0000:00:10.1: irq 11, io base 0x0000b000
<7>PM: Adding info for usb:usb3
<7>PM: Adding info for No Bus:usbdev3.1_ep00
<6>usb usb3: configuration #1 chosen from 1 choice
<7>PM: Adding info for usb:3-0:1.0
<6>hub 3-0:1.0: USB hub found
<6>hub 3-0:1.0: 2 ports detected
<7>PM: Adding info for No Bus:usbdev3.1_ep81
<7>PM: Adding info for No Bus:usbdev3.1
<7>Device `[USB3]' is not power manageableACPI: PCI Interrupt Link [LNKB] enabled at IRQ 10
<7>PCI: setting IRQ 10 as level-triggered
<6>ACPI: PCI Interrupt 0000:00:10.2[B] -> Link [LNKB] -> GSI 10 (level, low) -> IRQ 10
<6>uhci_hcd 0000:00:10.2: UHCI Host Controller
<6>uhci_hcd 0000:00:10.2: new USB bus registered, assigned bus number 4
<6>uhci_hcd 0000:00:10.2: irq 10, io base 0x0000b400
<7>PM: Adding info for usb:usb4
<7>PM: Adding info for No Bus:usbdev4.1_ep00
<6>usb usb4: configuration #1 chosen from 1 choice
<7>PM: Adding info for usb:4-0:1.0
<6>hub 4-0:1.0: USB hub found
<6>hub 4-0:1.0: 2 ports detected
<7>PM: Adding info for No Bus:usbdev4.1_ep81
<7>PM: Adding info for No Bus:usbdev4.1
<7>Device `[USB4]' is not power manageable<6>ACPI: PCI Interrupt 0000:00:10.3[B] -> Link [LNKB] -> GSI 10 (level, low) -> IRQ 10
<6>uhci_hcd 0000:00:10.3: UHCI Host Controller
<6>uhci_hcd 0000:00:10.3: new USB bus registered, assigned bus number 5
<6>uhci_hcd 0000:00:10.3: irq 10, io base 0x0000b800
<7>PM: Adding info for usb:usb5
<7>PM: Adding info for No Bus:usbdev5.1_ep00
<6>usb usb5: configuration #1 chosen from 1 choice
<7>PM: Adding info for usb:5-0:1.0
<6>hub 5-0:1.0: USB hub found
<6>hub 5-0:1.0: 2 ports detected
<7>PM: Adding info for No Bus:usbdev5.1_ep81
<7>PM: Adding info for No Bus:usbdev5.1
<6>ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LNKB] -> GSI 10 (level, low) -> IRQ 10
<6>skge 1.6 addr 0xfdb00000 irq 10 chip Yukon-Lite rev 7
<6>skge eth0: addr 00:0e:a6:70:34:22
<6>QLogic Fibre Channel HBA Driver
<6>ACPI: PCI Interrupt 0000:00:0d.0[A] -> Link [LNKC] -> GSI 5 (level, low) -> IRQ 5
<6>qla2xxx 0000:00:0d.0: Found an ISP6312, irq 5, iobase 0xf8840000
<6>qla2xxx 0000:00:0d.0: Configuring PCI space...
<6>qla2xxx 0000:00:0d.0: Configure NVRAM parameters...
<6>qla2xxx 0000:00:0d.0: Verifying loaded RISC code...
<4>scsi(0): **** Load RISC code ****
<4>scsi(0): Verifying Checksum of loaded RISC code.
<4>scsi(0): Checksum OK, start firmware.
<6>qla2xxx 0000:00:0d.0: Allocated (412 KB) for firmware dump...
<4>scsi(0): Issue init firmware.
<4>scsi(0): Asynchronous LIP RESET (f8ef).
<6>qla2xxx 0000:00:0d.0: LIP reset occured (f8ef).
<6>qla2xxx 0000:00:0d.0: Waiting for LIP to complete...
<4>scsi(0): LIP occured (f8ef).
<6>qla2xxx 0000:00:0d.0: LIP occured (f8ef).
<4>scsi(0): Asynchronous LOOP UP (2 Gbps).
<6>qla2xxx 0000:00:0d.0: LOOP UP detected (2 Gbps).
<4>scsi(0): Asynchronous PORT UPDATE.
<6>scsi(0): Port database changed ffff 0006 0000.
<4>scsi(0): F/W Ready - OK
<4>scsi(0): fw_state=3 curr time=fffee767.
<6>qla2xxx 0000:00:0d.0: Topology - (Loop), Host Loop address 0x1
<4>scsi(0): Configure loop -- dpc flags =0x4080040
<4>qla2x00_mailbox_command(0): **** FAILED. mbx0=4006, mbx1=7e, mbx2=0, cmd=6a ****
<4>qla2x00_get_port_name(0): failed=102.
<4>scsi(0): MBC_GET_PORT_NAME Failed, No FL Port
<4>scsi(0): LOOP READY
<4>DEBUG: detect hba 0 at address = dfbca47c
<6>scsi0 : qla2xxx
<7>PM: Adding info for No Bus:host0
<6>qla2xxx 0000:00:0d.0:
<4> QLogic Fibre Channel HBA Driver: 8.01.05-k3
<4> QLogic QLA200 -
<4> ISP6312: PCI (33 MHz) @ 0000:00:0d.0 hdma-, host#=0, fw=3.03.20 IPX
<7>PM: Adding info for No Bus:rport-0:0-0
<7>PM: Adding info for No Bus:target0:0:0
<4>ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 3
<7>PCI: setting IRQ 3 as level-triggered
<6>ACPI: PCI Interrupt 0000:00:0e.0[A] -> Link [LNKD] -> GSI 3 (level, low) -> IRQ 3
<6>qla2xxx 0000:00:0e.0: Found an ISP6312, irq 3, iobase 0xf8862000
<6>qla2xxx 0000:00:0e.0: Configuring PCI space...
<6>qla2xxx 0000:00:0e.0: Configure NVRAM parameters...
<6>qla2xxx 0000:00:0e.0: Verifying loaded RISC code...
<4>scsi(1): **** Load RISC code ****
<5> Vendor: DGC Model: RAID 5 Rev: 0219
<5> Type: Direct-Access ANSI SCSI revision: 04
<7>PM: Adding info for scsi:0:0:0:0
<5>SCSI device sda: 1367612160 512-byte hdwr sectors (700217 MB)
<5>sda: Write Protect is off
<7>sda: Mode Sense: 87 00 00 08
<5>SCSI device sda: drive cache: write through
<5>SCSI device sda: 1367612160 512-byte hdwr sectors (700217 MB)
<5>sda: Write Protect is off
<7>sda: Mode Sense: 87 00 00 08
<5>SCSI device sda: drive cache: write through
<6> sda: sda1 sda2
<5>sd 0:0:0:0: Attached scsi disk sda
<4>scsi(1): Verifying Checksum of loaded RISC code.
<4>scsi(1): Checksum OK, start firmware.
<6>qla2xxx 0000:00:0e.0: Allocated (412 KB) for firmware dump...
<4>scsi(1): Issue init firmware.
<4>scsi(1): Asynchronous LIP RESET (f8ef).
<6>qla2xxx 0000:00:0e.0: LIP reset occured (f8ef).
<6>qla2xxx 0000:00:0e.0: Waiting for LIP to complete...
<4>scsi(1): LIP occured (f8ef).
<6>qla2xxx 0000:00:0e.0: LIP occured (f8ef).
<4>scsi(1): Asynchronous LOOP UP (2 Gbps).
<6>qla2xxx 0000:00:0e.0: LOOP UP detected (2 Gbps).
<4>scsi(1): Asynchronous PORT UPDATE.
<6>scsi(1): Port database changed ffff 0006 0000.
<4>scsi(1): F/W Ready - OK
<4>scsi(1): fw_state=3 curr time=fffee846.
<6>qla2xxx 0000:00:0e.0: Topology - (Loop), Host Loop address 0x1
<4>scsi(1): Configure loop -- dpc flags =0x4080040
<4>qla2x00_mailbox_command(1): **** FAILED. mbx0=4006, mbx1=7e, mbx2=0, cmd=6a ****
<4>qla2x00_get_port_name(1): failed=102.
<4>scsi(1): MBC_GET_PORT_NAME Failed, No FL Port
<4>scsi(1): LOOP READY
<4>DEBUG: detect hba 1 at address = f769a47c
<6>scsi1 : qla2xxx
<7>PM: Adding info for No Bus:host1
<6>qla2xxx 0000:00:0e.0:
<4> QLogic Fibre Channel HBA Driver: 8.01.05-k3
<4> QLogic QLA200 -
<4> ISP6312: PCI (33 MHz) @ 0000:00:0e.0 hdma-, host#=1, fw=3.03.20 IPX
<7>PM: Adding info for No Bus:rport-1:0-0
<7>PM: Adding info for No Bus:target1:0:0
<5> Vendor: DGC Model: RAID 5 Rev: 0219
<5> Type: Direct-Access ANSI SCSI revision: 04
<7>PM: Adding info for scsi:1:0:0:0
<5>SCSI device sdb: 1367612160 512-byte hdwr sectors (700217 MB)
<5>sdb: Write Protect is off
<7>sdb: Mode Sense: 87 00 00 08
<5>SCSI device sdb: drive cache: write through
<5>SCSI device sdb: 1367612160 512-byte hdwr sectors (700217 MB)
<5>sdb: Write Protect is off
<7>sdb: Mode Sense: 87 00 00 08
<5>SCSI device sdb: drive cache: write through
<6> sdb: sdb1 sdb2
<5>sd 1:0:0:0: Attached scsi disk sdb
<7>libata version 2.00 loaded.
<7>sata_via 0000:00:0f.0: version 2.0
<6>ACPI: PCI Interrupt 0000:00:0f.0[B] -> Link [LNKB] -> GSI 10 (level, low) -> IRQ 10
<6>sata_via 0000:00:0f.0: routed to hard irq line 10
<6>ata1: SATA max UDMA/133 cmd 0xE800 ctl 0xE402 bmdma 0xD400 irq 10
<6>ata2: SATA max UDMA/133 cmd 0xE000 ctl 0xD802 bmdma 0xD408 irq 10
<6>scsi2 : sata_via
<7>PM: Adding info for No Bus:host2
<6>ata1: SATA link down (SStatus 0 SControl 310)
<4>ATA: abnormal status 0x7F on port 0xE807
<6>scsi3 : sata_via
<7>PM: Adding info for No Bus:host3
<6>ata2: SATA link down (SStatus 0 SControl 310)
<4>ATA: abnormal status 0x7F on port 0xE007
<6>skge eth0: enabling interface
<6>skge eth0: Link is up at 1000 Mbps, full duplex, flow control tx and rx
<4>Unable to identify CD-ROM format.
<4>VFS: Can't find an ext2 filesystem on dev loop0.
<6>security: 3 users, 6 roles, 1398 types, 148 bools, 1 sens, 256 cats
<6>security: 58 classes, 40314 rules
<6>SELinux: Completing initialization.
<6>SELinux: Setting up existing superblocks.
<6>SELinux: initialized (dev loop0, type squashfs), not configured for labeling
<6>SELinux: initialized (dev 0:13, type nfs), uses genfs_contexts
<6>SELinux: initialized (dev rpc_pipefs, type rpc_pipefs), uses genfs_contexts
<6>SELinux: initialized (dev usbfs, type usbfs), uses genfs_contexts
<6>SELinux: initialized (dev ramfs, type ramfs), uses genfs_contexts
<6>SELinux: initialized (dev ramfs, type ramfs), uses genfs_contexts
<6>SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
<6>SELinux: initialized (dev debugfs, type debugfs), uses genfs_contexts
<6>SELinux: initialized (dev selinuxfs, type selinuxfs), uses genfs_contexts
<6>SELinux: initialized (dev mqueue, type mqueue), uses transition SIDs
<6>SELinux: initialized (dev hugetlbfs, type hugetlbfs), uses genfs_contexts
<6>SELinux: initialized (dev devpts, type devpts), uses transition SIDs
<6>SELinux: initialized (dev eventpollfs, type eventpollfs), uses genfs_contexts
<6>SELinux: initialized (dev inotifyfs, type inotifyfs), uses genfs_contexts
<6>SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
<6>SELinux: initialized (dev futexfs, type futexfs), uses genfs_contexts
<6>SELinux: initialized (dev pipefs, type pipefs), uses task SIDs
<6>SELinux: initialized (dev sockfs, type sockfs), uses task SIDs
<6>SELinux: initialized (dev proc, type proc), uses genfs_contexts
<6>SELinux: initialized (dev bdev, type bdev), uses genfs_contexts
<6>SELinux: initialized (dev rootfs, type rootfs), uses genfs_contexts
<6>SELinux: initialized (dev sysfs, type sysfs), uses genfs_contexts
<5>audit(1152220579.829:2): policy loaded auid=4294967295
<5>audit(1152220579.829:3): avc: granted { load_policy } for pid=418 comm="load_policy" scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:object_r:security_t:s0 tclass=security
<5>audit(1152220579.837:4): avc: denied { transition } for pid=419 comm="loader" name="bash" dev=loop0 ino=6228 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:anaconda_t:s0 tclass=process
<6>md: raid0 personality registered for level 0
<6>md: raid1 personality registered for level 1
<6>JFS: nTxBlock = 7062, nTxLock = 56500
<6>SGI XFS with ACLs, security attributes, large block numbers, no debug enabled
<6>SGI XFS Quota Management subsystem
<6>device-mapper: ioctl: 4.7.0-ioctl (2006-06-24) initialised: dm-devel@redhat.com
<4>
<4>=============================================
<4>[ INFO: possible recursive locking detected ]
<4>---------------------------------------------
<4>anaconda/451 is trying to acquire lock:
<4> (&bdev->bd_mutex){--..}, at: [<c0463f46>] __blkdev_put+0x22/0x123
<4>
<4>but task is already holding lock:
<4> (&bdev->bd_mutex){--..}, at: [<c0464237>] do_open+0x68/0x385
<4>
<4>other info that might help us debug this:
<4>1 lock held by anaconda/451:
<4> #0: (&bdev->bd_mutex){--..}, at: [<c0464237>] do_open+0x68/0x385
<4>
<4>stack backtrace:
<4> [<c040373c>] show_trace_log_lvl+0x54/0xfd
<4> [<c0403cba>] show_trace+0xd/0x10
<4> [<c0403d57>] dump_stack+0x19/0x1b
<4> [<c042c3ba>] __lock_acquire+0x760/0x987
<4> [<c042cb52>] lock_acquire+0x4b/0x6d
<4> [<c05ec50c>] mutex_lock_nested+0xcc/0x243
<4> [<c0463f46>] __blkdev_put+0x22/0x123
<4> [<c0464060>] blkdev_put+0xa/0xc
<4> [<c04644e2>] do_open+0x313/0x385
<4> [<c0464759>] blkdev_open+0x1f/0x48
<4> [<c045bda0>] __dentry_open+0xb8/0x186
<4> [<c045bedc>] nameidata_to_filp+0x1c/0x2e
<4> [<c045bf1d>] do_filp_open+0x2f/0x36
<4> [<c045bf64>] do_sys_open+0x40/0xbb
<4> [<c045c00b>] sys_open+0x16/0x18
<4> [<c0402dc7>] syscall_call+0x7/0xb
^ permalink raw reply [flat|nested] 5+ messages in thread
* Re: lockdep: bdev->bd_mutex deadlock
2006-07-06 21:31 lockdep: bdev->bd_mutex deadlock Peter Jones
@ 2006-07-06 22:07 ` Dave Jones
2006-07-07 7:34 ` Arjan van de Ven
1 sibling, 0 replies; 5+ messages in thread
From: Dave Jones @ 2006-07-06 22:07 UTC (permalink / raw)
To: Peter Jones; +Cc: linux-kernel
On Thu, Jul 06, 2006 at 05:31:17PM -0400, Peter Jones wrote:
> I got this traceback from lockdep while running last night's Fedora Core
> (rawhide) kernel, which is version 2.6.17-1.2356.fc6 . (I believe it's
> based on a fairly current git pull, davej will know for sure.)
it's .18rc1.
Dave
--
http://www.codemonkey.org.uk
^ permalink raw reply [flat|nested] 5+ messages in thread
* Re: lockdep: bdev->bd_mutex deadlock
2006-07-06 21:31 lockdep: bdev->bd_mutex deadlock Peter Jones
2006-07-06 22:07 ` Dave Jones
@ 2006-07-07 7:34 ` Arjan van de Ven
2006-07-07 15:43 ` Peter Jones
1 sibling, 1 reply; 5+ messages in thread
From: Arjan van de Ven @ 2006-07-07 7:34 UTC (permalink / raw)
To: Peter Jones; +Cc: mingo, linux-kernel, Dave Jones
> <4> [<c0463f46>] __blkdev_put+0x22/0x123
> <4> [<c0464060>] blkdev_put+0xa/0xc
> <4> [<c04644e2>] do_open+0x313/0x385
> <4> [<c0464759>] blkdev_open+0x1f/0x48
ok this is a missing annotation in the error path
the patch below should fix this, can you verify this?
---
fs/block_dev.c | 2 +-
1 file changed, 1 insertion(+), 1 deletion(-)
Index: linux-2.6.18-rc1/fs/block_dev.c
===================================================================
--- linux-2.6.18-rc1.orig/fs/block_dev.c
+++ linux-2.6.18-rc1/fs/block_dev.c
@@ -980,7 +980,7 @@ out_first:
bdev->bd_disk = NULL;
bdev->bd_inode->i_data.backing_dev_info = &default_backing_dev_info;
if (bdev != bdev->bd_contains)
- blkdev_put(bdev->bd_contains);
+ blkdev_put_partition(bdev->bd_contains);
bdev->bd_contains = NULL;
put_disk(disk);
module_put(owner);
^ permalink raw reply [flat|nested] 5+ messages in thread
* Re: lockdep: bdev->bd_mutex deadlock
2006-07-07 7:34 ` Arjan van de Ven
@ 2006-07-07 15:43 ` Peter Jones
2006-07-11 11:14 ` Arjan van de Ven
0 siblings, 1 reply; 5+ messages in thread
From: Peter Jones @ 2006-07-07 15:43 UTC (permalink / raw)
To: Arjan van de Ven; +Cc: mingo, linux-kernel, Dave Jones
[-- Attachment #1: Type: text/plain, Size: 1594 bytes --]
On Fri, 2006-07-07 at 09:34 +0200, Arjan van de Ven wrote:
> > <4> [<c0463f46>] __blkdev_put+0x22/0x123
> > <4> [<c0464060>] blkdev_put+0xa/0xc
> > <4> [<c04644e2>] do_open+0x313/0x385
> > <4> [<c0464759>] blkdev_open+0x1f/0x48
>
> ok this is a missing annotation in the error path
>
> the patch below should fix this, can you verify this?
Seems to have fixed it. I different one this morning (I'm assuming it's
unrelated):
<4>=============================================
<4>[ INFO: possible recursive locking detected ]
<4>---------------------------------------------
<4>parted/525 is trying to acquire lock:
<4> (&bdev->bd_mutex){--..}, at: [<c05ebd9d>] mutex_lock+0x21/0x24
<4>
<4>but task is already holding lock:
<4> (&bdev->bd_mutex){--..}, at: [<c05ebd9d>] mutex_lock+0x21/0x24
<4>
<4>other info that might help us debug this:
<4>1 lock held by parted/525:
<4> #0: (&bdev->bd_mutex){--..}, at: [<c05ebd9d>] mutex_lock+0x21/0x24
<4>
<4>stack backtrace:
<4> [<c040373c>] show_trace_log_lvl+0x54/0xfd
<4> [<c0403cba>] show_trace+0xd/0x10
<4> [<c0403d57>] dump_stack+0x19/0x1b
<4> [<c042c3ba>] __lock_acquire+0x760/0x987
<4> [<c042cb52>] lock_acquire+0x4b/0x6d
<4> [<c05ebc05>] __mutex_lock_slowpath+0xb6/0x22d
<4> [<c05ebd9d>] mutex_lock+0x21/0x24
<4> [<c04c4acf>] blkdev_ioctl+0x5dd/0x732
<4> [<c046323b>] block_ioctl+0x16/0x1b
<4> [<c046cb99>] do_ioctl+0x19/0x50
<4> [<c046cdd0>] vfs_ioctl+0x200/0x217
<4> [<c046ce2e>] sys_ioctl+0x47/0x61
<4> [<c0402dc7>] syscall_call+0x7/0xb
I'm not sure I can reproduce this one reliably :/ . Again, the whole
dmesg is attached.
--
Peter
[-- Attachment #2: dmesg.txt --]
[-- Type: text/plain, Size: 16717 bytes --]
10 11 12)
<4>ACPI: PCI Interrupt Link [LNKW] (IRQs *48), disabled.
<4>ACPI: PCI Interrupt Link [LNKX] (IRQs *49), disabled.
<4>ACPI: PCI Interrupt Link [LNKY] (IRQs *50), disabled.
<4>ACPI: PCI Interrupt Link [LNKZ] (IRQs *51), disabled.
<4>ACPI: PCI Interrupt Link [LSMB] (IRQs 3 4 5 6 7 10 *11 12)
<4>ACPI: PCI Interrupt Link [LSB0] (IRQs 3 4 *5 6 7 10 11 12)
<4>ACPI: PCI Interrupt Link [LSB2] (IRQs 3 4 5 6 7 *10 11 12)
<4>ACPI: PCI Interrupt Link [LMAC] (IRQs 3 4 5 6 7 10 *11 12)
<4>ACPI: PCI Interrupt Link [LACI] (IRQs 3 4 5 6 7 10 *11 12)
<4>ACPI: PCI Interrupt Link [LMCI] (IRQs 3 4 5 6 7 10 11 12) *0, disabled.
<4>ACPI: PCI Interrupt Link [LIDE] (IRQs 3 4 5 6 7 10 11 12) *0, disabled.
<4>ACPI: PCI Interrupt Link [LSA0] (IRQs 3 4 5 6 7 10 11 12) *0, disabled.
<4>ACPI: PCI Interrupt Link [LSA1] (IRQs 3 4 5 6 7 10 11 12) *0, disabled.
<6>Linux Plug and Play Support v0.97 (c) Adam Belay
<6>pnp: PnP ACPI init
<7>PM: Adding info for No Bus:pnp0
<7>PM: Adding info for pnp:00:00
<7>PM: Adding info for pnp:00:01
<7>PM: Adding info for pnp:00:02
<7>PM: Adding info for pnp:00:03
<7>PM: Adding info for pnp:00:04
<7>PM: Adding info for pnp:00:05
<7>PM: Adding info for pnp:00:06
<7>PM: Adding info for pnp:00:07
<7>PM: Adding info for pnp:00:08
<7>PM: Adding info for pnp:00:09
<7>PM: Adding info for pnp:00:0a
<7>PM: Adding info for pnp:00:0b
<7>PM: Adding info for pnp:00:0c
<7>PM: Adding info for pnp:00:0d
<7>PM: Adding info for pnp:00:0e
<6>pnp: PnP ACPI: found 15 devices
<6>usbcore: registered new driver usbfs
<6>usbcore: registered new driver hub
<6>PCI: Using ACPI for IRQ routing
<6>PCI: If a device doesn't work, try "pci=routeirq". If it helps, post a report
<4>PCI: BIOS reporting unknown device 80:00
<6>pnp: 00:0a: ioport range 0x4d0-0x4d1 has been reserved
<6>pnp: 00:0b: ioport range 0x400-0x47f could not be reserved
<6>pnp: 00:0b: ioport range 0x480-0x48f has been reserved
<6>pnp: 00:0b: ioport range 0xf800-0xf87f could not be reserved
<6>pnp: 00:0b: ioport range 0xf880-0xf8ff has been reserved
<6>pnp: 00:0b: ioport range 0xf900-0xf97f has been reserved
<6>pnp: 00:0b: ioport range 0xf980-0xf9ff has been reserved
<6>pnp: 00:0b: ioport range 0xfa00-0xfa7f has been reserved
<6>pnp: 00:0b: ioport range 0xfa80-0xfaff has been reserved
<6>PCI: Bridge: 0000:00:09.0
<6> IO window: disabled.
<6> MEM window: f2000000-f20fffff
<6> PREFETCH window: disabled.
<3>PCI: Failed to allocate mem resource #6:20000@e0000000 for 0000:0a:00.0
<6>PCI: Bridge: 0000:00:0e.0
<6> IO window: disabled.
<6> MEM window: f0000000-f1ffffff
<6> PREFETCH window: d0000000-dfffffff
<7>PCI: Setting latency timer of device 0000:00:09.0 to 64
<7>Device `[EXPA]' is not power manageable<7>PCI: Setting latency timer of device 0000:00:0e.0 to 64
<6>NET: Registered protocol family 2
<4>IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
<4>TCP established hash table entries: 65536 (order: 9, 2621440 bytes)
<4>TCP bind hash table entries: 32768 (order: 8, 1441792 bytes)
<6>TCP: Hash tables configured (established 65536 bind 32768)
<6>TCP reno registered
<7>PM: Adding info for platform:pcspkr
<6>apm: BIOS not found.
<6>audit: initializing netlink socket (disabled)
<5>audit(1152267705.860:1): initialized
<4>Total HugeTLB memory allocated, 0
<5>VFS: Disk quotas dquot_6.5.1
<4>Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
<6>SELinux: Registering netfilter hooks
<6>Initializing Cryptographic API
<6>io scheduler noop registered
<6>io scheduler anticipatory registered
<6>io scheduler deadline registered
<6>io scheduler cfq registered (default)
<7>PCI: Setting latency timer of device 0000:00:0e.0 to 64
<4>pcie_portdrv_probe->Dev[005d:10de] has invalid IRQ. Check vendor BIOS
<4>assign_interrupt_mode Found MSI capability
<7>Allocate Port Service[0000:00:0e.0:pcie00]
<7>PM: Adding info for pci_express:0000:00:0e.0:pcie00
<7>PM: Adding info for platform:vesafb.0
<3>ACPI: Getting cpuindex for acpiid 0x2
<3>ACPI: Getting cpuindex for acpiid 0x3
<3>ACPI: Getting cpuindex for acpiid 0x4
<7>PM: Adding info for No Bus:pnp1
<6>isapnp: Scanning for PnP cards...
<6>isapnp: No Plug & Play device found
<6>Real Time Clock Driver v1.12ac
<6>Non-volatile memory driver v1.2
<6>Linux agpgart interface v0.101 (c) Dave Jones
<6>Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
<7>PM: Adding info for platform:serial8250
<6>serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
<6>00:07: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
<7>PM: Adding info for No Bus:isa
<4>RAMDISK driver initialized: 16 RAM disks of 16384K size 1024 blocksize
<6>Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
<6>ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
<6>NFORCE-CK804: IDE controller at PCI slot 0000:00:06.0
<7>Device `[IDE]' is not power manageable<6>NFORCE-CK804: chipset revision 242
<6>NFORCE-CK804: not 100% native mode: will probe irqs later
<6>NFORCE-CK804: 0000:00:06.0 (rev f2) UDMA133 controller
<6> ide0: BM-DMA at 0x28a0-0x28a7, BIOS settings: hda:DMA, hdb:pio
<6> ide1: BM-DMA at 0x28a8-0x28af, BIOS settings: hdc:pio, hdd:pio
<7>Probing IDE interface ide0...
<4>hda: PCRW804, ATAPI CD/DVD-ROM drive
<7>PM: Adding info for No Bus:ide0
<4>ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
<7>PM: Adding info for ide:0.0
<7>Probing IDE interface ide1...
<7>Probing IDE interface ide1...
<4>ide-floppy driver 0.99.newide
<6>usbcore: registered new driver libusual
<6>usbcore: registered new driver hiddev
<6>usbcore: registered new driver usbhid
<6>drivers/usb/input/hid-core.c: v2.6:USB HID core driver
<6>PNP: PS/2 Controller [PNP0303:KBD,PNP0f0e:PS2M] at 0x60,0x64 irq 1,12
<7>PM: Adding info for platform:i8042
<6>serio: i8042 AUX port at 0x60,0x64 irq 12
<6>serio: i8042 KBD port at 0x60,0x64 irq 1
<7>PM: Adding info for serio:serio0
<6>mice: PS/2 mouse device common for all mice
<6>md: md driver 0.90.3 MAX_MD_DEVS=256, MD_SB_DISKS=27
<6>md: bitmap version 4.39
<6>TCP bic registered
<6>Initializing IPsec netlink socket
<6>NET: Registered protocol family 1
<6>NET: Registered protocol family 17
<4>Using IPI Shortcut mode
<7>PM: Adding info for serio:serio1
<6>ACPI: (supports S0 S1 S3 S4 S5)
<6>Freeing unused kernel memory: 196k freed
<6>Time: tsc clocksource has been installed.
<4>Write protecting the kernel read-only data: 358k
<6>input: AT Translated Set 2 keyboard as /class/input/input0
<6>loop: loaded (max 8 devices)
<6>input: PS/2 Generic Mouse as /class/input/input1
<4>floppy0: no floppy controllers found
<6>BIOS EDD facility v0.16 2004-Jun-25, 1 devices found
<6>input: PC Speaker as /class/input/input2
<6>squashfs: version 3.0 (2006/03/15) Phillip Lougher
<6>hda: ATAPI 32X CD-ROM CD-R/RW drive, 2048kB Cache, DMA
<6>Uniform CD-ROM driver Revision: 3.20
<5>SCSI subsystem initialized
<7>Device `[USB2]' is not power manageableACPI: PCI Interrupt Link [LSB2] enabled at IRQ 10
<7>PCI: setting IRQ 10 as level-triggered
<6>ACPI: PCI Interrupt 0000:00:02.1[B] -> Link [LSB2] -> GSI 10 (level, low) -> IRQ 10
<7>PCI: Setting latency timer of device 0000:00:02.1 to 64
<6>ehci_hcd 0000:00:02.1: EHCI Host Controller
<6>ehci_hcd 0000:00:02.1: new USB bus registered, assigned bus number 1
<6>ehci_hcd 0000:00:02.1: debug port 1
<7>PCI: cache line size of 64 is not supported by device 0000:00:02.1
<6>ehci_hcd 0000:00:02.1: irq 10, io mem 0xfebf0000
<6>ehci_hcd 0000:00:02.1: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
<7>PM: Adding info for usb:usb1
<7>PM: Adding info for No Bus:usbdev1.1_ep00
<6>usb usb1: configuration #1 chosen from 1 choice
<7>PM: Adding info for usb:1-0:1.0
<6>hub 1-0:1.0: USB hub found
<6>hub 1-0:1.0: 10 ports detected
<7>PM: Adding info for No Bus:usbdev1.1_ep81
<7>PM: Adding info for No Bus:usbdev1.1
<7>ohci_hcd: 2005 April 22 USB 1.1 'Open' Host Controller (OHCI) Driver (PCI)
<7>Device `[USB0]' is not power manageableACPI: PCI Interrupt Link [LSB0] enabled at IRQ 5
<7>PCI: setting IRQ 5 as level-triggered
<6>ACPI: PCI Interrupt 0000:00:02.0[A] -> Link [LSB0] -> GSI 5 (level, low) -> IRQ 5
<7>PCI: Setting latency timer of device 0000:00:02.0 to 64
<6>ohci_hcd 0000:00:02.0: OHCI Host Controller
<6>ohci_hcd 0000:00:02.0: new USB bus registered, assigned bus number 2
<6>ohci_hcd 0000:00:02.0: irq 5, io mem 0xf2100000
<7>PM: Adding info for usb:usb2
<7>PM: Adding info for No Bus:usbdev2.1_ep00
<6>usb usb2: configuration #1 chosen from 1 choice
<7>PM: Adding info for usb:2-0:1.0
<6>hub 2-0:1.0: USB hub found
<6>hub 2-0:1.0: 10 ports detected
<6>usb 1-4: new high speed USB device using ehci_hcd and address 2
<7>PM: Adding info for No Bus:usbdev2.1_ep81
<7>PM: Adding info for No Bus:usbdev2.1
<7>PM: Adding info for usb:1-4
<7>PM: Adding info for No Bus:usbdev1.2_ep00
<6>usb 1-4: configuration #1 chosen from 1 choice
<7>PM: Adding info for usb:1-4:1.0
<7>PM: Adding info for No Bus:usbdev1.2_ep81
<7>PM: Adding info for No Bus:usbdev1.2_ep02
<7>PM: Adding info for usb:1-4:1.1
<7>PM: Adding info for No Bus:hiddev0
<6>hiddev96: USB HID v1.10 Device [DMI WD1600BB-00GUA0 ] on usb-0000:00:02.1-4
<7>PM: Adding info for No Bus:usbdev1.2_ep83
<7>PM: Adding info for No Bus:usbdev1.2
<6>Initializing USB Mass Storage driver...
<6>scsi0 : SCSI emulation for USB Mass Storage devices
<7>PM: Adding info for No Bus:host0
<7>usb-storage: device found at 2
<7>usb-storage: waiting for device to settle before scanning
<6>usbcore: registered new driver usb-storage
<6>USB Mass Storage support registered.
<7>ieee1394: Initialized config rom entry `ip1394'
<4>ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 10
<6>ACPI: PCI Interrupt 0000:05:05.0[A] -> Link [LNKD] -> GSI 10 (level, low) -> IRQ 10
<7>PM: Adding info for ieee1394:fw-host0
<6>ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[10] MMIO=[f2004000-f20047ff] Max Packet=[2048] IR/IT contexts=[4/8]
<7>PM: Adding info for No Bus:target0:0:0
<5> Vendor: DMI Model: WD1600BB-00GUA0 Rev: 3.53
<5> Type: Direct-Access ANSI SCSI revision: 00
<7>PM: Adding info for scsi:0:0:0:0
<5>SCSI device sda: 312581808 512-byte hdwr sectors (160042 MB)
<5>sda: Write Protect is off
<7>sda: Mode Sense: 86 0b 00 02
<3>sda: assuming drive cache: write through
<5>SCSI device sda: 312581808 512-byte hdwr sectors (160042 MB)
<5>sda: Write Protect is off
<7>sda: Mode Sense: 86 0b 00 02
<3>sda: assuming drive cache: write through
<6> sda: sda1 sda4
<5>sd 0:0:0:0: Attached scsi disk sda
<7>PM: Adding info for No Bus:target0:0:1
<7>PM: Removing info for No Bus:target0:0:1
<7>PM: Adding info for No Bus:target0:0:2
<7>PM: Removing info for No Bus:target0:0:2
<7>PM: Adding info for No Bus:target0:0:3
<7>PM: Removing info for No Bus:target0:0:3
<7>PM: Adding info for No Bus:target0:0:4
<7>PM: Removing info for No Bus:target0:0:4
<7>PM: Adding info for No Bus:target0:0:5
<7>PM: Removing info for No Bus:target0:0:5
<7>PM: Adding info for No Bus:target0:0:6
<7>PM: Removing info for No Bus:target0:0:6
<7>PM: Adding info for No Bus:target0:0:7
<7>PM: Removing info for No Bus:target0:0:7
<7>usb-storage: device scan complete
<7>PM: Adding info for ieee1394:0060b00000183041
<7>ieee1394: Host added: ID:BUS[0-00:1023] GUID[0060b00000183041]
<7>PM: Adding info for ieee1394:0060b00000183041-0
<6>forcedeth.c: Reverse Engineered nForce ethernet driver. Version 0.56.
<7>Device `[MAC0]' is not power manageableACPI: PCI Interrupt Link [LMAC] enabled at IRQ 11
<7>PCI: setting IRQ 11 as level-triggered
<6>ACPI: PCI Interrupt 0000:00:0a.0[A] -> Link [LMAC] -> GSI 11 (level, low) -> IRQ 11
<7>PCI: Setting latency timer of device 0000:00:0a.0 to 64
<6>forcedeth: using HIGHDMA
<6>eth0: forcedeth.c: subsystem: 0103c:1500 bound to 0000:00:0a.0
<7>libata version 2.00 loaded.
<4>eth0: no link during initialization.
<6>eth0: link up.
<4>Unable to identify CD-ROM format.
<4>VFS: Can't find an ext2 filesystem on dev loop0.
<6>security: 3 users, 6 roles, 1398 types, 148 bools, 1 sens, 256 cats
<6>security: 58 classes, 40314 rules
<6>SELinux: Completing initialization.
<6>SELinux: Setting up existing superblocks.
<6>SELinux: initialized (dev loop0, type squashfs), not configured for labeling
<6>SELinux: initialized (dev 0:13, type nfs), uses genfs_contexts
<6>SELinux: initialized (dev rpc_pipefs, type rpc_pipefs), uses genfs_contexts
<6>SELinux: initialized (dev usbfs, type usbfs), uses genfs_contexts
<6>SELinux: initialized (dev ramfs, type ramfs), uses genfs_contexts
<6>SELinux: initialized (dev ramfs, type ramfs), uses genfs_contexts
<6>SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
<6>SELinux: initialized (dev debugfs, type debugfs), uses genfs_contexts
<6>SELinux: initialized (dev selinuxfs, type selinuxfs), uses genfs_contexts
<6>SELinux: initialized (dev mqueue, type mqueue), uses transition SIDs
<6>SELinux: initialized (dev hugetlbfs, type hugetlbfs), uses genfs_contexts
<6>SELinux: initialized (dev devpts, type devpts), uses transition SIDs
<6>SELinux: initialized (dev eventpollfs, type eventpollfs), uses genfs_contexts
<6>SELinux: initialized (dev inotifyfs, type inotifyfs), uses genfs_contexts
<6>SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
<6>SELinux: initialized (dev futexfs, type futexfs), uses genfs_contexts
<6>SELinux: initialized (dev pipefs, type pipefs), uses task SIDs
<6>SELinux: initialized (dev sockfs, type sockfs), uses task SIDs
<6>SELinux: initialized (dev proc, type proc), uses genfs_contexts
<6>SELinux: initialized (dev bdev, type bdev), uses genfs_contexts
<6>SELinux: initialized (dev rootfs, type rootfs), uses genfs_contexts
<6>SELinux: initialized (dev sysfs, type sysfs), uses genfs_contexts
<5>audit(1152267732.289:2): policy loaded auid=4294967295
<5>audit(1152267732.289:3): avc: granted { load_policy } for pid=428 comm="load_policy" scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:object_r:security_t:s0 tclass=security
<5>audit(1152267732.305:4): avc: denied { transition } for pid=429 comm="loader" name="bash" dev=loop0 ino=6228 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:anaconda_t:s0 tclass=process
<6>md: raid0 personality registered for level 0
<6>md: raid1 personality registered for level 1
<6>JFS: nTxBlock = 7062, nTxLock = 56501
<6>SGI XFS with ACLs, security attributes, large block numbers, no debug enabled
<6>SGI XFS Quota Management subsystem
<6>device-mapper: ioctl: 4.7.0-ioctl (2006-06-24) initialised: dm-devel@redhat.com
<4>EXT2-fs warning (device dm-0): ext2_fill_super: mounting ext3 filesystem as ext2
<6>SELinux: initialized (dev dm-0, type ext2), uses xattr
<5>SCSI device sda: 312581808 512-byte hdwr sectors (160042 MB)
<5>sda: Write Protect is off
<7>sda: Mode Sense: 86 0b 00 02
<3>sda: assuming drive cache: write through
<6> sda: sda2 sda4
<5>SCSI device sda: 312581808 512-byte hdwr sectors (160042 MB)
<5>sda: Write Protect is off
<7>sda: Mode Sense: 86 0b 00 02
<3>sda: assuming drive cache: write through
<6> sda: sda2 sda4
<5>SCSI device sda: 312581808 512-byte hdwr sectors (160042 MB)
<5>sda: Write Protect is off
<7>sda: Mode Sense: 86 0b 00 02
<3>sda: assuming drive cache: write through
<6> sda: sda3 < sda5 > sda4
<5>SCSI device sda: 312581808 512-byte hdwr sectors (160042 MB)
<5>sda: Write Protect is off
<7>sda: Mode Sense: 86 0b 00 02
<3>sda: assuming drive cache: write through
<6> sda: sda3 < sda5 > sda4
<4>
<4>=============================================
<4>[ INFO: possible recursive locking detected ]
<4>---------------------------------------------
<4>parted/525 is trying to acquire lock:
<4> (&bdev->bd_mutex){--..}, at: [<c05ebd9d>] mutex_lock+0x21/0x24
<4>
<4>but task is already holding lock:
<4> (&bdev->bd_mutex){--..}, at: [<c05ebd9d>] mutex_lock+0x21/0x24
<4>
<4>other info that might help us debug this:
<4>1 lock held by parted/525:
<4> #0: (&bdev->bd_mutex){--..}, at: [<c05ebd9d>] mutex_lock+0x21/0x24
<4>
<4>stack backtrace:
<4> [<c040373c>] show_trace_log_lvl+0x54/0xfd
<4> [<c0403cba>] show_trace+0xd/0x10
<4> [<c0403d57>] dump_stack+0x19/0x1b
<4> [<c042c3ba>] __lock_acquire+0x760/0x987
<4> [<c042cb52>] lock_acquire+0x4b/0x6d
<4> [<c05ebc05>] __mutex_lock_slowpath+0xb6/0x22d
<4> [<c05ebd9d>] mutex_lock+0x21/0x24
<4> [<c04c4acf>] blkdev_ioctl+0x5dd/0x732
<4> [<c046323b>] block_ioctl+0x16/0x1b
<4> [<c046cb99>] do_ioctl+0x19/0x50
<4> [<c046cdd0>] vfs_ioctl+0x200/0x217
<4> [<c046ce2e>] sys_ioctl+0x47/0x61
<4> [<c0402dc7>] syscall_call+0x7/0xb
^ permalink raw reply [flat|nested] 5+ messages in thread
* Re: lockdep: bdev->bd_mutex deadlock
2006-07-07 15:43 ` Peter Jones
@ 2006-07-11 11:14 ` Arjan van de Ven
0 siblings, 0 replies; 5+ messages in thread
From: Arjan van de Ven @ 2006-07-11 11:14 UTC (permalink / raw)
To: Peter Jones; +Cc: mingo, linux-kernel, Dave Jones
On Fri, 2006-07-07 at 11:43 -0400, Peter Jones wrote:
> <4> [<c05ebd9d>] mutex_lock+0x21/0x24
> <4> [<c04c4acf>] blkdev_ioctl+0x5dd/0x732
> <4> [<c046323b>] block_ioctl+0x16/0x1b
From: Arjan van de Ven <arjan@linux.intel.com>
Subject: lockdep: annotate the BLKPG_DEL_PARTITION ioctl
The delete partition IOCTL takes the bd_mutex for both the disk and the partition;
these have an obvious hierarchical relationship and this patch annotates this
relationship for lockdep.
Signed-off-by: Arjan van de Ven <arjan@linux.intel.com>
Index: linux-2.6.18-rc1/block/ioctl.c
===================================================================
--- linux-2.6.18-rc1.orig/block/ioctl.c
+++ linux-2.6.18-rc1/block/ioctl.c
@@ -72,7 +72,7 @@ static int blkpg_ioctl(struct block_devi
bdevp = bdget_disk(disk, part);
if (!bdevp)
return -ENOMEM;
- mutex_lock(&bdevp->bd_mutex);
+ mutex_lock_nested(&bdevp->bd_mutex, BD_MUTEX_PARTITION);
if (bdevp->bd_openers) {
mutex_unlock(&bdevp->bd_mutex);
bdput(bdevp);
@@ -82,7 +82,7 @@ static int blkpg_ioctl(struct block_devi
fsync_bdev(bdevp);
invalidate_bdev(bdevp, 0);
- mutex_lock(&bdev->bd_mutex);
+ mutex_lock_nested(&bdev->bd_mutex, BD_MUTEX_WHOLE);
delete_partition(disk, part);
mutex_unlock(&bdev->bd_mutex);
mutex_unlock(&bdevp->bd_mutex);
^ permalink raw reply [flat|nested] 5+ messages in thread
end of thread, other threads:[~2006-07-11 11:14 UTC | newest]
Thread overview: 5+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2006-07-06 21:31 lockdep: bdev->bd_mutex deadlock Peter Jones
2006-07-06 22:07 ` Dave Jones
2006-07-07 7:34 ` Arjan van de Ven
2006-07-07 15:43 ` Peter Jones
2006-07-11 11:14 ` Arjan van de Ven
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox