lockdep: bdev->bd_mutex deadlock

From: Peter Jones
Date: Thu Jul 06 2006 - 17:30:11 EST

Next message: Ryan Richter: "Bad page state, slow disk with 2.6.17.3"
Previous message: Siddha, Suresh B: "Re: cpuinfo_x86 and apicid"
Next in thread: Dave Jones: "Re: lockdep: bdev->bd_mutex deadlock"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]

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
<5>Linux version 2.6.17-1.2356.fc6 (brewbuilder@xxxxxxxxxxxxxxxxxxxxxxxxxxx) (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@xxxxxxxxxx
<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

Next message: Ryan Richter: "Bad page state, slow disk with 2.6.17.3"
Previous message: Siddha, Suresh B: "Re: cpuinfo_x86 and apicid"
Next in thread: Dave Jones: "Re: lockdep: bdev->bd_mutex deadlock"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]