[linux-lvm] Re: INFO: possible recursive locking detected. Was: more than 10 shapshots freeze system
Andre Noll
maan at systemlinux.org
Wed Feb 28 17:45:23 UTC 2007
On 18:47, Andre Noll wrote:
[problems with snapshots]
No response, so here's some more info:
- the problem remains with 2.6.20.1 and 2.6.21-rc2
- Writing to the lv from which a couple of snapshots
were taken results in a kernel panic within minutes
on 2.6.21-rc2. Screenshot:
http://systemlinux.org/~maan/shots/kernel-panic-21-rc2-huangho2.png
- Sometimes the system locks up without panicing. In this case
it still responds to Alt + SysRq, is pingable, but otherwise
dead (impossible to ssh in, no response on keyboard, no disk io)
- 2.6.21-rc2 seems to have dm-related locking problems, see below
Thanks
Andre
[ 0.000000] Linux version 2.6.21-rc2 (root at huangho2) (gcc version 4.0.3 (Ubuntu 4.0.3-1ubuntu5)) #39 SMP Wed Feb 28 14:19:48 CET 2007
[ 0.000000] Command line:
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: 0000000000000000 - 000000000009f400 (usable)
[ 0.000000] BIOS-e820: 000000000009f400 - 00000000000a0000 (reserved)
[ 0.000000] BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
[ 0.000000] BIOS-e820: 0000000000100000 - 00000000f57f8000 (usable)
[ 0.000000] BIOS-e820: 00000000f57f8000 - 00000000f5800000 (ACPI data)
[ 0.000000] BIOS-e820: 00000000fdc00000 - 00000000fdc01000 (reserved)
[ 0.000000] BIOS-e820: 00000000fdc10000 - 00000000fdc11000 (reserved)
[ 0.000000] BIOS-e820: 00000000fec00000 - 00000000fec01000 (reserved)
[ 0.000000] BIOS-e820: 00000000fec10000 - 00000000fec11000 (reserved)
[ 0.000000] BIOS-e820: 00000000fec20000 - 00000000fec21000 (reserved)
[ 0.000000] BIOS-e820: 00000000fee00000 - 00000000fee10000 (reserved)
[ 0.000000] BIOS-e820: 00000000ff800000 - 0000000100000000 (reserved)
[ 0.000000] BIOS-e820: 0000000100000000 - 000000013ffff000 (usable)
[ 0.000000] Entering add_active_range(0, 0, 159) 0 entries of 3200 used
[ 0.000000] Entering add_active_range(0, 256, 1005560) 1 entries of 3200 used
[ 0.000000] Entering add_active_range(0, 1048576, 1310719) 2 entries of 3200 used
[ 0.000000] end_pfn_map = 1310719
[ 0.000000] DMI 2.3 present.
[ 0.000000] ACPI: RSDP 000F4F20, 0024 (r2 HP )
[ 0.000000] ACPI: XSDT F57F83E0, 0044 (r1 HP A05 2 Ò 162E)
[ 0.000000] ACPI: FACP F57F8460, 00F4 (r3 HP A05 2 Ò 162E)
[ 0.000000] ACPI: DSDT F57F8560, 422D (r1 HP DSDT 1 MSFT 2000001)
[ 0.000000] ACPI: FACS F57F80C0, 0040
[ 0.000000] ACPI: APIC F57F8100, 00B8 (r1 HP 00000083 2 0)
[ 0.000000] ACPI: SPCR F57F81E0, 0050 (r1 HP SPCRRBSU 1 Ò 162E)
[ 0.000000] ACPI: SRAT F57F8260, 0150 (r1 HP A05 1 0)
[ 0.000000] SRAT: PXM 1 -> APIC 0 -> Node 0
[ 0.000000] SRAT: PXM 2 -> APIC 2 -> Node 1
[ 0.000000] ACPI: [SRAT:0x00] ignored 4 entries of 8 found
[ 0.000000] SRAT: Node 0 PXM 1 0-c0000000
[ 0.000000] Entering add_active_range(0, 0, 159) 0 entries of 3200 used
[ 0.000000] Entering add_active_range(0, 256, 786432) 1 entries of 3200 used
[ 0.000000] SRAT: Node 1 PXM 2 c0000000-140000000
[ 0.000000] Entering add_active_range(1, 786432, 1005560) 2 entries of 3200 used
[ 0.000000] Entering add_active_range(1, 1048576, 1310719) 3 entries of 3200 used
[ 0.000000] NUMA: Using 30 for the hash shift.
[ 0.000000] Bootmem setup node 0 0000000000000000-00000000c0000000
[ 0.000000] Bootmem setup node 1 00000000c0000000-000000013ffff000
[ 0.000000] Zone PFN ranges:
[ 0.000000] DMA 0 -> 4096
[ 0.000000] DMA32 4096 -> 1048576
[ 0.000000] Normal 1048576 -> 1310719
[ 0.000000] early_node_map[4] active PFN ranges
[ 0.000000] 0: 0 -> 159
[ 0.000000] 0: 256 -> 786432
[ 0.000000] 1: 786432 -> 1005560
[ 0.000000] 1: 1048576 -> 1310719
[ 0.000000] On node 0 totalpages: 786335
[ 0.000000] DMA zone: 88 pages used for memmap
[ 0.000000] DMA zone: 2643 pages reserved
[ 0.000000] DMA zone: 1268 pages, LIFO batch:0
[ 0.000000] DMA32 zone: 16808 pages used for memmap
[ 0.000000] DMA32 zone: 765528 pages, LIFO batch:31
[ 0.000000] Normal zone: 0 pages used for memmap
[ 0.000000] On node 1 totalpages: 481271
[ 0.000000] DMA zone: 0 pages used for memmap
[ 0.000000] DMA32 zone: 5632 pages used for memmap
[ 0.000000] DMA32 zone: 213496 pages, LIFO batch:31
[ 0.000000] Normal zone: 5631 pages used for memmap
[ 0.000000] Normal zone: 256512 pages, LIFO batch:31
[ 0.000000] ACPI: PM-Timer IO Port: 0x908
[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[ 0.000000] Processor #0 (Bootup-CPU)
[ 0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x02] enabled)
[ 0.000000] Processor #2
[ 0.000000] ACPI: LAPIC (acpi_id[0x04] lapic_id[0x04] disabled)
[ 0.000000] ACPI: LAPIC (acpi_id[0x06] lapic_id[0x06] disabled)
[ 0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled)
[ 0.000000] Processor #1
[ 0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x03] enabled)
[ 0.000000] Processor #3
[ 0.000000] ACPI: LAPIC (acpi_id[0x05] lapic_id[0x05] disabled)
[ 0.000000] ACPI: LAPIC (acpi_id[0x07] lapic_id[0x07] disabled)
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] high edge lint[0x1])
[ 0.000000] ACPI: IOAPIC (id[0x04] address[0xfec00000] gsi_base[0])
[ 0.000000] IOAPIC[0]: apic_id 4, address 0xfec00000, GSI 0-23
[ 0.000000] ACPI: IOAPIC (id[0x05] address[0xfec10000] gsi_base[24])
[ 0.000000] IOAPIC[1]: apic_id 5, address 0xfec10000, GSI 24-27
[ 0.000000] ACPI: IOAPIC (id[0x06] address[0xfec20000] gsi_base[28])
[ 0.000000] IOAPIC[2]: apic_id 6, address 0xfec20000, GSI 28-31
[ 0.000000] ACPI: IOAPIC (id[0x07] address[0xfdc00000] gsi_base[32])
[ 0.000000] IOAPIC[3]: apic_id 7, address 0xfdc00000, GSI 32-35
[ 0.000000] ACPI: IOAPIC (id[0x08] address[0xfdc10000] gsi_base[36])
[ 0.000000] IOAPIC[4]: apic_id 8, address 0xfdc10000, GSI 36-39
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 high edge)
[ 0.000000] ACPI: IRQ0 used by override.
[ 0.000000] ACPI: IRQ2 used by override.
[ 0.000000] ACPI: IRQ9 used by override.
[ 0.000000] Setting APIC routing to flat
[ 0.000000] Using ACPI (MADT) for SMP configuration information
[ 0.000000] Nosave address range: 000000000009f000 - 00000000000a0000
[ 0.000000] Nosave address range: 00000000000a0000 - 00000000000f0000
[ 0.000000] Nosave address range: 00000000000f0000 - 0000000000100000
[ 0.000000] Nosave address range: 00000000f57f8000 - 00000000f5800000
[ 0.000000] Nosave address range: 00000000f5800000 - 00000000fdc00000
[ 0.000000] Nosave address range: 00000000fdc00000 - 00000000fdc01000
[ 0.000000] Nosave address range: 00000000fdc01000 - 00000000fdc10000
[ 0.000000] Nosave address range: 00000000fdc10000 - 00000000fdc11000
[ 0.000000] Nosave address range: 00000000fdc11000 - 00000000fec00000
[ 0.000000] Nosave address range: 00000000fec00000 - 00000000fec01000
[ 0.000000] Nosave address range: 00000000fec01000 - 00000000fec10000
[ 0.000000] Nosave address range: 00000000fec10000 - 00000000fec11000
[ 0.000000] Nosave address range: 00000000fec11000 - 00000000fec20000
[ 0.000000] Nosave address range: 00000000fec20000 - 00000000fec21000
[ 0.000000] Nosave address range: 00000000fec21000 - 00000000fee00000
[ 0.000000] Nosave address range: 00000000fee00000 - 00000000fee10000
[ 0.000000] Nosave address range: 00000000fee10000 - 00000000ff800000
[ 0.000000] Nosave address range: 00000000ff800000 - 0000000100000000
[ 0.000000] Allocating PCI resources starting at f6000000 (gap: f5800000:8400000)
[ 0.000000] PERCPU: Allocating 34176 bytes of per cpu data
[ 0.000000] Built 2 zonelists. Total pages: 1236804
[ 0.000000] Kernel command line:
[ 0.000000] Initializing CPU#0
[ 0.000000] PID hash table entries: 4096 (order: 12, 32768 bytes)
[ 0.000000] Extended CMOS year: 2000
[ 60.011565] time.c: Detected 2205.042 MHz processor.
[ 60.014387] Console: colour VGA+ 80x25
[ 60.027154] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[ 60.027343] ... MAX_LOCKDEP_SUBCLASSES: 8
[ 60.027459] ... MAX_LOCK_DEPTH: 30
[ 60.027585] ... MAX_LOCKDEP_KEYS: 2048
[ 60.027712] ... CLASSHASH_SIZE: 1024
[ 60.027839] ... MAX_LOCKDEP_ENTRIES: 8192
[ 60.027985] ... MAX_LOCKDEP_CHAINS: 16384
[ 60.028112] ... CHAINHASH_SIZE: 8192
[ 60.028239] memory used by lock dependency info: 1648 kB
[ 60.028373] per task-struct memory footprint: 1680 bytes
[ 60.028504] ------------------------
[ 60.028626] | Locking API testsuite:
[ 60.028751] ----------------------------------------------------------------------------
[ 60.028958] | spin |wlock |rlock |mutex | wsem | rsem |
[ 60.029150] --------------------------------------------------------------------------
[ 60.029345] A-A deadlock: ok | ok | ok | ok | ok | ok |
[ 60.030804] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok |
[ 60.032266] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok |
[ 60.033792] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok |
[ 60.035317] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok |
[ 60.036960] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok |
[ 60.038567] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok |
[ 60.040132] double unlock: ok | ok | ok | ok | ok | ok |
[ 60.041467] initialize held: ok | ok | ok | ok | ok | ok |
[ 60.042814] bad unlock order: ok | ok | ok | ok | ok | ok |
[ 60.044216] --------------------------------------------------------------------------
[ 60.044369] recursive read-lock: | ok | | ok |
[ 60.045068] recursive read-lock #2: | ok | | ok |
[ 60.045777] mixed read-write-lock: | ok | | ok |
[ 60.046483] mixed write-read-lock: | ok | | ok |
[ 60.047203] --------------------------------------------------------------------------
[ 60.047355] hard-irqs-on + irq-safe-A/12: ok | ok | ok |
[ 60.048089] soft-irqs-on + irq-safe-A/12: ok | ok | ok |
[ 60.048825] hard-irqs-on + irq-safe-A/21: ok | ok | ok |
[ 60.049586] soft-irqs-on + irq-safe-A/21: ok | ok | ok |
[ 60.050322] sirq-safe-A => hirqs-on/12: ok | ok | ok |
[ 60.051057] sirq-safe-A => hirqs-on/21: ok | ok | ok |
[ 60.051791] hard-safe-A + irqs-on/12: ok | ok | ok |
[ 60.052524] soft-safe-A + irqs-on/12: ok | ok | ok |
[ 60.053259] hard-safe-A + irqs-on/21: ok | ok | ok |
[ 60.054004] soft-safe-A + irqs-on/21: ok | ok | ok |
[ 60.054739] hard-safe-A + unsafe-B #1/123: ok | ok | ok |
[ 60.055503] soft-safe-A + unsafe-B #1/123: ok | ok | ok |
[ 60.056266] hard-safe-A + unsafe-B #1/132: ok | ok | ok |
[ 60.057027] soft-safe-A + unsafe-B #1/132: ok | ok | ok |
[ 60.057799] hard-safe-A + unsafe-B #1/213: ok | ok | ok |
[ 60.058562] soft-safe-A + unsafe-B #1/213: ok | ok | ok |
[ 60.059325] hard-safe-A + unsafe-B #1/231: ok | ok | ok |
[ 60.060079] soft-safe-A + unsafe-B #1/231: ok | ok | ok |
[ 60.060836] hard-safe-A + unsafe-B #1/312: ok | ok | ok |
[ 60.061588] soft-safe-A + unsafe-B #1/312: ok | ok | ok |
[ 60.062331] hard-safe-A + unsafe-B #1/321: ok | ok | ok |
[ 60.063086] soft-safe-A + unsafe-B #1/321: ok | ok | ok |
[ 60.063842] hard-safe-A + unsafe-B #2/123: ok | ok | ok |
[ 60.064597] soft-safe-A + unsafe-B #2/123: ok | ok | ok |
[ 60.065355] hard-safe-A + unsafe-B #2/132: ok | ok | ok |
[ 60.066212] soft-safe-A + unsafe-B #2/132: ok | ok | ok |
[ 60.066970] hard-safe-A + unsafe-B #2/213: ok | ok | ok |
[ 60.067724] soft-safe-A + unsafe-B #2/213: ok | ok | ok |
[ 60.068483] hard-safe-A + unsafe-B #2/231: ok | ok | ok |
[ 60.069231] soft-safe-A + unsafe-B #2/231: ok | ok | ok |
[ 60.069995] hard-safe-A + unsafe-B #2/312: ok | ok | ok |
[ 60.070746] soft-safe-A + unsafe-B #2/312: ok | ok | ok |
[ 60.071506] hard-safe-A + unsafe-B #2/321: ok | ok | ok |
[ 60.072256] soft-safe-A + unsafe-B #2/321: ok | ok | ok |
[ 60.073008] hard-irq lock-inversion/123: ok | ok | ok |
[ 60.073774] soft-irq lock-inversion/123: ok | ok | ok |
[ 60.074534] hard-irq lock-inversion/132: ok | ok | ok |
[ 60.075286] soft-irq lock-inversion/132: ok | ok | ok |
[ 60.077757] hard-irq lock-inversion/213: ok | ok | ok |
[ 60.078512] soft-irq lock-inversion/213: ok | ok | ok |
[ 60.079273] hard-irq lock-inversion/231: ok | ok | ok |
[ 60.080021] soft-irq lock-inversion/231: ok | ok | ok |
[ 60.080775] hard-irq lock-inversion/312: ok | ok | ok |
[ 60.081539] soft-irq lock-inversion/312: ok | ok | ok |
[ 60.082300] hard-irq lock-inversion/321: ok | ok | ok |
[ 60.083050] soft-irq lock-inversion/321: ok | ok | ok |
[ 60.083804] hard-irq read-recursion/123: ok |
[ 60.084164] soft-irq read-recursion/123: ok |
[ 60.084525] hard-irq read-recursion/132: ok |
[ 60.084894] soft-irq read-recursion/132: ok |
[ 60.085255] hard-irq read-recursion/213: ok |
[ 60.085625] soft-irq read-recursion/213: ok |
[ 60.085986] hard-irq read-recursion/231: ok |
[ 60.086346] soft-irq read-recursion/231: ok |
[ 60.086707] hard-irq read-recursion/312: ok |
[ 60.087067] soft-irq read-recursion/312: ok |
[ 60.087428] hard-irq read-recursion/321: ok |
[ 60.087788] soft-irq read-recursion/321: ok |
[ 60.088166] -------------------------------------------------------
[ 60.088280] Good, all 218 testcases passed! |
[ 60.088386] ---------------------------------
[ 60.093055] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes)
[ 60.100645] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes)
[ 60.103301] Checking aperture...
[ 60.103406] CPU 0: aperture @ 8000000 size 32 MB
[ 60.103513] Aperture too small (32 MB)
[ 60.110468] No AGP bridge found
[ 60.110569] Your BIOS doesn't leave a aperture memory hole
[ 60.110679] Please enable the IOMMU option in the BIOS setup
[ 60.110791] This costs you 64 MB of RAM
[ 60.148054] Mapping aperture over 65536 KB of RAM @ 8000000
[ 60.220565] Memory: 4871608k/5242876k available (3191k kernel code, 198816k reserved, 1506k data, 1560k init)
[ 60.301335] Calibrating delay using timer specific routine.. 4415.63 BogoMIPS (lpj=8831279)
[ 60.302065] Mount-cache hash table entries: 256
[ 60.303162] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line)
[ 60.303280] CPU: L2 Cache: 1024K (64 bytes/line)
[ 60.303388] CPU 0/0 -> Node 0
[ 60.303489] CPU: Physical Processor ID: 0
[ 60.303594] CPU: Processor Core ID: 0
[ 60.303709] Freeing SMP alternatives: 28k freed
[ 60.303831] ACPI: Core revision 20070126
[ 60.354197] Using local APIC timer interrupts.
[ 60.399617] result 12528651
[ 60.399716] Detected 12.528 MHz APIC timer.
[ 60.402631] lockdep: not fixing up alternatives.
[ 60.402859] Booting processor 1/4 APIC 0x2
[ 60.413270] Initializing CPU#1
[ 60.493172] Calibrating delay using timer specific routine.. 4410.33 BogoMIPS (lpj=8820661)
[ 60.493179] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line)
[ 60.493182] CPU: L2 Cache: 1024K (64 bytes/line)
[ 60.493184] CPU 1/2 -> Node 1
[ 60.493186] CPU: Physical Processor ID: 1
[ 60.493187] CPU: Processor Core ID: 0
[ 60.493196] AMD Opteron(tm) Processor 275 stepping 02
[ 60.498040] lockdep: not fixing up alternatives.
[ 60.499068] Booting processor 2/4 APIC 0x1
[ 60.509474] Initializing CPU#2
[ 60.589095] Calibrating delay using timer specific routine.. 4410.36 BogoMIPS (lpj=8820730)
[ 60.589101] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line)
[ 60.589103] CPU: L2 Cache: 1024K (64 bytes/line)
[ 60.589106] CPU 2/1 -> Node 0
[ 60.589108] CPU: Physical Processor ID: 0
[ 60.589109] CPU: Processor Core ID: 1
[ 60.589115] AMD Opteron(tm) Processor 275 stepping 02
[ 60.594169] lockdep: not fixing up alternatives.
[ 60.595207] Booting processor 3/4 APIC 0x3
[ 60.606128] Initializing CPU#3
[ 60.685018] Calibrating delay using timer specific routine.. 4410.35 BogoMIPS (lpj=8820706)
[ 60.685026] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line)
[ 60.685028] CPU: L2 Cache: 1024K (64 bytes/line)
[ 60.685030] CPU 3/3 -> Node 1
[ 60.685032] CPU: Physical Processor ID: 1
[ 60.685034] CPU: Processor Core ID: 1
[ 60.685042] AMD Opteron(tm) Processor 275 stepping 02
[ 60.689051] Brought up 4 CPUs
[ 60.690146] testing NMI watchdog ... OK.
[ 62.181374] migration_cost=342,388
[ 62.182752] NET: Registered protocol family 16
[ 62.183361] ACPI: bus type pci registered
[ 62.183477] PCI: Using configuration type 1
[ 62.199903] ACPI: SSDT F57FD000, 059D (r1 HP SSDT0 1 MSFT 2000001)
[ 62.200597] ACPI: SSDT F57FD700, 059D (r1 HP SSDT1 1 MSFT 2000001)
[ 62.201286] ACPI: SSDT F57FDE00, 059D (r1 HP SSDT2 1 MSFT 2000001)
[ 62.201978] ACPI: SSDT F57FE500, 059D (r1 HP SSDT3 1 MSFT 2000001)
[ 62.202964] ACPI: Interpreter enabled
[ 62.203068] ACPI: Using IOAPIC for interrupt routing
[ 62.219075] ACPI: PCI Root Bridge [CFG0] (0000:00)
[ 62.219217] PCI: Probing PCI hardware (bus 00)
[ 62.220855] Boot video device is 0000:01:03.0
[ 62.221528] ACPI: PCI Interrupt Routing Table [\_SB_.CFG0._PRT]
[ 62.221804] ACPI: PCI Interrupt Routing Table [\_SB_.CFG0.PCI0._PRT]
[ 62.222245] ACPI: PCI Interrupt Routing Table [\_SB_.CFG0.PCI1._PRT]
[ 62.222498] ACPI: PCI Interrupt Routing Table [\_SB_.CFG0.PCI2._PRT]
[ 62.233327] ACPI: PCI Root Bridge [CFG1] (0000:04)
[ 62.233440] PCI: Probing PCI hardware (bus 04)
[ 62.234321] ACPI: PCI Interrupt Routing Table [\_SB_.CFG1.PCI3._PRT]
[ 62.235257] ACPI: PCI Interrupt Routing Table [\_SB_.CFG1.PCI4._PRT]
[ 62.238167] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 5 7 *10 11)
[ 62.238843] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 5 7 10 *11)
[ 62.239507] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 5 7 10 11) *0, disabled.
[ 62.240433] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 *5 7 10 11)
[ 62.241164] Linux Plug and Play Support v0.97 (c) Adam Belay
[ 62.241307] pnp: PnP ACPI init
[ 62.251012] pnp: PnP ACPI: found 10 devices
[ 62.252581] SCSI subsystem initialized
[ 62.252972] usbcore: registered new interface driver usbfs
[ 62.253229] usbcore: registered new interface driver hub
[ 62.253505] usbcore: registered new device driver usb
[ 62.253734] PCI: Using ACPI for IRQ routing
[ 62.253840] PCI: If a device doesn't work, try "pci=routeirq". If it helps, post a report
[ 62.254447] PCI-DMA: Disabling AGP.
[ 62.254604] PCI-DMA: aperture base @ 8000000 size 65536 KB
[ 62.254715] PCI-DMA: using GART IOMMU.
[ 62.254820] PCI-DMA: Reserving 64MB of IOMMU area in the AGP aperture
[ 62.255163] pnp: 00:01: ioport range 0x230-0x233 has been reserved
[ 62.255279] pnp: 00:01: ioport range 0x260-0x267 has been reserved
[ 62.255395] pnp: 00:01: ioport range 0x4d0-0x4d1 has been reserved
[ 62.256784] PCI: Bridge: 0000:00:03.0
[ 62.256889] IO window: 4000-4fff
[ 62.256996] MEM window: f5f00000-f7cfffff
[ 62.257106] PREFETCH window: f8000000-f80fffff
[ 62.257219] PCI: Bridge: 0000:00:07.0
[ 62.257323] IO window: 5000-5fff
[ 62.257428] MEM window: f7d00000-f7dfffff
[ 62.257535] PREFETCH window: f8100000-f81fffff
[ 62.257664] PCI: Bridge: 0000:00:08.0
[ 62.257767] IO window: disabled.
[ 62.257872] MEM window: f7e00000-f7efffff
[ 62.257979] PREFETCH window: disabled.
[ 62.258131] PCI: Bridge: 0000:04:09.0
[ 62.258235] IO window: 6000-6fff
[ 62.258340] MEM window: f7f00000-f7ffffff
[ 62.258448] PREFETCH window: f8200000-f82fffff
[ 62.258557] PCI: Bridge: 0000:04:0a.0
[ 62.258678] IO window: disabled.
[ 62.258783] MEM window: disabled.
[ 62.258887] PREFETCH window: disabled.
[ 62.259225] NET: Registered protocol family 2
[ 62.295814] IP route cache hash table entries: 262144 (order: 9, 2097152 bytes)
[ 62.297336] TCP established hash table entries: 65536 (order: 10, 4194304 bytes)
[ 62.308707] TCP bind hash table entries: 65536 (order: 9, 3670016 bytes)
[ 62.316827] TCP: Hash tables configured (established 65536 bind 65536)
[ 62.316969] TCP reno registered
[ 62.378385] audit: initializing netlink socket (disabled)
[ 62.378554] audit(1172669747.284:1): initialized
[ 62.378908] Total HugeTLB memory allocated, 0
[ 62.379768] VFS: Disk quotas dquot_6.5.1
[ 62.379919] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 62.380569] SGI XFS with large block/inode numbers, no debug enabled
[ 62.381983] io scheduler noop registered
[ 62.382148] io scheduler anticipatory registered
[ 62.382315] io scheduler deadline registered
[ 62.382557] io scheduler cfq registered (default)
[ 62.417265] input: Power Button (FF) as /class/input/input0
[ 62.417388] ACPI: Power Button (FF) [PWRF]
[ 62.428417] Generic RTC Driver v1.07
[ 62.428717] AMD768 RNG detected
[ 62.429097] Linux agpgart interface v0.102 (c) Dave Jones
[ 62.429209] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
[ 62.429733] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[ 62.433509] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
[ 62.434909] 00:07: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[ 62.438010] RAMDISK driver initialized: 16 RAM disks of 65536K size 1024 blocksize
[ 62.438161] HP CISS Driver (v 3.6.14)
[ 62.438623] ACPI: PCI Interrupt 0000:02:04.0[A] -> GSI 24 (level, low) -> IRQ 24
[ 62.519482] cciss0: <0x46> at PCI 0000:02:04.0 IRQ 24 using DAC
[ 62.535450] blocks= 142253280 block_size= 512
[ 62.543443] heads=255, sectors=32, cylinders=17433
[ 62.543445]
[ 62.551435] blocks= 142253280 block_size= 512
[ 62.559430] heads=255, sectors=32, cylinders=17433
[ 62.559431]
[ 62.561641] blocks= 142253280 block_size= 512
[ 62.561837] heads=255, sectors=32, cylinders=17433
[ 62.561838]
[ 62.562089] cciss/c0d0: p1 p2 p3
[ 62.572705] blocks= 142253280 block_size= 512
[ 62.572908] heads=255, sectors=32, cylinders=17433
[ 62.572909]
[ 62.573131] cciss/c0d1: p1 p2 p3
[ 62.580161] Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
[ 62.580279] ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
[ 62.580511] AMD8111: IDE controller at PCI slot 0000:00:04.1
[ 62.580636] AMD8111: chipset revision 3
[ 62.580740] AMD8111: not 100% native mode: will probe irqs later
[ 62.580861] AMD8111: 0000:00:04.1 (rev 03) UDMA133 controller
[ 62.580981] ide0: BM-DMA at 0x2000-0x2007, BIOS settings: hda:pio, hdb:pio
[ 62.581253] Probing IDE interface ide0...
[ 63.374939] hda: TSSTcorpCD-ROM TS-L162C, ATAPI CD/DVD-ROM drive
[ 64.051365] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
[ 64.054275] hda: ATAPI 24X CD-ROM drive, 96kB Cache, UDMA(33)
[ 64.054742] Uniform CD-ROM driver Revision: 3.20
[ 64.059130] Loading iSCSI transport class v2.0-724.
[ 64.059576] ohci_hcd: 2006 August 04 USB 1.1 'Open' Host Controller (OHCI) Driver
[ 64.059745] ACPI: PCI Interrupt 0000:01:00.0[D] -> GSI 19 (level, low) -> IRQ 19
[ 64.059977] ohci_hcd 0000:01:00.0: OHCI Host Controller
[ 64.060598] ohci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[ 64.060776] ohci_hcd 0000:01:00.0: irq 19, io mem 0xf7cf0000
[ 64.120747] usb usb1: configuration #1 chosen from 1 choice
[ 64.121075] hub 1-0:1.0: USB hub found
[ 64.121216] hub 1-0:1.0: 3 ports detected
[ 64.226637] ACPI: PCI Interrupt 0000:01:00.1[D] -> GSI 19 (level, low) -> IRQ 19
[ 64.226866] ohci_hcd 0000:01:00.1: OHCI Host Controller
[ 64.227141] ohci_hcd 0000:01:00.1: new USB bus registered, assigned bus number 2
[ 64.227310] ohci_hcd 0000:01:00.1: irq 19, io mem 0xf7ce0000
[ 64.288399] usb usb2: configuration #1 chosen from 1 choice
[ 64.288670] hub 2-0:1.0: USB hub found
[ 64.288787] hub 2-0:1.0: 3 ports detected
[ 64.394428] usbcore: registered new interface driver hiddev
[ 64.394672] usbcore: registered new interface driver usbhid
[ 64.394786] drivers/usb/input/hid-core.c: v2.6:USB HID core driver
[ 64.395187] PNP: PS/2 Controller [PNP0303:KBD,PNP0f0e:PS2M] at 0x60,0x64 irq 1,12
[ 64.397164] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 64.397331] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 64.397800] mice: PS/2 mouse device common for all mice
[ 64.439516] input: AT Translated Set 2 keyboard as /class/input/input1
[ 64.444884] md: raid0 personality registered for level 0
[ 64.444996] md: raid1 personality registered for level 1
[ 64.445400] device-mapper: ioctl: 4.11.0-ioctl (2006-10-12) initialised: dm-devel at redhat.com
[ 64.446079] TCP cubic registered
[ 64.446220] NET: Registered protocol family 1
[ 64.446758] Freeing unused kernel memory: 1560k freed
[ 64.448226] Write protecting the kernel read-only data: 1157k
[ 64.450003] Time: acpi_pm clocksource has been installed.
[ 64.500964] md: md0 stopped.
[ 64.544656] md: bind<cciss/c0d1p1>
[ 64.545204] md: bind<cciss/c0d0p1>
[ 64.545949] raid1: raid set md0 active with 2 out of 2 mirrors
[ 64.548036] md: md1 stopped.
[ 64.579344] md: bind<cciss/c0d1p2>
[ 64.579803] md: bind<cciss/c0d0p2>
[ 64.580367] raid1: raid set md1 active with 2 out of 2 mirrors
[ 64.582393] md: md2 stopped.
[ 64.598875] md: bind<cciss/c0d1p3>
[ 64.599325] md: bind<cciss/c0d0p3>
[ 64.599892] raid1: raid set md2 active with 2 out of 2 mirrors
[ 64.625046] kjournald starting. Commit interval 5 seconds
[ 64.625155] EXT3-fs: mounted filesystem with ordered data mode.
[ 68.318788] tg3.c:v3.74 (February 20, 2007)
[ 68.318969] ACPI: PCI Interrupt 0000:03:06.0[A] -> GSI 28 (level, low) -> IRQ 28
[ 68.366156] eth0: Tigon3 [partno(N/A) rev 2100 PHY(5704)] (PCIX:133MHz:64-bit) 10/100/1000Base-T Ethernet 00:18:71:e4:5e:8a
[ 68.366669] eth0: RXcsums[1] LinkChgREG[0] MIirq[0] ASF[1] Split[0] WireSpeed[1] TSOcap[0]
[ 68.366824] eth0: dma_rwctrl[769f4000] dma_mask[64-bit]
[ 68.366995] ACPI: PCI Interrupt 0000:03:06.1[B] -> GSI 29 (level, low) -> IRQ 29
[ 68.414189] eth1: Tigon3 [partno(N/A) rev 2100 PHY(5704)] (PCIX:133MHz:64-bit) 10/100/1000Base-T Ethernet 00:18:71:e4:5e:89
[ 68.414704] eth1: RXcsums[1] LinkChgREG[0] MIirq[0] ASF[0] Split[0] WireSpeed[1] TSOcap[1]
[ 68.414860] eth1: dma_rwctrl[769f4000] dma_mask[64-bit]
[ 68.572438] QLogic Fibre Channel HBA Driver
[ 68.572724] ACPI: PCI Interrupt 0000:05:08.0[A] -> GSI 32 (level, low) -> IRQ 32
[ 68.573262] qla2xxx 0000:05:08.0: Found an ISP2422, irq 32, iobase 0xffffc20000022000
[ 68.573716] qla2xxx 0000:05:08.0: Configuring PCI space...
[ 68.576054] qla2xxx 0000:05:08.0: Configure NVRAM parameters...
[ 68.582688] qla2xxx 0000:05:08.0: Verifying loaded RISC code...
[ 68.666290] PM: Writing back config space on device 0000:03:06.1 at offset b (was 164814e4, writing d00e11)
[ 68.666307] PM: Writing back config space on device 0000:03:06.1 at offset 3 (was 804000, writing 804010)
[ 68.666312] PM: Writing back config space on device 0000:03:06.1 at offset 2 (was 2000000, writing 2000010)
[ 68.666318] PM: Writing back config space on device 0000:03:06.1 at offset 1 (was 2b00000, writing 2b00146)
[ 68.848753] Adding 6540152k swap on /dev/md2. Priority:-1 extents:1 across:6540152k
[ 68.918029] qla2xxx 0000:05:08.0: Allocated (64 KB) for EFT...
[ 68.918352] qla2xxx 0000:05:08.0: Allocated (1413 KB) for firmware dump...
[ 68.938105] scsi0 : qla2xxx
[ 69.029240] EXT3 FS on md1, internal journal
[ 69.071784] qla2xxx 0000:05:08.0:
[ 69.071785] QLogic Fibre Channel HBA Driver: 8.01.07-k5
[ 69.071786] QLogic HP AE369-60001 - QLA2340
[ 69.071787] ISP2422: PCI-X Mode 1 (133 MHz) @ 0000:05:08.0 hdma+, host#=0, fw=4.00.27 [IP]
[ 69.072317] ACPI: PCI Interrupt 0000:05:08.1[B] -> GSI 33 (level, low) -> IRQ 33
[ 69.072671] qla2xxx 0000:05:08.1: Found an ISP2422, irq 33, iobase 0xffffc20000028000
[ 69.073179] qla2xxx 0000:05:08.1: Configuring PCI space...
[ 69.075480] qla2xxx 0000:05:08.1: Configure NVRAM parameters...
[ 69.082057] qla2xxx 0000:05:08.1: Verifying loaded RISC code...
[ 69.425615] qla2xxx 0000:05:08.1: Allocated (64 KB) for EFT...
[ 69.425949] qla2xxx 0000:05:08.1: Allocated (1413 KB) for firmware dump...
[ 69.445950] scsi1 : qla2xxx
[ 69.570607] PM: Writing back config space on device 0000:03:06.0 at offset b (was 164814e4, writing d00e11)
[ 69.570627] PM: Writing back config space on device 0000:03:06.0 at offset 3 (was 804000, writing 804010)
[ 69.570634] PM: Writing back config space on device 0000:03:06.0 at offset 2 (was 2000000, writing 2000010)
[ 69.570641] PM: Writing back config space on device 0000:03:06.0 at offset 1 (was 2b00000, writing 2b00146)
[ 69.595597] qla2xxx 0000:05:08.1:
[ 69.595599] QLogic Fibre Channel HBA Driver: 8.01.07-k5
[ 69.595600] QLogic HP AE369-60001 - QLA2340
[ 69.595601] ISP2422: PCI-X Mode 1 (133 MHz) @ 0000:05:08.1 hdma+, host#=1, fw=4.00.27 [IP]
[ 69.761182] qla2xxx 0000:05:08.0: LIP reset occured (f8f7).
[ 69.796520] qla2xxx 0000:05:08.0: LIP occured (f8f7).
[ 69.798698] qla2xxx 0000:05:08.0: LOOP UP detected (2 Gbps).
[ 69.940110] scsi 0:0:0:0: Direct-Access transtec T6100F16R1-E 342I PQ: 0 ANSI: 5
[ 69.940785] sda : very big device. try to use READ CAPACITY(16).
[ 69.940999] SCSI device sda: 11714863104 512-byte hdwr sectors (5998010 MB)
[ 69.941386] sda: Write Protect is off
[ 69.941491] sda: Mode Sense: 9b 00 00 08
[ 69.941794] SCSI device sda: write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[ 69.942531] sda : very big device. try to use READ CAPACITY(16).
[ 69.942737] SCSI device sda: 11714863104 512-byte hdwr sectors (5998010 MB)
[ 69.943093] sda: Write Protect is off
[ 69.943198] sda: Mode Sense: 9b 00 00 08
[ 69.943483] SCSI device sda: write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[ 69.943665] sda: unknown partition table
[ 69.944175] sd 0:0:0:0: Attached scsi disk sda
[ 69.944491] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 70.206428] qla2xxx 0000:05:08.1: LIP reset occured (f7f7).
[ 70.241834] qla2xxx 0000:05:08.1: LIP occured (f7f7).
[ 70.244010] qla2xxx 0000:05:08.1: LOOP UP detected (2 Gbps).
[ 70.447043] scsi 1:0:0:0: Direct-Access ADVUNI OXYGENRAID 416F 341B PQ: 0 ANSI: 3
[ 70.447653] sdb : very big device. try to use READ CAPACITY(16).
[ 70.447918] SCSI device sdb: 9370656768 512-byte hdwr sectors (4797776 MB)
[ 70.448369] sdb: Write Protect is off
[ 70.448475] sdb: Mode Sense: 8f 00 00 08
[ 70.448939] SCSI device sdb: write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[ 70.449468] sdb : very big device. try to use READ CAPACITY(16).
[ 70.449737] SCSI device sdb: 9370656768 512-byte hdwr sectors (4797776 MB)
[ 70.450175] sdb: Write Protect is off
[ 70.450282] sdb: Mode Sense: 8f 00 00 08
[ 70.450738] SCSI device sdb: write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[ 70.450919] sdb: unknown partition table
[ 70.451546] sd 1:0:0:0: Attached scsi disk sdb
[ 70.451809] sd 1:0:0:0: Attached scsi generic sg1 type 0
[ 70.494620] hda: packet command error: status=0x51 { DriveReady SeekComplete Error }
[ 70.496805] hda: packet command error: error=0x54 { AbortedCommand LastFailedSense=0x05 }
[ 70.497134] ide: failed opcode was: unknown
[ 71.057732] NET: Registered protocol family 17
[ 71.274301] device-mapper: table: 238:8: linear: dm-linear: Device lookup failed
[ 71.274461] device-mapper: ioctl: error adding target to table
[ 71.275795] device-mapper: table: 238:8: linear: dm-linear: Device lookup failed
[ 71.275948] device-mapper: ioctl: error adding target to table
[ 71.277527] device-mapper: table: 238:8: linear: dm-linear: Device lookup failed
[ 71.277718] device-mapper: ioctl: error adding target to table
[ 71.279141] device-mapper: table: 238:8: linear: dm-linear: Device lookup failed
[ 71.279332] device-mapper: ioctl: error adding target to table
[ 71.280791] device-mapper: table: 238:8: linear: dm-linear: Device lookup failed
[ 71.280980] device-mapper: ioctl: error adding target to table
[ 71.282711] device-mapper: table: 238:8: linear: dm-linear: Device lookup failed
[ 71.282902] device-mapper: ioctl: error adding target to table
[ 71.284376] device-mapper: table: 238:8: linear: dm-linear: Device lookup failed
[ 71.284574] device-mapper: ioctl: error adding target to table
[ 71.285940] device-mapper: table: 238:8: linear: dm-linear: Device lookup failed
[ 71.286131] device-mapper: ioctl: error adding target to table
[ 71.287512] device-mapper: table: 238:8: linear: dm-linear: Device lookup failed
[ 71.287703] device-mapper: ioctl: error adding target to table
[ 71.289147] device-mapper: table: 238:8: linear: dm-linear: Device lookup failed
[ 71.289336] device-mapper: ioctl: error adding target to table
[ 71.290811] device-mapper: table: 238:8: linear: dm-linear: Device lookup failed
[ 71.291002] device-mapper: ioctl: error adding target to table
[ 71.292474] device-mapper: table: 238:8: linear: dm-linear: Device lookup failed
[ 71.292671] device-mapper: ioctl: error adding target to table
[ 72.122418] tg3: eth2: Link is up at 1000 Mbps, full duplex.
[ 72.122538] tg3: eth2: Flow control is on for TX and on for RX.
[ 72.220661] tg3: eth3: Link is up at 1000 Mbps, full duplex.
[ 72.220779] tg3: eth3: Flow control is off for TX and off for RX.
[ 73.447400] Installing knfsd (copyright (C) 1996 okir at monad.swb.de).
[ 73.486247] kjournald starting. Commit interval 5 seconds
[ 73.503340] EXT3 FS on md0, internal journal
[ 73.503519] EXT3-fs: mounted filesystem with ordered data mode.
[ 77.025062] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
[ 77.034331] NFSD: starting 90-second grace period
[ 712.188710]
[ 712.188713] =============================================
[ 712.188927] [ INFO: possible recursive locking detected ]
[ 712.189041] 2.6.21-rc2 #39
[ 712.189142] ---------------------------------------------
[ 712.189257] lvs/6073 is trying to acquire lock:
[ 712.189368] (&md->io_lock){----}, at: [<ffffffff8049de65>] dm_request+0x25/0x150
[ 712.189771]
[ 712.189772] but task is already holding lock:
[ 712.189977] (&md->io_lock){----}, at: [<ffffffff8049de65>] dm_request+0x25/0x150
[ 712.190374]
[ 712.190374] other info that might help us debug this:
[ 712.190583] 1 lock held by lvs/6073:
[ 712.190708] #0: (&md->io_lock){----}, at: [<ffffffff8049de65>] dm_request+0x25/0x150
[ 712.191230]
[ 712.191231] stack backtrace:
[ 712.191467]
[ 712.191468] Call Trace:
[ 712.191712] [<ffffffff8024aa49>] __lock_acquire+0xae9/0xfa0
[ 712.191855] [<ffffffff80264bb1>] mempool_alloc_slab+0x11/0x20
[ 712.191999] [<ffffffff8028997c>] kmem_cache_alloc+0x11c/0x140
[ 712.192142] [<ffffffff8049de65>] dm_request+0x25/0x150
[ 712.192283] [<ffffffff8024b2cb>] lock_acquire+0x8b/0xc0
[ 712.192423] [<ffffffff8049de65>] dm_request+0x25/0x150
[ 712.192561] [<ffffffff80245109>] down_read+0x39/0x50
[ 712.192698] [<ffffffff8049de65>] dm_request+0x25/0x150
[ 712.192837] [<ffffffff8039ba9e>] generic_make_request+0x17e/0x1a0
[ 712.192983] [<ffffffff8049cd2c>] __map_bio+0x4c/0xb0
[ 712.193119] [<ffffffff8049d5ff>] __split_bio+0x16f/0x3e0
[ 712.193261] [<ffffffff8049df70>] dm_request+0x130/0x150
[ 712.193401] [<ffffffff8039ba9e>] generic_make_request+0x17e/0x1a0
[ 712.193547] [<ffffffff802b9d90>] dio_bio_submit+0x40/0xa0
[ 712.193690] [<ffffffff8039df92>] submit_bio+0xd2/0xe0
[ 712.193826] [<ffffffff802b9dc8>] dio_bio_submit+0x78/0xa0
[ 712.193969] [<ffffffff802bafa8>] __blockdev_direct_IO+0x958/0xbd0
[ 712.194112] [<ffffffff8024ae4f>] __lock_acquire+0xeef/0xfa0
[ 712.194252] [<ffffffff802b9b19>] blkdev_direct_IO+0x49/0x50
[ 712.194392] [<ffffffff802b9a20>] blkdev_get_blocks+0x0/0xb0
[ 712.194531] [<ffffffff802628ad>] generic_file_direct_IO+0xad/0x120
[ 712.194675] [<ffffffff80264223>] generic_file_aio_read+0xd3/0x1d0
[ 712.194818] [<ffffffff8024ae4f>] __lock_acquire+0xeef/0xfa0
[ 712.194963] [<ffffffff8028fd19>] do_sync_read+0xe9/0x140
[ 712.195102] [<ffffffff8024b5b1>] trace_hardirqs_on+0x141/0x180
[ 712.195244] [<ffffffff80241ee0>] autoremove_wake_function+0x0/0x40
[ 712.195390] [<ffffffff8024b5b1>] trace_hardirqs_on+0x141/0x180
[ 712.195536] [<ffffffff8029014b>] vfs_read+0xbb/0x160
[ 712.195674] [<ffffffff80290ae0>] sys_read+0x50/0x90
[ 712.195813] [<ffffffff80209a7e>] system_call+0x7e/0x83
[ 712.195950]
--
The only person who always got his work done by Friday was Robinson Crusoe
-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 189 bytes
Desc: Digital signature
URL: <http://listman.redhat.com/archives/linux-lvm/attachments/20070228/0ea619c8/attachment.sig>
More information about the linux-lvm
mailing list