All of lore.kernel.org
 help / color / mirror / Atom feed
* WARNING: at block/genhd.c:1474 __disk_unblock_events+0xe1/0xf0() -- should I be concerned?
@ 2012-03-04 14:19 Michael Tokarev
  2012-03-05  6:35 ` Michael Tokarev
  0 siblings, 1 reply; 7+ messages in thread
From: Michael Tokarev @ 2012-03-04 14:19 UTC (permalink / raw)
  To: Linux-kernel

[-- Attachment #1: Type: text/plain, Size: 4886 bytes --]

I booted a 3.0 kernel (i686) on a relatively old hardware,
on a server which was running 2.6.32 stable series for a
long while.

And at the first boot I see the following messages in dmesg:

...
[    2.817314] mptbase: ioc0: Initiating bringup
[    3.230016] ioc0: LSI53C1030 B2: Capabilities={Initiator}
[    4.120665] scsi2 : ioc0: LSI53C1030 B2, FwRev=01000e00h, Ports=1, MaxQ=222, IRQ=27
[    4.489143] mptspi 0000:08:07.1: PCI INT B -> GSI 28 (level, low) -> IRQ 28
[    4.489361] mptbase: ioc1: Initiating bringup
[   19.486696] mptbase: ioc0: WARNING - Issuing Reset from mpt_config!!, doorbell=0x24000000
[   20.126685] mptbase: ioc0: Attempting Retry Config request type 0x4, page 0x1, action 2
[   20.126831] mptbase: ioc0: Retry completed ret=0x0 timeleft=4500
[   20.127697] scsi 2:0:0:0: Direct-Access     IBM-ESXS DTN073C3UCDY10FN S25J PQ: 0 ANSI: 3
...
[   22.604075] sd 2:0:3:0: [sdd] Attached SCSI disk
[   22.635177] ------------[ cut here ]------------
[   22.635290] WARNING: at block/genhd.c:1474 __disk_unblock_events+0xe1/0xf0()
[   22.635371] Hardware name: eserver xSeries 345 -[867052G]-
[   22.635448] Modules linked in: mptspi(+) mptscsih mptbase scsi_transport_spi e1000 ohci_hcd pata_serverworks libata usbhid hid usbcore sd_mod scsi_mod crc_t10dif
[   22.636260] Pid: 271, comm: blkid Not tainted 3.0.0-i686 #3.0.23
[   22.636334] Call Trace:
[   22.636407]  [<c1040118>] ? warn_slowpath_common+0x78/0xb0
[   22.636482]  [<c1188d81>] ? __disk_unblock_events+0xe1/0xf0
[   22.636556]  [<c1188d81>] ? __disk_unblock_events+0xe1/0xf0
[   22.636631]  [<c104016b>] ? warn_slowpath_null+0x1b/0x20
[   22.636705]  [<c1188d81>] ? __disk_unblock_events+0xe1/0xf0
[   22.636783]  [<c1124f57>] ? __blkdev_get+0x157/0x3c0
[   22.636856]  [<c1125203>] ? blkdev_get+0x43/0x2b0
[   22.636931]  [<c11616b0>] ? security_dentry_open+0x70/0x80
[   22.637006]  [<c11245f8>] ? bd_acquire+0x28/0xc0
[   22.637080]  [<c10f70c8>] ? __dentry_open+0x118/0x2b0
[   22.637154]  [<c10f7348>] ? nameidata_to_filp+0x68/0x70
[   22.637228]  [<c1125470>] ? blkdev_get+0x2b0/0x2b0
[   22.637302]  [<c110429d>] ? do_last+0xcd/0x820
[   22.637375]  [<c10fa48b>] ? get_empty_filp+0x11b/0x1d0
[   22.637449]  [<c1105601>] ? path_openat+0xa1/0x370
[   22.637523]  [<c11059b4>] ? do_filp_open+0x34/0x90
[   22.637597]  [<c10464c1>] ? irq_exit+0x31/0xa0
[   22.637672]  [<c12e69d2>] ? call_function_interrupt+0x2a/0x30
[   22.637746]  [<c10f6cce>] ? do_sys_open+0xee/0x1c0
[   22.637820]  [<c10f6e1c>] ? sys_open+0x2c/0x40
[   22.637892]  [<c12e70d8>] ? sysenter_do_call+0x12/0x28
[   22.637963] ---[ end trace 9071b164502805fa ]---
[   22.645185] sd 2:0:2:0: [sdc] Attached SCSI disk
[   22.659254] sd 2:0:4:0: [sde] Attached SCSI disk
[   34.493348] mptbase: ioc1: ERROR - Wait IOC_READY state (0x20000000) timeout(15)!
[   38.883345] ioc1: LSI53C1030 B2: Capabilities={Initiator}
[   39.283343] ioc0: LSI53C1030 B2: Capabilities={Initiator}
[   40.567328] scsi3 : ioc1: LSI53C1030 B2, FwRev=01000e00h, Ports=1, MaxQ=222, IRQ=28
...

This is 3.0.23.  Line 1474 of block/genhd.c is like this:

static void __disk_unblock_events(struct gendisk *disk, bool check_now)
{
        struct disk_events *ev = disk->ev;
...
        if (WARN_ON_ONCE(ev->block <= 0))  <==== this one
                goto out_unlock;
...

The machine has the following SCSI controller:

08:07.1 SCSI storage controller: LSI Logic / Symbios Logic 53c1030 PCI-X Fusion-MPT Dual Ultra320 SCSI (rev 07)
	Subsystem: IBM Device 026c
	Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV+ VGASnoop- ParErr+ Stepping- SERR+ FastB2B- DisINTx-
	Status: Cap+ 66MHz+ UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 72 (4250ns min, 4500ns max), Cache Line Size: 32 bytes
	Interrupt: pin B routed to IRQ 28
	Region 0: I/O ports at 2700 [size=256]
	Region 1: Memory at f9fd0000 (64-bit, non-prefetchable) [size=64K]
	Region 3: Memory at f9fc0000 (64-bit, non-prefetchable) [size=64K]
	[virtual] Expansion ROM at a0200000 [disabled] [size=1M]
	Capabilities: [50] Power Management version 2
		Flags: PMEClk- DSI- D1+ D2+ AuxCurrent=0mA PME(D0-,D1-,D2-,D3hot-,D3cold-)
		Status: D0 NoSoftRst- PME-Enable- DSel=0 DScale=0 PME-
	Capabilities: [58] MSI: Enable- Count=1/1 Maskable- 64bit+
		Address: 0000000000000000  Data: 0000
	Capabilities: [68] PCI-X non-bridge device
		Command: DPERE- ERO- RBC=512 OST=1
		Status: Dev=08:07.1 64bit+ 133MHz+ SCD- USC- DC=simple DMMRBC=2048 DMOST=8 DMCRS=16 RSCEM- 266MHz- 533MHz-
	Kernel driver in use: mptspi

with 5 parallel scsi drives attached to it.  In the kernel
config, SCSI bus asyncronous scanning (CONFIG_SCSI_SCAN_ASYNC)
is enabled.

The system appears to work fine so far, but these messages,
together with a few errors and delays around, are a bit
worrying.  What the problem is, any suggestion?

Complete bootup dmesg is attached.

Thank you!

/mjt

[-- Attachment #2: dmesg --]
[-- Type: text/plain, Size: 46163 bytes --]

[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Linux version 3.0.0-i686 (mjt@titania.msk.rgs-podm.ru) (gcc version 4.4.5 (Debian 4.4.5-8) ) #3.0.23 SMP Thu Mar 1 15:46:05 MSK 2012
[    0.000000] BIOS-provided physical RAM map:
[    0.000000]  BIOS-e820: 0000000000000000 - 000000000009d400 (usable)
[    0.000000]  BIOS-e820: 000000000009d400 - 00000000000a0000 (reserved)
[    0.000000]  BIOS-e820: 00000000000e0000 - 0000000000100000 (reserved)
[    0.000000]  BIOS-e820: 0000000000100000 - 000000009ffeb2c0 (usable)
[    0.000000]  BIOS-e820: 000000009ffeb2c0 - 000000009fff0000 (ACPI data)
[    0.000000]  BIOS-e820: 000000009fff0000 - 00000000a0000000 (reserved)
[    0.000000]  BIOS-e820: 00000000fec00000 - 0000000100000000 (reserved)
[    0.000000] Notice: NX (Execute Disable) protection missing in CPU!
[    0.000000] DMI 2.3 present.
[    0.000000] DMI: IBM eserver xSeries 345 -[867052G]-/, BIOS -[GEE149AUS-1.12]- 07/14/2003
[    0.000000] e820 update range: 0000000000000000 - 0000000000010000 (usable) ==> (reserved)
[    0.000000] e820 remove range: 00000000000a0000 - 0000000000100000 (usable)
[    0.000000] last_pfn = 0x9ffeb max_arch_pfn = 0x100000
[    0.000000] MTRR default type: uncachable
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-BFFFF uncachable
[    0.000000]   C0000-CFFFF write-protect
[    0.000000]   D0000-DFFFF uncachable
[    0.000000]   E0000-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 000000000 mask F80000000 write-back
[    0.000000]   1 base 080000000 mask FE0000000 write-back
[    0.000000]   2 disabled
[    0.000000]   3 disabled
[    0.000000]   4 disabled
[    0.000000]   5 disabled
[    0.000000]   6 disabled
[    0.000000]   7 disabled
[    0.000000] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[    0.000000] found SMP MP-table at [c009d540] 9d540
[    0.000000] initial memory mapped : 0 - 01800000
[    0.000000] Base memory trampoline at [c0099000] 99000 size 16384
[    0.000000] init_memory_mapping: 0000000000000000-00000000377fe000
[    0.000000]  0000000000 - 0000400000 page 4k
[    0.000000]  0000400000 - 0037400000 page 2M
[    0.000000]  0037400000 - 00377fe000 page 4k
[    0.000000] kernel direct mapping tables up to 377fe000 @ 17fb000-1800000
[    0.000000] RAMDISK: 7fe0b000 - 7ffff000
[    0.000000] Allocated new RAMDISK: 3760a000 - 377fd880
[    0.000000] Move RAMDISK from 000000007fe0b000 - 000000007fffe87f to 3760a000 - 377fd87f
[    0.000000] ACPI: RSDP 000fdfc0 00014 (v00 IBM   )
[    0.000000] ACPI: RSDT 9ffeff80 00030 (v01 IBM    SERONYXP 00001000 IBM  45444F43)
[    0.000000] ACPI: FACP 9ffeff00 00074 (v01 IBM    SERONYXP 00001000 IBM  45444F43)
[    0.000000] ACPI: DSDT 9ffeb2c0 04A4E (v01 IBM    SERGEODE 00001000 MSFT 0100000B)
[    0.000000] ACPI: FACS 9ffefe00 00040
[    0.000000] ACPI: APIC 9ffefe40 00092 (v01 IBM    SERONYXP 00001000 IBM  45444F43)
[    0.000000] ACPI: ASF! 9ffefd80 0004B (v16 IBM    SERONYXP 00000001 IBM  45444F43)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] 1671MB HIGHMEM available.
[    0.000000] 887MB LOWMEM available.
[    0.000000]   mapped low ram: 0 - 377fe000
[    0.000000]   low ram: 0 - 377fe000
[    0.000000] Zone PFN ranges:
[    0.000000]   DMA      0x00000010 -> 0x00001000
[    0.000000]   Normal   0x00001000 -> 0x000377fe
[    0.000000]   HighMem  0x000377fe -> 0x0009ffeb
[    0.000000] Movable zone start PFN for each node
[    0.000000] early_node_map[2] active PFN ranges
[    0.000000]     0: 0x00000010 -> 0x0000009d
[    0.000000]     0: 0x00000100 -> 0x0009ffeb
[    0.000000] On node 0 totalpages: 655224
[    0.000000] free_area_init_node: node 0, pgdat c13eb840, node_mem_map f6209200
[    0.000000]   DMA zone: 32 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 3949 pages, LIFO batch:0
[    0.000000]   Normal zone: 1744 pages used for memmap
[    0.000000]   Normal zone: 221486 pages, LIFO batch:31
[    0.000000]   HighMem zone: 3344 pages used for memmap
[    0.000000]   HighMem zone: 424669 pages, LIFO batch:31
[    0.000000] Using APIC driver default
[    0.000000] ACPI: PM-Timer IO Port: 0x488
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x06] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x01] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x07] enabled)
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x00] dfl dfl lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x06] dfl dfl lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x07] dfl dfl lint[0x1])
[    0.000000] ACPI: IOAPIC (id[0x0e] address[0xfec00000] gsi_base[0])
[    0.000000] IOAPIC[0]: apic_id 14, version 17, address 0xfec00000, GSI 0-15
[    0.000000] ACPI: IOAPIC (id[0x0d] address[0xfec01000] gsi_base[16])
[    0.000000] IOAPIC[1]: apic_id 13, version 17, address 0xfec01000, GSI 16-31
[    0.000000] ACPI: IOAPIC (id[0x0c] address[0xfec02000] gsi_base[32])
[    0.000000] IOAPIC[2]: apic_id 12, version 17, address 0xfec02000, GSI 32-47
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ2 used by override.
[    0.000000] ACPI: IRQ7 used by override.
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] SMP: Allowing 4 CPUs, 0 hotplug CPUs
[    0.000000] nr_irqs_gsi: 64
[    0.000000] PM: Registered nosave memory: 000000000009d000 - 000000000009e000
[    0.000000] PM: Registered nosave memory: 000000000009e000 - 00000000000a0000
[    0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000e0000
[    0.000000] PM: Registered nosave memory: 00000000000e0000 - 0000000000100000
[    0.000000] Allocating PCI resources starting at a0000000 (gap: a0000000:5ec00000)
[    0.000000] Booting paravirtualized kernel on bare hardware
[    0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:4 nr_node_ids:1
[    0.000000] PERCPU: Embedded 12 pages/cpu @f5c00000 s25280 r0 d23872 u1048576
[    0.000000] pcpu-alloc: s25280 r0 d23872 u1048576 alloc=1*4194304
[    0.000000] pcpu-alloc: [0] 0 1 2 3 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 650104
[    0.000000] Kernel command line: panic=30 elevator=deadline ipv6.disable=1 initrd=initrd-3.0.0-i686 BOOT_IMAGE=vmlinuz-3.0.0-i686 linux-test
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Initializing CPU#0
[    0.000000] Initializing HighMem for node 0 (000377fe:0009ffeb)
[    0.000000] Memory: 2592400k/2621356k available (2976k kernel code, 28496k reserved, 1061k data, 388k init, 1712052k highmem)
[    0.000000] virtual kernel memory layout:
[    0.000000]     fixmap  : 0xfff16000 - 0xfffff000   ( 932 kB)
[    0.000000]     pkmap   : 0xff800000 - 0xffc00000   (4096 kB)
[    0.000000]     vmalloc : 0xf7ffe000 - 0xff7fe000   ( 120 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xf77fe000   ( 887 MB)
[    0.000000]       .init : 0xc13f2000 - 0xc1453000   ( 388 kB)
[    0.000000]       .data : 0xc12e8162 - 0xc13f1940   (1061 kB)
[    0.000000]       .text : 0xc1000000 - 0xc12e8162   (2976 kB)
[    0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    0.000000] Hierarchical RCU implementation.
[    0.000000] NR_IRQS:512
[    0.000000] CPU 0 irqstacks, hard=f580a000 soft=f580c000
[    0.000000] Console: colour VGA+ 80x25
[    0.000000] console [tty0] enabled
[    0.000000] Fast TSC calibration using PIT
[    0.000000] Detected 2660.640 MHz processor.
[    0.006670] Calibrating delay loop (skipped), value calculated using timer frequency.. 5323.00 BogoMIPS (lpj=8868800)
[    0.006813] pid_max: default: 32768 minimum: 301
[    0.006934] Security Framework initialized
[    0.007030] Mount-cache hash table entries: 512
[    0.007323] Initializing cgroup subsys cpuacct
[    0.007406] Initializing cgroup subsys devices
[    0.007475] Initializing cgroup subsys freezer
[    0.007544] Initializing cgroup subsys net_cls
[    0.007613] Initializing cgroup subsys blkio
[    0.007727] CPU: Physical Processor ID: 0
[    0.007794] CPU: Processor Core ID: 0
[    0.007862] mce: CPU supports 4 MCE banks
[    0.007941] CPU0: Thermal monitoring enabled (TM1)
[    0.010156] ACPI: Core revision 20110413
[    0.013416] Enabling APIC mode:  Flat.  Using 3 I/O APICs
[    0.014114] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.047752] CPU0: Intel(R) Xeon(TM) CPU 2.66GHz stepping 09
[    0.049997] Performance Events: Netburst events, Netburst P4/Xeon PMU driver.
[    0.049997] ... version:                0
[    0.049997] ... bit width:              40
[    0.049997] ... generic registers:      18
[    0.049997] ... value mask:             000000ffffffffff
[    0.049997] ... max period:             0000007fffffffff
[    0.049997] ... fixed-purpose events:   0
[    0.049997] ... event mask:             000000000003ffff
[    0.049997] NMI watchdog enabled, takes one hw-pmu counter.
[    0.049997] CPU 1 irqstacks, hard=f5896000 soft=f5898000
[    0.049997] Booting Node   0, Processors  #1
[    0.049997] smpboot cpu 1: start_ip = 99000
[    0.009999] Initializing CPU#1
[    0.143362] NMI watchdog enabled, takes one hw-pmu counter.
[    0.143666] CPU 2 irqstacks, hard=f58a8000 soft=f58aa000
[    0.143669]  #2
[    0.143720] smpboot cpu 2: start_ip = 99000
[    0.009999] Initializing CPU#2
[    0.240030] NMI watchdog enabled, takes one hw-pmu counter.
[    0.240377] CPU 3 irqstacks, hard=f58d8000 soft=f58da000
[    0.240381]  #3 Ok.
[    0.240446] smpboot cpu 3: start_ip = 99000
[    0.009999] Initializing CPU#3
[    0.336674] NMI watchdog enabled, takes one hw-pmu counter.
[    0.336843] Brought up 4 CPUs
[    0.336913] Total of 4 processors activated (21294.45 BogoMIPS).
[    0.337915] devtmpfs: initialized
[    0.337915] NET: Registered protocol family 16
[    0.337915] ACPI: bus type pci registered
[    0.337915] PCI: PCI BIOS revision 2.10 entry at 0xfd7dc, last bus=8
[    0.337915] PCI: Using configuration type 1 for base access
[    0.337915] bio: create slab <bio-0> at 0
[    0.341500] ACPI: EC: Look up EC in DSDT
[    0.344564] ACPI: Interpreter enabled
[    0.344638] ACPI: (supports S0 S4 S5)
[    0.344860] ACPI: Using IOAPIC for interrupt routing
[    0.350925] HEST: Table not found.
[    0.351001] PCI: Ignoring host bridge windows from ACPI; if necessary, use "pci=use_crs" and report a bug
[    0.352708] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-01])
[    0.355968] pci_root PNP0A03:00: host bridge window [io  0x03c0-0x03df] (ignored)
[    0.355975] pci_root PNP0A03:00: host bridge window [io  0x03b0-0x03bb] (ignored)
[    0.355982] pci_root PNP0A03:00: host bridge window [mem 0x000a0000-0x000bffff] (ignored)
[    0.355988] pci_root PNP0A03:00: host bridge window [mem 0xfc000000-0xffffffff] (ignored)
[    0.355995] pci_root PNP0A03:00: host bridge window [io  0x03e0-0x24ff] (ignored)
[    0.356001] pci_root PNP0A03:00: host bridge window [io  0x03bc-0x03bf] (ignored)
[    0.356006] pci_root PNP0A03:00: host bridge window [io  0x0000-0x03af] (ignored)
[    0.356023] pci 0000:00:00.0: [1166:0014] type 0 class 0x000600
[    0.356066] pci 0000:00:00.1: [1166:0014] type 0 class 0x000600
[    0.356100] pci 0000:00:00.2: [1166:0014] type 0 class 0x000600
[    0.356153] pci 0000:00:06.0: [1002:4752] type 0 class 0x000300
[    0.356176] pci 0000:00:06.0: reg 10: [mem 0xfd000000-0xfdffffff]
[    0.356189] pci 0000:00:06.0: reg 14: [io  0x2400-0x24ff]
[    0.356201] pci 0000:00:06.0: reg 18: [mem 0xfebff000-0xfebfffff]
[    0.356241] pci 0000:00:06.0: reg 30: [mem 0x00000000-0x0001ffff pref]
[    0.356274] pci 0000:00:06.0: supports D1 D2
[    0.356303] pci 0000:00:0f.0: [1166:0201] type 0 class 0x000600
[    0.356362] pci 0000:00:0f.1: [1166:0212] type 0 class 0x000101
[    0.356380] pci 0000:00:0f.1: reg 10: [io  0x01f0-0x01f7]
[    0.356391] pci 0000:00:0f.1: reg 14: [io  0x03f4-0x03f7]
[    0.356402] pci 0000:00:0f.1: reg 18: [io  0x0170-0x0177]
[    0.356414] pci 0000:00:0f.1: reg 1c: [io  0x0374-0x0377]
[    0.356425] pci 0000:00:0f.1: reg 20: [io  0x0700-0x070f]
[    0.356464] pci 0000:00:0f.2: [1166:0220] type 0 class 0x000c03
[    0.356482] pci 0000:00:0f.2: reg 10: [mem 0xfebfe000-0xfebfefff]
[    0.356553] pci 0000:00:0f.3: [1166:0225] type 0 class 0x000601
[    0.356660] pci 0000:00:10.0: [1166:0101] type 0 class 0x000600
[    0.356725] pci 0000:00:10.2: [1166:0101] type 0 class 0x000600
[    0.356796] pci 0000:00:11.0: [1166:0101] type 0 class 0x000600
[    0.356860] pci 0000:00:11.2: [1166:0101] type 0 class 0x000600
[    0.356939] pci_bus 0000:00: on NUMA node 0
[    0.356945] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[    0.357057]  pci0000:00: Unable to request _OSC control (_OSC support mask: 0x1e)
[    0.359272] ACPI: PCI Root Bridge [PCI1] (domain 0000 [bus 02-03])
[    0.359838] pci_bus 0000:02: on NUMA node 0
[    0.359842] ACPI: PCI Interrupt Routing Table [\_SB_.PCI1._PRT]
[    0.359897]  pci0000:02: Unable to request _OSC control (_OSC support mask: 0x1e)
[    0.360441] ACPI: PCI Root Bridge [PCI2] (domain 0000 [bus 04-05])
[    0.361006] pci_bus 0000:04: on NUMA node 0
[    0.361010] ACPI: PCI Interrupt Routing Table [\_SB_.PCI2._PRT]
[    0.361065]  pci0000:04: Unable to request _OSC control (_OSC support mask: 0x1e)
[    0.362036] ACPI: PCI Root Bridge [PCI3] (domain 0000 [bus 06-07])
[    0.363904] pci_root PNP0A03:03: host bridge window [mem 0xfa000000-0xfbffffff] (ignored)
[    0.363912] pci_root PNP0A03:03: host bridge window [io  0x2500-0x257f] (ignored)
[    0.363942] pci 0000:06:08.0: [8086:1010] type 0 class 0x000200
[    0.363967] pci 0000:06:08.0: reg 10: [mem 0xfbfe0000-0xfbffffff 64bit]
[    0.363990] pci 0000:06:08.0: reg 20: [io  0x2500-0x253f]
[    0.364044] pci 0000:06:08.0: PME# supported from D0 D3hot D3cold
[    0.364051] pci 0000:06:08.0: PME# disabled
[    0.364079] pci 0000:06:08.1: [8086:1010] type 0 class 0x000200
[    0.364104] pci 0000:06:08.1: reg 10: [mem 0xfbfc0000-0xfbfdffff 64bit]
[    0.364127] pci 0000:06:08.1: reg 20: [io  0x2540-0x257f]
[    0.364178] pci 0000:06:08.1: PME# supported from D0 D3hot D3cold
[    0.364185] pci 0000:06:08.1: PME# disabled
[    0.364226] pci_bus 0000:06: on NUMA node 0
[    0.364230] ACPI: PCI Interrupt Routing Table [\_SB_.PCI3._PRT]
[    0.364296]  pci0000:06: Unable to request _OSC control (_OSC support mask: 0x1e)
[    0.365362] ACPI: PCI Root Bridge [PCI4] (domain 0000 [bus 08])
[    0.367203] pci_root PNP0A03:04: host bridge window [mem 0xf8000000-0xf9ffffff] (ignored)
[    0.367210] pci_root PNP0A03:04: host bridge window [io  0x2580-0xffff] (ignored)
[    0.367239] pci 0000:08:07.0: [1000:0030] type 0 class 0x000100
[    0.367262] pci 0000:08:07.0: reg 10: [io  0x2600-0x26ff]
[    0.367276] pci 0000:08:07.0: reg 14: [mem 0xf9ff0000-0xf9ffffff 64bit]
[    0.367290] pci 0000:08:07.0: reg 1c: [mem 0xf9fe0000-0xf9feffff 64bit]
[    0.367307] pci 0000:08:07.0: reg 30: [mem 0x00000000-0x000fffff pref]
[    0.367346] pci 0000:08:07.0: supports D1 D2
[    0.367375] pci 0000:08:07.1: [1000:0030] type 0 class 0x000100
[    0.367397] pci 0000:08:07.1: reg 10: [io  0x2700-0x27ff]
[    0.367411] pci 0000:08:07.1: reg 14: [mem 0xf9fd0000-0xf9fdffff 64bit]
[    0.367425] pci 0000:08:07.1: reg 1c: [mem 0xf9fc0000-0xf9fcffff 64bit]
[    0.367442] pci 0000:08:07.1: reg 30: [mem 0x00000000-0x000fffff pref]
[    0.367480] pci 0000:08:07.1: supports D1 D2
[    0.367524] pci_bus 0000:08: on NUMA node 0
[    0.367528] ACPI: PCI Interrupt Routing Table [\_SB_.PCI4._PRT]
[    0.367594]  pci0000:08: Unable to request _OSC control (_OSC support mask: 0x1e)
[    0.367916] ACPI: PCI Interrupt Link [LP00] (IRQs) *0, disabled.
[    0.368293] ACPI: PCI Interrupt Link [LP01] (IRQs) *0, disabled.
[    0.368668] ACPI: PCI Interrupt Link [LP02] (IRQs) *0, disabled.
[    0.369044] ACPI: PCI Interrupt Link [LP03] (IRQs) *0, disabled.
[    0.369418] ACPI: PCI Interrupt Link [LP04] (IRQs) *0, disabled.
[    0.369791] ACPI: PCI Interrupt Link [LP05] (IRQs) *0, disabled.
[    0.370173] ACPI: PCI Interrupt Link [LP06] (IRQs) *0, disabled.
[    0.370547] ACPI: PCI Interrupt Link [LP07] (IRQs) *0, disabled.
[    0.370923] ACPI: PCI Interrupt Link [LP08] (IRQs) *0, disabled.
[    0.371300] ACPI: PCI Interrupt Link [LP09] (IRQs) *0, disabled.
[    0.371680] ACPI: PCI Interrupt Link [LP0A] (IRQs *11)
[    0.372009] ACPI: PCI Interrupt Link [LP0B] (IRQs *9)
[    0.372340] ACPI: PCI Interrupt Link [LP0C] (IRQs *10)
[    0.372668] ACPI: PCI Interrupt Link [LP0D] (IRQs *4)
[    0.372996] ACPI: PCI Interrupt Link [LP0E] (IRQs *5)
[    0.373357] ACPI: PCI Interrupt Link [LP0F] (IRQs) *0, disabled.
[    0.373732] ACPI: PCI Interrupt Link [LP10] (IRQs) *0, disabled.
[    0.374108] ACPI: PCI Interrupt Link [LP11] (IRQs) *0, disabled.
[    0.374482] ACPI: PCI Interrupt Link [LP12] (IRQs) *0, disabled.
[    0.374855] ACPI: PCI Interrupt Link [LP13] (IRQs) *0, disabled.
[    0.375232] ACPI: PCI Interrupt Link [LP14] (IRQs) *0, disabled.
[    0.375607] ACPI: PCI Interrupt Link [LP15] (IRQs) *0, disabled.
[    0.375983] ACPI: PCI Interrupt Link [LP16] (IRQs) *0, disabled.
[    0.376356] ACPI: PCI Interrupt Link [LP17] (IRQs) *0, disabled.
[    0.376745] ACPI: PCI Interrupt Link [LP18] (IRQs) *0, disabled.
[    0.377122] ACPI: PCI Interrupt Link [LP19] (IRQs) *0, disabled.
[    0.377497] ACPI: PCI Interrupt Link [LP1A] (IRQs) *0, disabled.
[    0.377870] ACPI: PCI Interrupt Link [LP1B] (IRQs) *0, disabled.
[    0.378243] ACPI: PCI Interrupt Link [LP1C] (IRQs) *0, disabled.
[    0.378617] ACPI: PCI Interrupt Link [LP1D] (IRQs) *0, disabled.
[    0.378994] ACPI: PCI Interrupt Link [LP1E] (IRQs) *0, disabled.
[    0.379369] ACPI: PCI Interrupt Link [LP1F] (IRQs) *0, disabled.
[    0.380106] ACPI: PCI Interrupt Link [LPUS] (IRQs *3)
[    0.380434] vgaarb: device added: PCI:0000:00:06.0,decodes=io+mem,owns=io+mem,locks=none
[    0.380539] vgaarb: loaded
[    0.380605] vgaarb: bridge control possible 0000:00:06.0
[    0.380723] PCI: Using ACPI for IRQ routing
[    0.380916] PCI: pci_cache_line_size set to 64 bytes
[    0.380997] reserve RAM buffer: 000000000009d400 - 000000000009ffff 
[    0.381001] reserve RAM buffer: 000000009ffeb2c0 - 000000009fffffff 
[    0.384404] pnp: PnP ACPI init
[    0.384497] ACPI: bus type pnp registered
[    0.386202] pnp 00:00: [io  0x03c0-0x03df window]
[    0.386206] pnp 00:00: [io  0x03b0-0x03bb window]
[    0.386210] pnp 00:00: [mem 0x000a0000-0x000bffff window]
[    0.386214] pnp 00:00: [bus 00-01]
[    0.386217] pnp 00:00: [mem 0xfc000000-0xffffffff window]
[    0.386221] pnp 00:00: [io  0x03e0-0x24ff window]
[    0.386224] pnp 00:00: [io  0x03bc-0x03bf window]
[    0.386228] pnp 00:00: [io  0x0000-0x03af window]
[    0.386318] pnp 00:00: Plug and Play ACPI device, IDs PNP0a03 (active)
[    0.387195] pnp 00:01: [io  0x0510-0x0517]
[    0.387199] pnp 00:01: [io  0x0504-0x0507]
[    0.387202] pnp 00:01: [io  0x0500-0x0503]
[    0.387205] pnp 00:01: [io  0x0420-0x0427]
[    0.387211] pnp 00:01: [io  0x0460-0x0461]
[    0.387214] pnp 00:01: [io  0x002e-0x002f]
[    0.387289] system 00:01: [io  0x0510-0x0517] has been reserved
[    0.387366] system 00:01: [io  0x0504-0x0507] has been reserved
[    0.387441] system 00:01: [io  0x0500-0x0503] has been reserved
[    0.387513] system 00:01: [io  0x0420-0x0427] has been reserved
[    0.387586] system 00:01: [io  0x0460-0x0461] has been reserved
[    0.387659] system 00:01: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.387747] pnp 00:02: Plug and Play ACPI device, IDs PNP0303 (active)
[    0.387884] pnp 00:03: [irq 12]
[    0.387925] pnp 00:03: Plug and Play ACPI device, IDs PNP0f13 (active)
[    0.388268] pnp 00:04: [dma 2]
[    0.388275] pnp 00:04: [irq 6]
[    0.388280] pnp 00:04: [io  0x03f0-0x03f5]
[    0.388330] pnp 00:04: Plug and Play ACPI device, IDs PNP0700 (active)
[    0.388417] pnp 00:05: [io  0x0020-0x0021]
[    0.388421] pnp 00:05: [io  0x00a0-0x00a1]
[    0.388467] pnp 00:05: Plug and Play ACPI device, IDs PNP0003 (active)
[    0.388492] pnp 00:06: [dma 4]
[    0.388495] pnp 00:06: [io  0x0000-0x001f]
[    0.388499] pnp 00:06: [io  0x0080-0x008f]
[    0.388501] pnp 00:06: [io  0x00c0-0x00df]
[    0.388536] pnp 00:06: Plug and Play ACPI device, IDs PNP0200 (active)
[    0.388570] pnp 00:07: [io  0x0070-0x0073]
[    0.388576] pnp 00:07: [irq 8]
[    0.388613] pnp 00:07: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.388636] pnp 00:08: [io  0x0061]
[    0.388674] pnp 00:08: Plug and Play ACPI device, IDs PNP0800 (active)
[    0.388697] pnp 00:09: [io  0x00f0-0x00ff]
[    0.388704] pnp 00:09: [irq 13]
[    0.388743] pnp 00:09: Plug and Play ACPI device, IDs PNP0c04 (active)
[    0.388769] pnp 00:0a: [io  0x0092]
[    0.388772] pnp 00:0a: [io  0x00eb-0x00ec]
[    0.388775] pnp 00:0a: [io  0x01ec-0x01ef]
[    0.388778] pnp 00:0a: [io  0x0400-0x04fe]
[    0.388781] pnp 00:0a: [io  0x0600]
[    0.388784] pnp 00:0a: [io  0x0800-0x080f]
[    0.388787] pnp 00:0a: [io  0x0c00-0x0cfe]
[    0.388790] pnp 00:0a: [io  0x0f50-0x0f58]
[    0.388793] pnp 00:0a: [mem 0xfec00000-0xffffffff]
[    0.388858] system 00:0a: [io  0x01ec-0x01ef] has been reserved
[    0.388934] system 00:0a: [io  0x0400-0x04fe] could not be reserved
[    0.389008] system 00:0a: [io  0x0600] has been reserved
[    0.389082] system 00:0a: [io  0x0800-0x080f] has been reserved
[    0.389154] system 00:0a: [io  0x0c00-0x0cfe] could not be reserved
[    0.389228] system 00:0a: [io  0x0f50-0x0f58] has been reserved
[    0.389302] system 00:0a: [mem 0xfec00000-0xffffffff] could not be reserved
[    0.389377] system 00:0a: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.389860] pnp 00:0b: [bus 02-03]
[    0.389927] pnp 00:0b: Plug and Play ACPI device, IDs PNP0a03 (active)
[    0.390395] pnp 00:0c: [bus 04-05]
[    0.390456] pnp 00:0c: Plug and Play ACPI device, IDs PNP0a03 (active)
[    0.391312] pnp 00:0d: [bus 06-07]
[    0.391317] pnp 00:0d: [mem 0xfa000000-0xfbffffff window]
[    0.391321] pnp 00:0d: [io  0x2500-0x257f window]
[    0.391383] pnp 00:0d: Plug and Play ACPI device, IDs PNP0a03 (active)
[    0.392240] pnp 00:0e: [bus 08]
[    0.392245] pnp 00:0e: [mem 0xf8000000-0xf9ffffff window]
[    0.392248] pnp 00:0e: [io  0x2580-0xffff window]
[    0.392306] pnp 00:0e: Plug and Play ACPI device, IDs PNP0a03 (active)
[    0.393184] pnp: PnP ACPI: found 15 devices
[    0.393254] ACPI: ACPI bus type pnp unregistered
[    0.393317] PnPBIOS: Disabled by ACPI PNP
[    0.430257] Switching to clocksource acpi_pm
[    0.430336] PCI: max bus depth: 0 pci_try_num: 1
[    0.430336] pci 0000:00:06.0: BAR 6: assigned [mem 0xa0000000-0xa001ffff pref]
[    0.430336] pci 0000:08:07.0: BAR 6: assigned [mem 0xa0100000-0xa01fffff pref]
[    0.430336] pci 0000:08:07.1: BAR 6: assigned [mem 0xa0200000-0xa02fffff pref]
[    0.430336] pci_bus 0000:00: resource 0 [io  0x0000-0xffff]
[    0.430336] pci_bus 0000:00: resource 1 [mem 0x00000000-0xffffffff]
[    0.430336] pci_bus 0000:02: resource 0 [io  0x0000-0xffff]
[    0.430339] pci_bus 0000:02: resource 1 [mem 0x00000000-0xffffffff]
[    0.430343] pci_bus 0000:04: resource 0 [io  0x0000-0xffff]
[    0.430346] pci_bus 0000:04: resource 1 [mem 0x00000000-0xffffffff]
[    0.430350] pci_bus 0000:06: resource 0 [io  0x0000-0xffff]
[    0.430353] pci_bus 0000:06: resource 1 [mem 0x00000000-0xffffffff]
[    0.430357] pci_bus 0000:08: resource 0 [io  0x0000-0xffff]
[    0.430360] pci_bus 0000:08: resource 1 [mem 0x00000000-0xffffffff]
[    0.430485] NET: Registered protocol family 2
[    0.430652] IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.431035] TCP established hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.431971] TCP bind hash table entries: 65536 (order: 7, 524288 bytes)
[    0.432554] TCP: Hash tables configured (established 131072 bind 65536)
[    0.432633] TCP reno registered
[    0.432706] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.432794] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.433014] Switched to NOHz mode on CPU #0
[    0.433024] Switched to NOHz mode on CPU #3
[    0.433068] Switched to NOHz mode on CPU #1
[    0.433083] Switched to NOHz mode on CPU #2
[    0.433473] NET: Registered protocol family 1
[    0.433570] pci 0000:00:06.0: Boot video device
[    0.433575] PCI: CLS mismatch (64 != 32), using 64 bytes
[    0.433764] ACPI: PCI Interrupt Link [LPUS] enabled at IRQ 3
[    0.433851] pci 0000:00:0f.2: PCI INT A -> Link[LPUS] -> GSI 3 (level, low) -> IRQ 3
[    0.500033] pci 0000:00:0f.2: PCI INT A disabled
[    0.500203] Unpacking initramfs...
[    1.003988] Freeing initrd memory: 2000k freed
[    1.006578] audit: initializing netlink socket (disabled)
[    1.006710] type=2000 audit(1330858318.006:1): initialized
[    1.014499] highmem bounce pool size: 64 pages
[    1.014579] HugeTLB registered 4 MB page size, pre-allocated 0 pages
[    1.029699] VFS: Disk quotas dquot_6.5.2
[    1.029824] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    1.030034] msgmni has been set to 1723
[    1.030201] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[    1.030294] io scheduler noop registered
[    1.030361] io scheduler deadline registered (default)
[    1.030458] io scheduler cfq registered
[    1.030689] ERST: Table is not found!
[    1.030778] isapnp: Scanning for PnP cards...
[    1.387854] isapnp: No Plug & Play device found
[    1.388033] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    1.577223] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at 0x0,0x0 irq 0,12
[    1.577317] i8042: PNP: PS/2 controller has invalid data port 0x0; using default 0x60
[    1.577406] i8042: PNP: PS/2 controller has invalid command port 0x0; using default 0x64
[    1.577496] i8042: PNP: PS/2 controller doesn't have KBD irq; using default 1
[    1.579279] serio: i8042 KBD port at 0x60,0x64 irq 1
[    1.579360] serio: i8042 AUX port at 0x60,0x64 irq 12
[    1.579537] mousedev: PS/2 mouse device common for all mice
[    1.579691] input: PC Speaker as /devices/platform/pcspkr/input/input0
[    1.579956] rtc_cmos 00:07: rtc core: registered rtc_cmos as rtc0
[    1.580092] rtc0: alarms up to one year, 242 bytes nvram
[    1.580179] cpuidle: using governor ladder
[    1.580248] cpuidle: using governor menu
[    1.580452] TCP cubic registered
[    1.580521] NET: Registered protocol family 17
[    1.580598] Registering the dns_resolver key type
[    1.580699] Using IPI No-Shortcut mode
[    1.580896] registered taskstats version 1
[    1.588621] rtc_cmos 00:07: setting system clock to 2012-03-04 10:51:58 UTC (1330858318)
[    1.588875] Freeing unused kernel memory: 388k freed
[    1.592065] Processing INITRAMFS
[    1.600372] loading module sd_mod
[    1.605254] SCSI subsystem initialized
[    1.606296] loading module usbhid
[    1.610663] usbcore: registered new interface driver usbfs
[    1.610784] usbcore: registered new interface driver hub
[    1.610999] usbcore: registered new device driver usb
[    1.612559] usbcore: registered new interface driver usbhid
[    1.612636] usbhid: USB HID core driver
[    1.755458] libata version 3.00 loaded.
[    1.761744] scsi0 : pata_serverworks
[    1.763847] scsi1 : pata_serverworks
[    1.764462] ata1: PATA max UDMA/100 cmd 0x1f0 ctl 0x3f6 bmdma 0x700 irq 14
[    1.764551] ata2: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0x708 irq 15
[    1.940382] ata1.00: ATAPI: LG CD-ROM CRN-8245B, 1.16, max UDMA/33
[    1.970368] ata1.00: configured for UDMA/33
[    1.972200] scsi 0:0:0:0: CD-ROM            LG       CD-ROM CRN-8245B 1.16 PQ: 0 ANSI: 5
[    2.016707] Refined TSC clocksource calibration: 2660.836 MHz.
[    2.016789] Switching to clocksource tsc
[    2.130653] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    2.130800] ohci_hcd 0000:00:0f.2: PCI INT A -> Link[LPUS] -> GSI 3 (level, low) -> IRQ 3
[    2.130936] ohci_hcd 0000:00:0f.2: OHCI Host Controller
[    2.131069] ohci_hcd 0000:00:0f.2: new USB bus registered, assigned bus number 1
[    2.131204] ohci_hcd 0000:00:0f.2: irq 3, io mem 0xfebfe000
[    2.185419] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001
[    2.185499] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.185589] usb usb1: Product: OHCI Host Controller
[    2.185658] usb usb1: Manufacturer: Linux 3.0.0-i686 ohci_hcd
[    2.185730] usb usb1: SerialNumber: 0000:00:0f.2
[    2.186405] hub 1-0:1.0: USB hub found
[    2.186497] hub 1-0:1.0: 4 ports detected
[    2.200670] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[    2.200758] e1000: Copyright (c) 1999-2006 Intel Corporation.
[    2.200888] e1000 0000:06:08.0: PCI INT A -> GSI 29 (level, low) -> IRQ 29
[    2.503548] e1000 0000:06:08.0: eth0: (PCI-X:100MHz:64-bit) 00:0d:60:19:14:1c
[    2.503649] e1000 0000:06:08.0: eth0: Intel(R) PRO/1000 Network Connection
[    2.503788] e1000 0000:06:08.1: PCI INT B -> GSI 30 (level, low) -> IRQ 30
[    2.806894] e1000 0000:06:08.1: eth1: (PCI-X:100MHz:64-bit) 00:0d:60:19:14:1d
[    2.806993] e1000 0000:06:08.1: eth1: Intel(R) PRO/1000 Network Connection
[    2.814642] Fusion MPT base driver 3.04.19
[    2.814732] Copyright (c) 1999-2008 LSI Corporation
[    2.816841] Fusion MPT SPI Host driver 3.04.19
[    2.817022] mptspi 0000:08:07.0: PCI INT A -> GSI 27 (level, low) -> IRQ 27
[    2.817314] mptbase: ioc0: Initiating bringup
[    3.230016] ioc0: LSI53C1030 B2: Capabilities={Initiator}
[    4.120665] scsi2 : ioc0: LSI53C1030 B2, FwRev=01000e00h, Ports=1, MaxQ=222, IRQ=27
[    4.489143] mptspi 0000:08:07.1: PCI INT B -> GSI 28 (level, low) -> IRQ 28
[    4.489361] mptbase: ioc1: Initiating bringup
[   19.486696] mptbase: ioc0: WARNING - Issuing Reset from mpt_config!!, doorbell=0x24000000
[   20.126685] mptbase: ioc0: Attempting Retry Config request type 0x4, page 0x1, action 2
[   20.126831] mptbase: ioc0: Retry completed ret=0x0 timeleft=4500
[   20.127697] scsi 2:0:0:0: Direct-Access     IBM-ESXS DTN073C3UCDY10FN S25J PQ: 0 ANSI: 3
[   20.127805] scsi target2:0:0: Beginning Domain Validation
[   20.138358] scsi target2:0:0: Ending Domain Validation
[   20.138485] scsi target2:0:0: FAST-160 WIDE SCSI 320.0 MB/s DT IU QAS HMCS (6.25 ns, offset 127)
[   20.139657] scsi 2:0:1:0: Direct-Access     IBM-ESXS DTN073C3UCDY10FN S27M PQ: 0 ANSI: 3
[   20.139757] scsi target2:0:1: Beginning Domain Validation
[   20.150181] scsi target2:0:1: Ending Domain Validation
[   20.150308] scsi target2:0:1: FAST-160 WIDE SCSI 320.0 MB/s DT IU QAS HMCS (6.25 ns, offset 127)
[   20.151467] scsi 2:0:2:0: Direct-Access     IBM-ESXS DTN073C3UCDY10FN S27M PQ: 0 ANSI: 3
[   20.151568] scsi target2:0:2: Beginning Domain Validation
[   20.161983] scsi target2:0:2: Ending Domain Validation
[   20.162111] scsi target2:0:2: FAST-160 WIDE SCSI 320.0 MB/s DT IU QAS HMCS (6.25 ns, offset 127)
[   20.163285] scsi 2:0:3:0: Direct-Access     IBM-ESXS DTN073C3UCDY10FN S27M PQ: 0 ANSI: 3
[   20.163399] scsi target2:0:3: Beginning Domain Validation
[   20.173803] scsi target2:0:3: Ending Domain Validation
[   20.173932] scsi target2:0:3: FAST-160 WIDE SCSI 320.0 MB/s DT IU QAS HMCS (6.25 ns, offset 127)
[   20.177932] scsi 2:0:4:0: Direct-Access     IBM-ESXS MAW3073NC     FN C206 PQ: 0 ANSI: 4
[   20.178036] scsi target2:0:4: Beginning Domain Validation
[   20.197999] scsi target2:0:4: Ending Domain Validation
[   20.198126] scsi target2:0:4: FAST-160 WIDE SCSI 320.0 MB/s DT IU HMCS (6.25 ns, offset 127)
[   20.699166] scsi 2:0:8:0: Processor         IBM      32P0032a S320  1 1    PQ: 0 ANSI: 2
[   20.699271] scsi target2:0:8: Beginning Domain Validation
[   20.701305] scsi target2:0:8: Ending Domain Validation
[   20.701433] scsi target2:0:8: asynchronous
[   22.455641] sd 2:0:0:0: [sda] 143374000 512-byte logical blocks: (73.4 GB/68.3 GiB)
[   22.456769] sd 2:0:0:0: [sda] Write Protect is off
[   22.456884] sd 2:0:0:0: [sda] Mode Sense: cb 00 00 08
[   22.458039] sd 2:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[   22.461351] sd 2:0:1:0: [sdb] 143374000 512-byte logical blocks: (73.4 GB/68.3 GiB)
[   22.462396] sd 2:0:1:0: [sdb] Write Protect is off
[   22.462513] sd 2:0:1:0: [sdb] Mode Sense: cb 00 00 08
[   22.463600] sd 2:0:1:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[   22.466840] sd 2:0:2:0: [sdc] 143374000 512-byte logical blocks: (73.4 GB/68.3 GiB)
[   22.467968] sd 2:0:2:0: [sdc] Write Protect is off
[   22.468057] sd 2:0:2:0: [sdc] Mode Sense: cb 00 00 08
[   22.469469] sd 2:0:2:0: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[   22.471825] sd 2:0:3:0: [sdd] 143374000 512-byte logical blocks: (73.4 GB/68.3 GiB)
[   22.472990] sd 2:0:3:0: [sdd] Write Protect is off
[   22.473079] sd 2:0:3:0: [sdd] Mode Sense: cb 00 00 08
[   22.474481] sd 2:0:3:0: [sdd] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[   22.478704] sd 2:0:4:0: [sde] 143374000 512-byte logical blocks: (73.4 GB/68.3 GiB)
[   22.481389] sd 2:0:4:0: [sde] Write Protect is off
[   22.481484] sd 2:0:4:0: [sde] Mode Sense: cf 00 00 08
[   22.482867] sd 2:0:4:0: [sde] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[   22.488021]  sda: sda1 sda2 sda3 sda4 < sda5 sda6 sda7 >
[   22.495096]  sdb: sdb1 sdb2 sdb3 sdb4 < sdb5 sdb6 sdb7 >
[   22.497675] sd 2:0:0:0: [sda] Attached SCSI disk
[   22.500312]  sdd: sdd1 sdd2 sdd3 sdd4 < sdd5 sdd6 sdd7 >
[   22.506406]  sdc: sdc1 sdc2 sdc3 sdc4 < sdc5 sdc6 sdc7 >
[   22.508003]  sde: sde1 sde2 sde3 sde4 < sde5 sde6 sde7 >
[   22.586994] sd 2:0:1:0: [sdb] Attached SCSI disk
[   22.604075] sd 2:0:3:0: [sdd] Attached SCSI disk
[   22.635177] ------------[ cut here ]------------
[   22.635290] WARNING: at block/genhd.c:1474 __disk_unblock_events+0xe1/0xf0()
[   22.635371] Hardware name: eserver xSeries 345 -[867052G]-
[   22.635448] Modules linked in: mptspi(+) mptscsih mptbase scsi_transport_spi e1000 ohci_hcd pata_serverworks libata usbhid hid usbcore sd_mod scsi_mod crc_t10dif
[   22.636260] Pid: 271, comm: blkid Not tainted 3.0.0-i686 #3.0.23
[   22.636334] Call Trace:
[   22.636407]  [<c1040118>] ? warn_slowpath_common+0x78/0xb0
[   22.636482]  [<c1188d81>] ? __disk_unblock_events+0xe1/0xf0
[   22.636556]  [<c1188d81>] ? __disk_unblock_events+0xe1/0xf0
[   22.636631]  [<c104016b>] ? warn_slowpath_null+0x1b/0x20
[   22.636705]  [<c1188d81>] ? __disk_unblock_events+0xe1/0xf0
[   22.636783]  [<c1124f57>] ? __blkdev_get+0x157/0x3c0
[   22.636856]  [<c1125203>] ? blkdev_get+0x43/0x2b0
[   22.636931]  [<c11616b0>] ? security_dentry_open+0x70/0x80
[   22.637006]  [<c11245f8>] ? bd_acquire+0x28/0xc0
[   22.637080]  [<c10f70c8>] ? __dentry_open+0x118/0x2b0
[   22.637154]  [<c10f7348>] ? nameidata_to_filp+0x68/0x70
[   22.637228]  [<c1125470>] ? blkdev_get+0x2b0/0x2b0
[   22.637302]  [<c110429d>] ? do_last+0xcd/0x820
[   22.637375]  [<c10fa48b>] ? get_empty_filp+0x11b/0x1d0
[   22.637449]  [<c1105601>] ? path_openat+0xa1/0x370
[   22.637523]  [<c11059b4>] ? do_filp_open+0x34/0x90
[   22.637597]  [<c10464c1>] ? irq_exit+0x31/0xa0
[   22.637672]  [<c12e69d2>] ? call_function_interrupt+0x2a/0x30
[   22.637746]  [<c10f6cce>] ? do_sys_open+0xee/0x1c0
[   22.637820]  [<c10f6e1c>] ? sys_open+0x2c/0x40
[   22.637892]  [<c12e70d8>] ? sysenter_do_call+0x12/0x28
[   22.637963] ---[ end trace 9071b164502805fa ]---
[   22.645185] sd 2:0:2:0: [sdc] Attached SCSI disk
[   22.659254] sd 2:0:4:0: [sde] Attached SCSI disk
[   34.493348] mptbase: ioc1: ERROR - Wait IOC_READY state (0x20000000) timeout(15)!
[   38.883345] ioc1: LSI53C1030 B2: Capabilities={Initiator}
[   39.283343] ioc0: LSI53C1030 B2: Capabilities={Initiator}
[   40.567328] scsi3 : ioc1: LSI53C1030 B2, FwRev=01000e00h, Ports=1, MaxQ=222, IRQ=28
[   41.087234] configuring raid array md1
[   41.089401] mdadm: sending ioctl 800c0910 to a partition!
[   41.089480] mdadm: sending ioctl 800c0910 to a partition!
[   41.089567] mdadm: sending ioctl 1261 to a partition!
[   41.089638] mdadm: sending ioctl 1261 to a partition!
[   41.106558] mdadm: sending ioctl 1261 to a partition!
[   41.106630] mdadm: sending ioctl 1261 to a partition!
[   41.108013] mdadm: sending ioctl 1261 to a partition!
[   41.108084] mdadm: sending ioctl 1261 to a partition!
[   41.108832] mdadm: sending ioctl 1261 to a partition!
[   41.108903] mdadm: sending ioctl 1261 to a partition!
[   41.485564] md: md1 stopped.
[   41.623942] md: bind<sdb1>
[   41.627272] md: bind<sdc1>
[   41.636989] md: bind<sdd1>
[   41.690496] md: bind<sde1>
[   41.759812] md: bind<sda1>
[   41.763241] md: raid1 personality registered for level 1
[   41.763610] bio: create slab <bio-1> at 1
[   41.763853] md/raid1:md1: active with 5 out of 5 mirrors
[   41.763978] md1: detected capacity change from 0 to 263061504
[   41.764407] mdadm: /dev/md1 has been started with 5 drives.
[   41.765219] mounting ext4 fs on /dev/md1 (options: relatime,nodiratime,data=ordered)
[   41.798356]  md1: unknown partition table
[   41.868159] EXT4-fs (md1): warning: checktime reached, running e2fsck is recommended
[   41.990762] EXT4-fs (md1): mounted filesystem with ordered data mode. Opts: data=ordered
[   42.155948] mount: mounting /run on /root/run failed: No such file or directory
[   44.708416] EXT4-fs (md1): re-mounted. Opts: (null)
[   57.520667] EXT4-fs (md1): re-mounted. Opts: data=ordered
[   57.777857] scsi_verify_blk_ioctl: 224 callbacks suppressed
[   57.777935] mdadm: sending ioctl 800c0910 to a partition!
[   57.778007] mdadm: sending ioctl 800c0910 to a partition!
[   57.778092] mdadm: sending ioctl 1261 to a partition!
[   57.778162] mdadm: sending ioctl 1261 to a partition!
[   57.796934] mdadm: sending ioctl 1261 to a partition!
[   57.797007] mdadm: sending ioctl 1261 to a partition!
[   57.798379] mdadm: sending ioctl 1261 to a partition!
[   57.798450] mdadm: sending ioctl 1261 to a partition!
[   57.811598] mdadm: sending ioctl 1261 to a partition!
[   57.811670] mdadm: sending ioctl 1261 to a partition!
[   58.614204] md: md2 stopped.
[   58.663890] md: bind<sdb2>
[   58.665318] md: bind<sdc2>
[   58.666644] md: bind<sdd2>
[   58.669065] md: bind<sde2>
[   58.670420] md: bind<sda2>
[   58.833384] raid6: int32x1    731 MB/s
[   58.890028] raid6: int32x2    905 MB/s
[   58.946721] raid6: int32x4    862 MB/s
[   59.003434] raid6: int32x8    567 MB/s
[   59.060022] raid6: mmxx1     2200 MB/s
[   59.116679] raid6: mmxx2     2763 MB/s
[   59.173374] raid6: sse1x1    1498 MB/s
[   59.230029] raid6: sse1x2    2442 MB/s
[   59.286676] raid6: sse2x1    2968 MB/s
[   59.343340] raid6: sse2x2    2997 MB/s
[   59.343409] raid6: using algorithm sse2x2 (2997 MB/s)
[   59.363383] xor: automatically using best checksumming function: pIII_sse
[   59.380002]    pIII_sse  :  3386.400 MB/sec
[   59.380074] xor: using function: pIII_sse (3386.400 MB/sec)
[   59.432762] md: raid6 personality registered for level 6
[   59.432847] md: raid5 personality registered for level 5
[   59.432922] md: raid4 personality registered for level 4
[   59.433280] md/raid:md2: device sda2 operational as raid disk 0
[   59.433423] md/raid:md2: device sde2 operational as raid disk 4
[   59.433505] md/raid:md2: device sdd2 operational as raid disk 3
[   59.433591] md/raid:md2: device sdc2 operational as raid disk 2
[   59.433676] md/raid:md2: device sdb2 operational as raid disk 1
[   59.434627] md/raid:md2: allocated 5265kB
[   59.434783] md/raid:md2: raid level 5 active with 5 out of 5 devices, algorithm 2
[   59.434873] RAID conf printout:
[   59.434876]  --- level:5 rd:5 wd:5
[   59.434879]  disk 0, o:1, dev:sda2
[   59.434882]  disk 1, o:1, dev:sdb2
[   59.434885]  disk 2, o:1, dev:sdc2
[   59.434888]  disk 3, o:1, dev:sdd2
[   59.434891]  disk 4, o:1, dev:sde2
[   59.435241] created bitmap (1 pages) for device md2
[   59.435679] md2: bitmap initialized from disk: read 1/1 pages, set 0 of 2040 bits
[   59.447002] md2: detected capacity change from 0 to 4276617216
[   59.450131]  md2: unknown partition table
[   59.624321] md: md3 stopped.
[   59.635243] md: bind<sdb3>
[   59.636578] md: bind<sdc3>
[   59.637967] md: bind<sdd3>
[   59.640357] md: bind<sde3>
[   59.641749] md: bind<sda3>
[   59.659807] md: raid10 personality registered for level 10
[   59.661696] md/raid10:md3: active with 5 out of 5 devices
[   59.661818] md3: detected capacity change from 0 to 2672558080
[   59.668310]  md3: unknown partition table
[   59.770927] md: md5 stopped.
[   59.790201] md: bind<sdb5>
[   59.791551] md: bind<sdc5>
[   59.792843] md: bind<sdd5>
[   59.795230] md: bind<sde5>
[   59.796582] md: bind<sda5>
[   59.798759] md/raid:md5: device sda5 operational as raid disk 0
[   59.798834] md/raid:md5: device sde5 operational as raid disk 4
[   59.798907] md/raid:md5: device sdd5 operational as raid disk 3
[   59.798979] md/raid:md5: device sdc5 operational as raid disk 2
[   59.799051] md/raid:md5: device sdb5 operational as raid disk 1
[   59.799896] md/raid:md5: allocated 5265kB
[   59.800108] md/raid:md5: raid level 5 active with 5 out of 5 devices, algorithm 2
[   59.800198] RAID conf printout:
[   59.800201]  --- level:5 rd:5 wd:5
[   59.800205]  disk 0, o:1, dev:sda5
[   59.800208]  disk 1, o:1, dev:sdb5
[   59.800210]  disk 2, o:1, dev:sdc5
[   59.800213]  disk 3, o:1, dev:sdd5
[   59.800216]  disk 4, o:1, dev:sde5
[   59.800544] created bitmap (2 pages) for device md5
[   59.801164] md5: bitmap initialized from disk: read 1/1 pages, set 0 of 4095 bits
[   59.810979] md5: detected capacity change from 0 to 17173577728
[   59.814005]  md5: unknown partition table
[   59.893269] md: md6 stopped.
[   59.912734] md: bind<sdb6>
[   59.914073] md: bind<sdc6>
[   59.915366] md: bind<sdd6>
[   59.917752] md: bind<sde6>
[   59.919148] md: bind<sda6>
[   59.921278] md/raid:md6: device sda6 operational as raid disk 0
[   59.921352] md/raid:md6: device sde6 operational as raid disk 4
[   59.921425] md/raid:md6: device sdd6 operational as raid disk 3
[   59.921497] md/raid:md6: device sdc6 operational as raid disk 2
[   59.921570] md/raid:md6: device sdb6 operational as raid disk 1
[   59.922416] md/raid:md6: allocated 5265kB
[   59.922555] md/raid:md6: raid level 5 active with 5 out of 5 devices, algorithm 2
[   59.922646] RAID conf printout:
[   59.922649]  --- level:5 rd:5 wd:5
[   59.922652]  disk 0, o:1, dev:sda6
[   59.922655]  disk 1, o:1, dev:sdb6
[   59.922658]  disk 2, o:1, dev:sdc6
[   59.922661]  disk 3, o:1, dev:sdd6
[   59.922663]  disk 4, o:1, dev:sde6
[   59.923029] created bitmap (2 pages) for device md6
[   59.923693] md6: bitmap initialized from disk: read 1/1 pages, set 0 of 4095 bits
[   59.931475] md6: detected capacity change from 0 to 8586788864
[   59.934532]  md6: unknown partition table
[   59.986496] md: md7 stopped.
[   60.006071] md: bind<sdb7>
[   60.007411] md: bind<sdc7>
[   60.008707] md: bind<sdd7>
[   60.011095] md: bind<sde7>
[   60.012490] md: bind<sda7>
[   60.014613] md/raid:md7: device sda7 operational as raid disk 0
[   60.014688] md/raid:md7: device sde7 operational as raid disk 4
[   60.014760] md/raid:md7: device sdd7 operational as raid disk 3
[   60.014833] md/raid:md7: device sdc7 operational as raid disk 2
[   60.014906] md/raid:md7: device sdb7 operational as raid disk 1
[   60.015743] md/raid:md7: allocated 5265kB
[   60.015875] md/raid:md7: raid level 5 active with 5 out of 5 devices, algorithm 2
[   60.015965] RAID conf printout:
[   60.015968]  --- level:5 rd:5 wd:5
[   60.015971]  disk 0, o:1, dev:sda7
[   60.015974]  disk 1, o:1, dev:sdb7
[   60.015977]  disk 2, o:1, dev:sdc7
[   60.015979]  disk 3, o:1, dev:sdd7
[   60.015982]  disk 4, o:1, dev:sde7
[   60.016345] created bitmap (8 pages) for device md7
[   60.017696] md7: bitmap initialized from disk: read 1/1 pages, set 0 of 15279 bits
[   60.032209] md7: detected capacity change from 0 to 256331743232
[   60.035474]  md7: unknown partition table
[   60.163404] Adding 2609916k swap on /dev/md3.  Priority:-1 extents:1 across:2609916k 
[  596.221382] EXT4-fs (md2): mounted filesystem with ordered data mode. Opts: data=ordered
[  596.322628] EXT4-fs (md5): mounted filesystem with ordered data mode. Opts: data=ordered
[  596.416414] EXT4-fs (md6): mounted filesystem with ordered data mode. Opts: data=ordered,usrquota,grpquota
[  596.773165] SGI XFS with ACLs, security attributes, large block/inode numbers, no debug enabled
[  596.773849] SGI XFS Quota Management subsystem
[  596.830744] XFS (md7): Mounting Filesystem
[  599.085866] XFS (md7): Ending clean mount
[  603.084899] IPv6: Loaded, but administratively disabled, reboot required to enable
[  603.147044] e1000: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX
[  605.489829] ip_tables: (C) 2000-2006 Netfilter Core Team
[  605.533016] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
[  607.803732] sshd (1037): /proc/1037/oom_adj is deprecated, please use /proc/1037/oom_score_adj instead.
[  645.153899] RPC: Registered named UNIX socket transport module.
[  645.153941] RPC: Registered udp transport module.
[  645.153975] RPC: Registered tcp transport module.
[  645.154006] RPC: Registered tcp NFSv4.1 backchannel transport module.
[  645.295822] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[  645.350044] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
[  645.356085] NFSD: starting 90-second grace period
[  649.531126] tun: Universal TUN/TAP device driver, 1.6
[  649.531167] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>

^ permalink raw reply	[flat|nested] 7+ messages in thread

* WARNING: at block/genhd.c:1474 __disk_unblock_events+0xe1/0xf0() -- should I be concerned?
  2012-03-04 14:19 WARNING: at block/genhd.c:1474 __disk_unblock_events+0xe1/0xf0() -- should I be concerned? Michael Tokarev
@ 2012-03-05  6:35 ` Michael Tokarev
  2012-03-05 17:56   ` Bart Van Assche
  0 siblings, 1 reply; 7+ messages in thread
From: Michael Tokarev @ 2012-03-05  6:35 UTC (permalink / raw)
  To: SCSI Mailing List

[-- Attachment #1: Type: text/plain, Size: 5309 bytes --]

I meant to sent the original message to linux-scsi, but somehow
posted it to LKML.  So resending it into proper mailinglist.
Please forgive me for double post.

-------- Original Message --------
Subject: WARNING: at block/genhd.c:1474 __disk_unblock_events+0xe1/0xf0() -- should I be concerned?
Date: Sun, 04 Mar 2012 18:19:33 +0400
From: Michael Tokarev <mjt@tls.msk.ru>
To: Linux-kernel <linux-kernel@vger.kernel.org>

I booted a 3.0 kernel (i686) on a relatively old hardware,
on a server which was running 2.6.32 stable series for a
long while.

And at the first boot I see the following messages in dmesg:

...
[    2.817314] mptbase: ioc0: Initiating bringup
[    3.230016] ioc0: LSI53C1030 B2: Capabilities={Initiator}
[    4.120665] scsi2 : ioc0: LSI53C1030 B2, FwRev=01000e00h, Ports=1, MaxQ=222, IRQ=27
[    4.489143] mptspi 0000:08:07.1: PCI INT B -> GSI 28 (level, low) -> IRQ 28
[    4.489361] mptbase: ioc1: Initiating bringup
[   19.486696] mptbase: ioc0: WARNING - Issuing Reset from mpt_config!!, doorbell=0x24000000
[   20.126685] mptbase: ioc0: Attempting Retry Config request type 0x4, page 0x1, action 2
[   20.126831] mptbase: ioc0: Retry completed ret=0x0 timeleft=4500
[   20.127697] scsi 2:0:0:0: Direct-Access     IBM-ESXS DTN073C3UCDY10FN S25J PQ: 0 ANSI: 3
...
[   22.604075] sd 2:0:3:0: [sdd] Attached SCSI disk
[   22.635177] ------------[ cut here ]------------
[   22.635290] WARNING: at block/genhd.c:1474 __disk_unblock_events+0xe1/0xf0()
[   22.635371] Hardware name: eserver xSeries 345 -[867052G]-
[   22.635448] Modules linked in: mptspi(+) mptscsih mptbase scsi_transport_spi e1000 ohci_hcd pata_serverworks libata usbhid hid usbcore sd_mod scsi_mod crc_t10dif
[   22.636260] Pid: 271, comm: blkid Not tainted 3.0.0-i686 #3.0.23
[   22.636334] Call Trace:
[   22.636407]  [<c1040118>] ? warn_slowpath_common+0x78/0xb0
[   22.636482]  [<c1188d81>] ? __disk_unblock_events+0xe1/0xf0
[   22.636556]  [<c1188d81>] ? __disk_unblock_events+0xe1/0xf0
[   22.636631]  [<c104016b>] ? warn_slowpath_null+0x1b/0x20
[   22.636705]  [<c1188d81>] ? __disk_unblock_events+0xe1/0xf0
[   22.636783]  [<c1124f57>] ? __blkdev_get+0x157/0x3c0
[   22.636856]  [<c1125203>] ? blkdev_get+0x43/0x2b0
[   22.636931]  [<c11616b0>] ? security_dentry_open+0x70/0x80
[   22.637006]  [<c11245f8>] ? bd_acquire+0x28/0xc0
[   22.637080]  [<c10f70c8>] ? __dentry_open+0x118/0x2b0
[   22.637154]  [<c10f7348>] ? nameidata_to_filp+0x68/0x70
[   22.637228]  [<c1125470>] ? blkdev_get+0x2b0/0x2b0
[   22.637302]  [<c110429d>] ? do_last+0xcd/0x820
[   22.637375]  [<c10fa48b>] ? get_empty_filp+0x11b/0x1d0
[   22.637449]  [<c1105601>] ? path_openat+0xa1/0x370
[   22.637523]  [<c11059b4>] ? do_filp_open+0x34/0x90
[   22.637597]  [<c10464c1>] ? irq_exit+0x31/0xa0
[   22.637672]  [<c12e69d2>] ? call_function_interrupt+0x2a/0x30
[   22.637746]  [<c10f6cce>] ? do_sys_open+0xee/0x1c0
[   22.637820]  [<c10f6e1c>] ? sys_open+0x2c/0x40
[   22.637892]  [<c12e70d8>] ? sysenter_do_call+0x12/0x28
[   22.637963] ---[ end trace 9071b164502805fa ]---
[   22.645185] sd 2:0:2:0: [sdc] Attached SCSI disk
[   22.659254] sd 2:0:4:0: [sde] Attached SCSI disk
[   34.493348] mptbase: ioc1: ERROR - Wait IOC_READY state (0x20000000) timeout(15)!
[   38.883345] ioc1: LSI53C1030 B2: Capabilities={Initiator}
[   39.283343] ioc0: LSI53C1030 B2: Capabilities={Initiator}
[   40.567328] scsi3 : ioc1: LSI53C1030 B2, FwRev=01000e00h, Ports=1, MaxQ=222, IRQ=28
...

This is 3.0.23.  Line 1474 of block/genhd.c is like this:

static void __disk_unblock_events(struct gendisk *disk, bool check_now)
{
        struct disk_events *ev = disk->ev;
...
        if (WARN_ON_ONCE(ev->block <= 0))  <==== this one
                goto out_unlock;
...

The machine has the following SCSI controller:

08:07.1 SCSI storage controller: LSI Logic / Symbios Logic 53c1030 PCI-X Fusion-MPT Dual Ultra320 SCSI (rev 07)
	Subsystem: IBM Device 026c
	Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV+ VGASnoop- ParErr+ Stepping- SERR+ FastB2B- DisINTx-
	Status: Cap+ 66MHz+ UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 72 (4250ns min, 4500ns max), Cache Line Size: 32 bytes
	Interrupt: pin B routed to IRQ 28
	Region 0: I/O ports at 2700 [size=256]
	Region 1: Memory at f9fd0000 (64-bit, non-prefetchable) [size=64K]
	Region 3: Memory at f9fc0000 (64-bit, non-prefetchable) [size=64K]
	[virtual] Expansion ROM at a0200000 [disabled] [size=1M]
	Capabilities: [50] Power Management version 2
		Flags: PMEClk- DSI- D1+ D2+ AuxCurrent=0mA PME(D0-,D1-,D2-,D3hot-,D3cold-)
		Status: D0 NoSoftRst- PME-Enable- DSel=0 DScale=0 PME-
	Capabilities: [58] MSI: Enable- Count=1/1 Maskable- 64bit+
		Address: 0000000000000000  Data: 0000
	Capabilities: [68] PCI-X non-bridge device
		Command: DPERE- ERO- RBC=512 OST=1
		Status: Dev=08:07.1 64bit+ 133MHz+ SCD- USC- DC=simple DMMRBC=2048 DMOST=8 DMCRS=16 RSCEM- 266MHz- 533MHz-
	Kernel driver in use: mptspi

with 5 parallel scsi drives attached to it.  In the kernel
config, SCSI bus asyncronous scanning (CONFIG_SCSI_SCAN_ASYNC)
is enabled.

The system appears to work fine so far, but these messages,
together with a few errors and delays around, are a bit
worrying.  What the problem is, any suggestion?

Complete bootup dmesg is attached.

Thank you!

/mjt


[-- Attachment #2: dmesg --]
[-- Type: text/plain, Size: 46163 bytes --]

[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Linux version 3.0.0-i686 (mjt@titania.msk.rgs-podm.ru) (gcc version 4.4.5 (Debian 4.4.5-8) ) #3.0.23 SMP Thu Mar 1 15:46:05 MSK 2012
[    0.000000] BIOS-provided physical RAM map:
[    0.000000]  BIOS-e820: 0000000000000000 - 000000000009d400 (usable)
[    0.000000]  BIOS-e820: 000000000009d400 - 00000000000a0000 (reserved)
[    0.000000]  BIOS-e820: 00000000000e0000 - 0000000000100000 (reserved)
[    0.000000]  BIOS-e820: 0000000000100000 - 000000009ffeb2c0 (usable)
[    0.000000]  BIOS-e820: 000000009ffeb2c0 - 000000009fff0000 (ACPI data)
[    0.000000]  BIOS-e820: 000000009fff0000 - 00000000a0000000 (reserved)
[    0.000000]  BIOS-e820: 00000000fec00000 - 0000000100000000 (reserved)
[    0.000000] Notice: NX (Execute Disable) protection missing in CPU!
[    0.000000] DMI 2.3 present.
[    0.000000] DMI: IBM eserver xSeries 345 -[867052G]-/, BIOS -[GEE149AUS-1.12]- 07/14/2003
[    0.000000] e820 update range: 0000000000000000 - 0000000000010000 (usable) ==> (reserved)
[    0.000000] e820 remove range: 00000000000a0000 - 0000000000100000 (usable)
[    0.000000] last_pfn = 0x9ffeb max_arch_pfn = 0x100000
[    0.000000] MTRR default type: uncachable
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-BFFFF uncachable
[    0.000000]   C0000-CFFFF write-protect
[    0.000000]   D0000-DFFFF uncachable
[    0.000000]   E0000-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 000000000 mask F80000000 write-back
[    0.000000]   1 base 080000000 mask FE0000000 write-back
[    0.000000]   2 disabled
[    0.000000]   3 disabled
[    0.000000]   4 disabled
[    0.000000]   5 disabled
[    0.000000]   6 disabled
[    0.000000]   7 disabled
[    0.000000] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[    0.000000] found SMP MP-table at [c009d540] 9d540
[    0.000000] initial memory mapped : 0 - 01800000
[    0.000000] Base memory trampoline at [c0099000] 99000 size 16384
[    0.000000] init_memory_mapping: 0000000000000000-00000000377fe000
[    0.000000]  0000000000 - 0000400000 page 4k
[    0.000000]  0000400000 - 0037400000 page 2M
[    0.000000]  0037400000 - 00377fe000 page 4k
[    0.000000] kernel direct mapping tables up to 377fe000 @ 17fb000-1800000
[    0.000000] RAMDISK: 7fe0b000 - 7ffff000
[    0.000000] Allocated new RAMDISK: 3760a000 - 377fd880
[    0.000000] Move RAMDISK from 000000007fe0b000 - 000000007fffe87f to 3760a000 - 377fd87f
[    0.000000] ACPI: RSDP 000fdfc0 00014 (v00 IBM   )
[    0.000000] ACPI: RSDT 9ffeff80 00030 (v01 IBM    SERONYXP 00001000 IBM  45444F43)
[    0.000000] ACPI: FACP 9ffeff00 00074 (v01 IBM    SERONYXP 00001000 IBM  45444F43)
[    0.000000] ACPI: DSDT 9ffeb2c0 04A4E (v01 IBM    SERGEODE 00001000 MSFT 0100000B)
[    0.000000] ACPI: FACS 9ffefe00 00040
[    0.000000] ACPI: APIC 9ffefe40 00092 (v01 IBM    SERONYXP 00001000 IBM  45444F43)
[    0.000000] ACPI: ASF! 9ffefd80 0004B (v16 IBM    SERONYXP 00000001 IBM  45444F43)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] 1671MB HIGHMEM available.
[    0.000000] 887MB LOWMEM available.
[    0.000000]   mapped low ram: 0 - 377fe000
[    0.000000]   low ram: 0 - 377fe000
[    0.000000] Zone PFN ranges:
[    0.000000]   DMA      0x00000010 -> 0x00001000
[    0.000000]   Normal   0x00001000 -> 0x000377fe
[    0.000000]   HighMem  0x000377fe -> 0x0009ffeb
[    0.000000] Movable zone start PFN for each node
[    0.000000] early_node_map[2] active PFN ranges
[    0.000000]     0: 0x00000010 -> 0x0000009d
[    0.000000]     0: 0x00000100 -> 0x0009ffeb
[    0.000000] On node 0 totalpages: 655224
[    0.000000] free_area_init_node: node 0, pgdat c13eb840, node_mem_map f6209200
[    0.000000]   DMA zone: 32 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 3949 pages, LIFO batch:0
[    0.000000]   Normal zone: 1744 pages used for memmap
[    0.000000]   Normal zone: 221486 pages, LIFO batch:31
[    0.000000]   HighMem zone: 3344 pages used for memmap
[    0.000000]   HighMem zone: 424669 pages, LIFO batch:31
[    0.000000] Using APIC driver default
[    0.000000] ACPI: PM-Timer IO Port: 0x488
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x06] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x01] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x07] enabled)
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x00] dfl dfl lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x06] dfl dfl lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x07] dfl dfl lint[0x1])
[    0.000000] ACPI: IOAPIC (id[0x0e] address[0xfec00000] gsi_base[0])
[    0.000000] IOAPIC[0]: apic_id 14, version 17, address 0xfec00000, GSI 0-15
[    0.000000] ACPI: IOAPIC (id[0x0d] address[0xfec01000] gsi_base[16])
[    0.000000] IOAPIC[1]: apic_id 13, version 17, address 0xfec01000, GSI 16-31
[    0.000000] ACPI: IOAPIC (id[0x0c] address[0xfec02000] gsi_base[32])
[    0.000000] IOAPIC[2]: apic_id 12, version 17, address 0xfec02000, GSI 32-47
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ2 used by override.
[    0.000000] ACPI: IRQ7 used by override.
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] SMP: Allowing 4 CPUs, 0 hotplug CPUs
[    0.000000] nr_irqs_gsi: 64
[    0.000000] PM: Registered nosave memory: 000000000009d000 - 000000000009e000
[    0.000000] PM: Registered nosave memory: 000000000009e000 - 00000000000a0000
[    0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000e0000
[    0.000000] PM: Registered nosave memory: 00000000000e0000 - 0000000000100000
[    0.000000] Allocating PCI resources starting at a0000000 (gap: a0000000:5ec00000)
[    0.000000] Booting paravirtualized kernel on bare hardware
[    0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:4 nr_node_ids:1
[    0.000000] PERCPU: Embedded 12 pages/cpu @f5c00000 s25280 r0 d23872 u1048576
[    0.000000] pcpu-alloc: s25280 r0 d23872 u1048576 alloc=1*4194304
[    0.000000] pcpu-alloc: [0] 0 1 2 3 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 650104
[    0.000000] Kernel command line: panic=30 elevator=deadline ipv6.disable=1 initrd=initrd-3.0.0-i686 BOOT_IMAGE=vmlinuz-3.0.0-i686 linux-test
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Initializing CPU#0
[    0.000000] Initializing HighMem for node 0 (000377fe:0009ffeb)
[    0.000000] Memory: 2592400k/2621356k available (2976k kernel code, 28496k reserved, 1061k data, 388k init, 1712052k highmem)
[    0.000000] virtual kernel memory layout:
[    0.000000]     fixmap  : 0xfff16000 - 0xfffff000   ( 932 kB)
[    0.000000]     pkmap   : 0xff800000 - 0xffc00000   (4096 kB)
[    0.000000]     vmalloc : 0xf7ffe000 - 0xff7fe000   ( 120 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xf77fe000   ( 887 MB)
[    0.000000]       .init : 0xc13f2000 - 0xc1453000   ( 388 kB)
[    0.000000]       .data : 0xc12e8162 - 0xc13f1940   (1061 kB)
[    0.000000]       .text : 0xc1000000 - 0xc12e8162   (2976 kB)
[    0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    0.000000] Hierarchical RCU implementation.
[    0.000000] NR_IRQS:512
[    0.000000] CPU 0 irqstacks, hard=f580a000 soft=f580c000
[    0.000000] Console: colour VGA+ 80x25
[    0.000000] console [tty0] enabled
[    0.000000] Fast TSC calibration using PIT
[    0.000000] Detected 2660.640 MHz processor.
[    0.006670] Calibrating delay loop (skipped), value calculated using timer frequency.. 5323.00 BogoMIPS (lpj=8868800)
[    0.006813] pid_max: default: 32768 minimum: 301
[    0.006934] Security Framework initialized
[    0.007030] Mount-cache hash table entries: 512
[    0.007323] Initializing cgroup subsys cpuacct
[    0.007406] Initializing cgroup subsys devices
[    0.007475] Initializing cgroup subsys freezer
[    0.007544] Initializing cgroup subsys net_cls
[    0.007613] Initializing cgroup subsys blkio
[    0.007727] CPU: Physical Processor ID: 0
[    0.007794] CPU: Processor Core ID: 0
[    0.007862] mce: CPU supports 4 MCE banks
[    0.007941] CPU0: Thermal monitoring enabled (TM1)
[    0.010156] ACPI: Core revision 20110413
[    0.013416] Enabling APIC mode:  Flat.  Using 3 I/O APICs
[    0.014114] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.047752] CPU0: Intel(R) Xeon(TM) CPU 2.66GHz stepping 09
[    0.049997] Performance Events: Netburst events, Netburst P4/Xeon PMU driver.
[    0.049997] ... version:                0
[    0.049997] ... bit width:              40
[    0.049997] ... generic registers:      18
[    0.049997] ... value mask:             000000ffffffffff
[    0.049997] ... max period:             0000007fffffffff
[    0.049997] ... fixed-purpose events:   0
[    0.049997] ... event mask:             000000000003ffff
[    0.049997] NMI watchdog enabled, takes one hw-pmu counter.
[    0.049997] CPU 1 irqstacks, hard=f5896000 soft=f5898000
[    0.049997] Booting Node   0, Processors  #1
[    0.049997] smpboot cpu 1: start_ip = 99000
[    0.009999] Initializing CPU#1
[    0.143362] NMI watchdog enabled, takes one hw-pmu counter.
[    0.143666] CPU 2 irqstacks, hard=f58a8000 soft=f58aa000
[    0.143669]  #2
[    0.143720] smpboot cpu 2: start_ip = 99000
[    0.009999] Initializing CPU#2
[    0.240030] NMI watchdog enabled, takes one hw-pmu counter.
[    0.240377] CPU 3 irqstacks, hard=f58d8000 soft=f58da000
[    0.240381]  #3 Ok.
[    0.240446] smpboot cpu 3: start_ip = 99000
[    0.009999] Initializing CPU#3
[    0.336674] NMI watchdog enabled, takes one hw-pmu counter.
[    0.336843] Brought up 4 CPUs
[    0.336913] Total of 4 processors activated (21294.45 BogoMIPS).
[    0.337915] devtmpfs: initialized
[    0.337915] NET: Registered protocol family 16
[    0.337915] ACPI: bus type pci registered
[    0.337915] PCI: PCI BIOS revision 2.10 entry at 0xfd7dc, last bus=8
[    0.337915] PCI: Using configuration type 1 for base access
[    0.337915] bio: create slab <bio-0> at 0
[    0.341500] ACPI: EC: Look up EC in DSDT
[    0.344564] ACPI: Interpreter enabled
[    0.344638] ACPI: (supports S0 S4 S5)
[    0.344860] ACPI: Using IOAPIC for interrupt routing
[    0.350925] HEST: Table not found.
[    0.351001] PCI: Ignoring host bridge windows from ACPI; if necessary, use "pci=use_crs" and report a bug
[    0.352708] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-01])
[    0.355968] pci_root PNP0A03:00: host bridge window [io  0x03c0-0x03df] (ignored)
[    0.355975] pci_root PNP0A03:00: host bridge window [io  0x03b0-0x03bb] (ignored)
[    0.355982] pci_root PNP0A03:00: host bridge window [mem 0x000a0000-0x000bffff] (ignored)
[    0.355988] pci_root PNP0A03:00: host bridge window [mem 0xfc000000-0xffffffff] (ignored)
[    0.355995] pci_root PNP0A03:00: host bridge window [io  0x03e0-0x24ff] (ignored)
[    0.356001] pci_root PNP0A03:00: host bridge window [io  0x03bc-0x03bf] (ignored)
[    0.356006] pci_root PNP0A03:00: host bridge window [io  0x0000-0x03af] (ignored)
[    0.356023] pci 0000:00:00.0: [1166:0014] type 0 class 0x000600
[    0.356066] pci 0000:00:00.1: [1166:0014] type 0 class 0x000600
[    0.356100] pci 0000:00:00.2: [1166:0014] type 0 class 0x000600
[    0.356153] pci 0000:00:06.0: [1002:4752] type 0 class 0x000300
[    0.356176] pci 0000:00:06.0: reg 10: [mem 0xfd000000-0xfdffffff]
[    0.356189] pci 0000:00:06.0: reg 14: [io  0x2400-0x24ff]
[    0.356201] pci 0000:00:06.0: reg 18: [mem 0xfebff000-0xfebfffff]
[    0.356241] pci 0000:00:06.0: reg 30: [mem 0x00000000-0x0001ffff pref]
[    0.356274] pci 0000:00:06.0: supports D1 D2
[    0.356303] pci 0000:00:0f.0: [1166:0201] type 0 class 0x000600
[    0.356362] pci 0000:00:0f.1: [1166:0212] type 0 class 0x000101
[    0.356380] pci 0000:00:0f.1: reg 10: [io  0x01f0-0x01f7]
[    0.356391] pci 0000:00:0f.1: reg 14: [io  0x03f4-0x03f7]
[    0.356402] pci 0000:00:0f.1: reg 18: [io  0x0170-0x0177]
[    0.356414] pci 0000:00:0f.1: reg 1c: [io  0x0374-0x0377]
[    0.356425] pci 0000:00:0f.1: reg 20: [io  0x0700-0x070f]
[    0.356464] pci 0000:00:0f.2: [1166:0220] type 0 class 0x000c03
[    0.356482] pci 0000:00:0f.2: reg 10: [mem 0xfebfe000-0xfebfefff]
[    0.356553] pci 0000:00:0f.3: [1166:0225] type 0 class 0x000601
[    0.356660] pci 0000:00:10.0: [1166:0101] type 0 class 0x000600
[    0.356725] pci 0000:00:10.2: [1166:0101] type 0 class 0x000600
[    0.356796] pci 0000:00:11.0: [1166:0101] type 0 class 0x000600
[    0.356860] pci 0000:00:11.2: [1166:0101] type 0 class 0x000600
[    0.356939] pci_bus 0000:00: on NUMA node 0
[    0.356945] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[    0.357057]  pci0000:00: Unable to request _OSC control (_OSC support mask: 0x1e)
[    0.359272] ACPI: PCI Root Bridge [PCI1] (domain 0000 [bus 02-03])
[    0.359838] pci_bus 0000:02: on NUMA node 0
[    0.359842] ACPI: PCI Interrupt Routing Table [\_SB_.PCI1._PRT]
[    0.359897]  pci0000:02: Unable to request _OSC control (_OSC support mask: 0x1e)
[    0.360441] ACPI: PCI Root Bridge [PCI2] (domain 0000 [bus 04-05])
[    0.361006] pci_bus 0000:04: on NUMA node 0
[    0.361010] ACPI: PCI Interrupt Routing Table [\_SB_.PCI2._PRT]
[    0.361065]  pci0000:04: Unable to request _OSC control (_OSC support mask: 0x1e)
[    0.362036] ACPI: PCI Root Bridge [PCI3] (domain 0000 [bus 06-07])
[    0.363904] pci_root PNP0A03:03: host bridge window [mem 0xfa000000-0xfbffffff] (ignored)
[    0.363912] pci_root PNP0A03:03: host bridge window [io  0x2500-0x257f] (ignored)
[    0.363942] pci 0000:06:08.0: [8086:1010] type 0 class 0x000200
[    0.363967] pci 0000:06:08.0: reg 10: [mem 0xfbfe0000-0xfbffffff 64bit]
[    0.363990] pci 0000:06:08.0: reg 20: [io  0x2500-0x253f]
[    0.364044] pci 0000:06:08.0: PME# supported from D0 D3hot D3cold
[    0.364051] pci 0000:06:08.0: PME# disabled
[    0.364079] pci 0000:06:08.1: [8086:1010] type 0 class 0x000200
[    0.364104] pci 0000:06:08.1: reg 10: [mem 0xfbfc0000-0xfbfdffff 64bit]
[    0.364127] pci 0000:06:08.1: reg 20: [io  0x2540-0x257f]
[    0.364178] pci 0000:06:08.1: PME# supported from D0 D3hot D3cold
[    0.364185] pci 0000:06:08.1: PME# disabled
[    0.364226] pci_bus 0000:06: on NUMA node 0
[    0.364230] ACPI: PCI Interrupt Routing Table [\_SB_.PCI3._PRT]
[    0.364296]  pci0000:06: Unable to request _OSC control (_OSC support mask: 0x1e)
[    0.365362] ACPI: PCI Root Bridge [PCI4] (domain 0000 [bus 08])
[    0.367203] pci_root PNP0A03:04: host bridge window [mem 0xf8000000-0xf9ffffff] (ignored)
[    0.367210] pci_root PNP0A03:04: host bridge window [io  0x2580-0xffff] (ignored)
[    0.367239] pci 0000:08:07.0: [1000:0030] type 0 class 0x000100
[    0.367262] pci 0000:08:07.0: reg 10: [io  0x2600-0x26ff]
[    0.367276] pci 0000:08:07.0: reg 14: [mem 0xf9ff0000-0xf9ffffff 64bit]
[    0.367290] pci 0000:08:07.0: reg 1c: [mem 0xf9fe0000-0xf9feffff 64bit]
[    0.367307] pci 0000:08:07.0: reg 30: [mem 0x00000000-0x000fffff pref]
[    0.367346] pci 0000:08:07.0: supports D1 D2
[    0.367375] pci 0000:08:07.1: [1000:0030] type 0 class 0x000100
[    0.367397] pci 0000:08:07.1: reg 10: [io  0x2700-0x27ff]
[    0.367411] pci 0000:08:07.1: reg 14: [mem 0xf9fd0000-0xf9fdffff 64bit]
[    0.367425] pci 0000:08:07.1: reg 1c: [mem 0xf9fc0000-0xf9fcffff 64bit]
[    0.367442] pci 0000:08:07.1: reg 30: [mem 0x00000000-0x000fffff pref]
[    0.367480] pci 0000:08:07.1: supports D1 D2
[    0.367524] pci_bus 0000:08: on NUMA node 0
[    0.367528] ACPI: PCI Interrupt Routing Table [\_SB_.PCI4._PRT]
[    0.367594]  pci0000:08: Unable to request _OSC control (_OSC support mask: 0x1e)
[    0.367916] ACPI: PCI Interrupt Link [LP00] (IRQs) *0, disabled.
[    0.368293] ACPI: PCI Interrupt Link [LP01] (IRQs) *0, disabled.
[    0.368668] ACPI: PCI Interrupt Link [LP02] (IRQs) *0, disabled.
[    0.369044] ACPI: PCI Interrupt Link [LP03] (IRQs) *0, disabled.
[    0.369418] ACPI: PCI Interrupt Link [LP04] (IRQs) *0, disabled.
[    0.369791] ACPI: PCI Interrupt Link [LP05] (IRQs) *0, disabled.
[    0.370173] ACPI: PCI Interrupt Link [LP06] (IRQs) *0, disabled.
[    0.370547] ACPI: PCI Interrupt Link [LP07] (IRQs) *0, disabled.
[    0.370923] ACPI: PCI Interrupt Link [LP08] (IRQs) *0, disabled.
[    0.371300] ACPI: PCI Interrupt Link [LP09] (IRQs) *0, disabled.
[    0.371680] ACPI: PCI Interrupt Link [LP0A] (IRQs *11)
[    0.372009] ACPI: PCI Interrupt Link [LP0B] (IRQs *9)
[    0.372340] ACPI: PCI Interrupt Link [LP0C] (IRQs *10)
[    0.372668] ACPI: PCI Interrupt Link [LP0D] (IRQs *4)
[    0.372996] ACPI: PCI Interrupt Link [LP0E] (IRQs *5)
[    0.373357] ACPI: PCI Interrupt Link [LP0F] (IRQs) *0, disabled.
[    0.373732] ACPI: PCI Interrupt Link [LP10] (IRQs) *0, disabled.
[    0.374108] ACPI: PCI Interrupt Link [LP11] (IRQs) *0, disabled.
[    0.374482] ACPI: PCI Interrupt Link [LP12] (IRQs) *0, disabled.
[    0.374855] ACPI: PCI Interrupt Link [LP13] (IRQs) *0, disabled.
[    0.375232] ACPI: PCI Interrupt Link [LP14] (IRQs) *0, disabled.
[    0.375607] ACPI: PCI Interrupt Link [LP15] (IRQs) *0, disabled.
[    0.375983] ACPI: PCI Interrupt Link [LP16] (IRQs) *0, disabled.
[    0.376356] ACPI: PCI Interrupt Link [LP17] (IRQs) *0, disabled.
[    0.376745] ACPI: PCI Interrupt Link [LP18] (IRQs) *0, disabled.
[    0.377122] ACPI: PCI Interrupt Link [LP19] (IRQs) *0, disabled.
[    0.377497] ACPI: PCI Interrupt Link [LP1A] (IRQs) *0, disabled.
[    0.377870] ACPI: PCI Interrupt Link [LP1B] (IRQs) *0, disabled.
[    0.378243] ACPI: PCI Interrupt Link [LP1C] (IRQs) *0, disabled.
[    0.378617] ACPI: PCI Interrupt Link [LP1D] (IRQs) *0, disabled.
[    0.378994] ACPI: PCI Interrupt Link [LP1E] (IRQs) *0, disabled.
[    0.379369] ACPI: PCI Interrupt Link [LP1F] (IRQs) *0, disabled.
[    0.380106] ACPI: PCI Interrupt Link [LPUS] (IRQs *3)
[    0.380434] vgaarb: device added: PCI:0000:00:06.0,decodes=io+mem,owns=io+mem,locks=none
[    0.380539] vgaarb: loaded
[    0.380605] vgaarb: bridge control possible 0000:00:06.0
[    0.380723] PCI: Using ACPI for IRQ routing
[    0.380916] PCI: pci_cache_line_size set to 64 bytes
[    0.380997] reserve RAM buffer: 000000000009d400 - 000000000009ffff 
[    0.381001] reserve RAM buffer: 000000009ffeb2c0 - 000000009fffffff 
[    0.384404] pnp: PnP ACPI init
[    0.384497] ACPI: bus type pnp registered
[    0.386202] pnp 00:00: [io  0x03c0-0x03df window]
[    0.386206] pnp 00:00: [io  0x03b0-0x03bb window]
[    0.386210] pnp 00:00: [mem 0x000a0000-0x000bffff window]
[    0.386214] pnp 00:00: [bus 00-01]
[    0.386217] pnp 00:00: [mem 0xfc000000-0xffffffff window]
[    0.386221] pnp 00:00: [io  0x03e0-0x24ff window]
[    0.386224] pnp 00:00: [io  0x03bc-0x03bf window]
[    0.386228] pnp 00:00: [io  0x0000-0x03af window]
[    0.386318] pnp 00:00: Plug and Play ACPI device, IDs PNP0a03 (active)
[    0.387195] pnp 00:01: [io  0x0510-0x0517]
[    0.387199] pnp 00:01: [io  0x0504-0x0507]
[    0.387202] pnp 00:01: [io  0x0500-0x0503]
[    0.387205] pnp 00:01: [io  0x0420-0x0427]
[    0.387211] pnp 00:01: [io  0x0460-0x0461]
[    0.387214] pnp 00:01: [io  0x002e-0x002f]
[    0.387289] system 00:01: [io  0x0510-0x0517] has been reserved
[    0.387366] system 00:01: [io  0x0504-0x0507] has been reserved
[    0.387441] system 00:01: [io  0x0500-0x0503] has been reserved
[    0.387513] system 00:01: [io  0x0420-0x0427] has been reserved
[    0.387586] system 00:01: [io  0x0460-0x0461] has been reserved
[    0.387659] system 00:01: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.387747] pnp 00:02: Plug and Play ACPI device, IDs PNP0303 (active)
[    0.387884] pnp 00:03: [irq 12]
[    0.387925] pnp 00:03: Plug and Play ACPI device, IDs PNP0f13 (active)
[    0.388268] pnp 00:04: [dma 2]
[    0.388275] pnp 00:04: [irq 6]
[    0.388280] pnp 00:04: [io  0x03f0-0x03f5]
[    0.388330] pnp 00:04: Plug and Play ACPI device, IDs PNP0700 (active)
[    0.388417] pnp 00:05: [io  0x0020-0x0021]
[    0.388421] pnp 00:05: [io  0x00a0-0x00a1]
[    0.388467] pnp 00:05: Plug and Play ACPI device, IDs PNP0003 (active)
[    0.388492] pnp 00:06: [dma 4]
[    0.388495] pnp 00:06: [io  0x0000-0x001f]
[    0.388499] pnp 00:06: [io  0x0080-0x008f]
[    0.388501] pnp 00:06: [io  0x00c0-0x00df]
[    0.388536] pnp 00:06: Plug and Play ACPI device, IDs PNP0200 (active)
[    0.388570] pnp 00:07: [io  0x0070-0x0073]
[    0.388576] pnp 00:07: [irq 8]
[    0.388613] pnp 00:07: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.388636] pnp 00:08: [io  0x0061]
[    0.388674] pnp 00:08: Plug and Play ACPI device, IDs PNP0800 (active)
[    0.388697] pnp 00:09: [io  0x00f0-0x00ff]
[    0.388704] pnp 00:09: [irq 13]
[    0.388743] pnp 00:09: Plug and Play ACPI device, IDs PNP0c04 (active)
[    0.388769] pnp 00:0a: [io  0x0092]
[    0.388772] pnp 00:0a: [io  0x00eb-0x00ec]
[    0.388775] pnp 00:0a: [io  0x01ec-0x01ef]
[    0.388778] pnp 00:0a: [io  0x0400-0x04fe]
[    0.388781] pnp 00:0a: [io  0x0600]
[    0.388784] pnp 00:0a: [io  0x0800-0x080f]
[    0.388787] pnp 00:0a: [io  0x0c00-0x0cfe]
[    0.388790] pnp 00:0a: [io  0x0f50-0x0f58]
[    0.388793] pnp 00:0a: [mem 0xfec00000-0xffffffff]
[    0.388858] system 00:0a: [io  0x01ec-0x01ef] has been reserved
[    0.388934] system 00:0a: [io  0x0400-0x04fe] could not be reserved
[    0.389008] system 00:0a: [io  0x0600] has been reserved
[    0.389082] system 00:0a: [io  0x0800-0x080f] has been reserved
[    0.389154] system 00:0a: [io  0x0c00-0x0cfe] could not be reserved
[    0.389228] system 00:0a: [io  0x0f50-0x0f58] has been reserved
[    0.389302] system 00:0a: [mem 0xfec00000-0xffffffff] could not be reserved
[    0.389377] system 00:0a: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.389860] pnp 00:0b: [bus 02-03]
[    0.389927] pnp 00:0b: Plug and Play ACPI device, IDs PNP0a03 (active)
[    0.390395] pnp 00:0c: [bus 04-05]
[    0.390456] pnp 00:0c: Plug and Play ACPI device, IDs PNP0a03 (active)
[    0.391312] pnp 00:0d: [bus 06-07]
[    0.391317] pnp 00:0d: [mem 0xfa000000-0xfbffffff window]
[    0.391321] pnp 00:0d: [io  0x2500-0x257f window]
[    0.391383] pnp 00:0d: Plug and Play ACPI device, IDs PNP0a03 (active)
[    0.392240] pnp 00:0e: [bus 08]
[    0.392245] pnp 00:0e: [mem 0xf8000000-0xf9ffffff window]
[    0.392248] pnp 00:0e: [io  0x2580-0xffff window]
[    0.392306] pnp 00:0e: Plug and Play ACPI device, IDs PNP0a03 (active)
[    0.393184] pnp: PnP ACPI: found 15 devices
[    0.393254] ACPI: ACPI bus type pnp unregistered
[    0.393317] PnPBIOS: Disabled by ACPI PNP
[    0.430257] Switching to clocksource acpi_pm
[    0.430336] PCI: max bus depth: 0 pci_try_num: 1
[    0.430336] pci 0000:00:06.0: BAR 6: assigned [mem 0xa0000000-0xa001ffff pref]
[    0.430336] pci 0000:08:07.0: BAR 6: assigned [mem 0xa0100000-0xa01fffff pref]
[    0.430336] pci 0000:08:07.1: BAR 6: assigned [mem 0xa0200000-0xa02fffff pref]
[    0.430336] pci_bus 0000:00: resource 0 [io  0x0000-0xffff]
[    0.430336] pci_bus 0000:00: resource 1 [mem 0x00000000-0xffffffff]
[    0.430336] pci_bus 0000:02: resource 0 [io  0x0000-0xffff]
[    0.430339] pci_bus 0000:02: resource 1 [mem 0x00000000-0xffffffff]
[    0.430343] pci_bus 0000:04: resource 0 [io  0x0000-0xffff]
[    0.430346] pci_bus 0000:04: resource 1 [mem 0x00000000-0xffffffff]
[    0.430350] pci_bus 0000:06: resource 0 [io  0x0000-0xffff]
[    0.430353] pci_bus 0000:06: resource 1 [mem 0x00000000-0xffffffff]
[    0.430357] pci_bus 0000:08: resource 0 [io  0x0000-0xffff]
[    0.430360] pci_bus 0000:08: resource 1 [mem 0x00000000-0xffffffff]
[    0.430485] NET: Registered protocol family 2
[    0.430652] IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.431035] TCP established hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.431971] TCP bind hash table entries: 65536 (order: 7, 524288 bytes)
[    0.432554] TCP: Hash tables configured (established 131072 bind 65536)
[    0.432633] TCP reno registered
[    0.432706] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.432794] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.433014] Switched to NOHz mode on CPU #0
[    0.433024] Switched to NOHz mode on CPU #3
[    0.433068] Switched to NOHz mode on CPU #1
[    0.433083] Switched to NOHz mode on CPU #2
[    0.433473] NET: Registered protocol family 1
[    0.433570] pci 0000:00:06.0: Boot video device
[    0.433575] PCI: CLS mismatch (64 != 32), using 64 bytes
[    0.433764] ACPI: PCI Interrupt Link [LPUS] enabled at IRQ 3
[    0.433851] pci 0000:00:0f.2: PCI INT A -> Link[LPUS] -> GSI 3 (level, low) -> IRQ 3
[    0.500033] pci 0000:00:0f.2: PCI INT A disabled
[    0.500203] Unpacking initramfs...
[    1.003988] Freeing initrd memory: 2000k freed
[    1.006578] audit: initializing netlink socket (disabled)
[    1.006710] type=2000 audit(1330858318.006:1): initialized
[    1.014499] highmem bounce pool size: 64 pages
[    1.014579] HugeTLB registered 4 MB page size, pre-allocated 0 pages
[    1.029699] VFS: Disk quotas dquot_6.5.2
[    1.029824] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    1.030034] msgmni has been set to 1723
[    1.030201] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[    1.030294] io scheduler noop registered
[    1.030361] io scheduler deadline registered (default)
[    1.030458] io scheduler cfq registered
[    1.030689] ERST: Table is not found!
[    1.030778] isapnp: Scanning for PnP cards...
[    1.387854] isapnp: No Plug & Play device found
[    1.388033] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    1.577223] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at 0x0,0x0 irq 0,12
[    1.577317] i8042: PNP: PS/2 controller has invalid data port 0x0; using default 0x60
[    1.577406] i8042: PNP: PS/2 controller has invalid command port 0x0; using default 0x64
[    1.577496] i8042: PNP: PS/2 controller doesn't have KBD irq; using default 1
[    1.579279] serio: i8042 KBD port at 0x60,0x64 irq 1
[    1.579360] serio: i8042 AUX port at 0x60,0x64 irq 12
[    1.579537] mousedev: PS/2 mouse device common for all mice
[    1.579691] input: PC Speaker as /devices/platform/pcspkr/input/input0
[    1.579956] rtc_cmos 00:07: rtc core: registered rtc_cmos as rtc0
[    1.580092] rtc0: alarms up to one year, 242 bytes nvram
[    1.580179] cpuidle: using governor ladder
[    1.580248] cpuidle: using governor menu
[    1.580452] TCP cubic registered
[    1.580521] NET: Registered protocol family 17
[    1.580598] Registering the dns_resolver key type
[    1.580699] Using IPI No-Shortcut mode
[    1.580896] registered taskstats version 1
[    1.588621] rtc_cmos 00:07: setting system clock to 2012-03-04 10:51:58 UTC (1330858318)
[    1.588875] Freeing unused kernel memory: 388k freed
[    1.592065] Processing INITRAMFS
[    1.600372] loading module sd_mod
[    1.605254] SCSI subsystem initialized
[    1.606296] loading module usbhid
[    1.610663] usbcore: registered new interface driver usbfs
[    1.610784] usbcore: registered new interface driver hub
[    1.610999] usbcore: registered new device driver usb
[    1.612559] usbcore: registered new interface driver usbhid
[    1.612636] usbhid: USB HID core driver
[    1.755458] libata version 3.00 loaded.
[    1.761744] scsi0 : pata_serverworks
[    1.763847] scsi1 : pata_serverworks
[    1.764462] ata1: PATA max UDMA/100 cmd 0x1f0 ctl 0x3f6 bmdma 0x700 irq 14
[    1.764551] ata2: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0x708 irq 15
[    1.940382] ata1.00: ATAPI: LG CD-ROM CRN-8245B, 1.16, max UDMA/33
[    1.970368] ata1.00: configured for UDMA/33
[    1.972200] scsi 0:0:0:0: CD-ROM            LG       CD-ROM CRN-8245B 1.16 PQ: 0 ANSI: 5
[    2.016707] Refined TSC clocksource calibration: 2660.836 MHz.
[    2.016789] Switching to clocksource tsc
[    2.130653] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    2.130800] ohci_hcd 0000:00:0f.2: PCI INT A -> Link[LPUS] -> GSI 3 (level, low) -> IRQ 3
[    2.130936] ohci_hcd 0000:00:0f.2: OHCI Host Controller
[    2.131069] ohci_hcd 0000:00:0f.2: new USB bus registered, assigned bus number 1
[    2.131204] ohci_hcd 0000:00:0f.2: irq 3, io mem 0xfebfe000
[    2.185419] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001
[    2.185499] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.185589] usb usb1: Product: OHCI Host Controller
[    2.185658] usb usb1: Manufacturer: Linux 3.0.0-i686 ohci_hcd
[    2.185730] usb usb1: SerialNumber: 0000:00:0f.2
[    2.186405] hub 1-0:1.0: USB hub found
[    2.186497] hub 1-0:1.0: 4 ports detected
[    2.200670] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[    2.200758] e1000: Copyright (c) 1999-2006 Intel Corporation.
[    2.200888] e1000 0000:06:08.0: PCI INT A -> GSI 29 (level, low) -> IRQ 29
[    2.503548] e1000 0000:06:08.0: eth0: (PCI-X:100MHz:64-bit) 00:0d:60:19:14:1c
[    2.503649] e1000 0000:06:08.0: eth0: Intel(R) PRO/1000 Network Connection
[    2.503788] e1000 0000:06:08.1: PCI INT B -> GSI 30 (level, low) -> IRQ 30
[    2.806894] e1000 0000:06:08.1: eth1: (PCI-X:100MHz:64-bit) 00:0d:60:19:14:1d
[    2.806993] e1000 0000:06:08.1: eth1: Intel(R) PRO/1000 Network Connection
[    2.814642] Fusion MPT base driver 3.04.19
[    2.814732] Copyright (c) 1999-2008 LSI Corporation
[    2.816841] Fusion MPT SPI Host driver 3.04.19
[    2.817022] mptspi 0000:08:07.0: PCI INT A -> GSI 27 (level, low) -> IRQ 27
[    2.817314] mptbase: ioc0: Initiating bringup
[    3.230016] ioc0: LSI53C1030 B2: Capabilities={Initiator}
[    4.120665] scsi2 : ioc0: LSI53C1030 B2, FwRev=01000e00h, Ports=1, MaxQ=222, IRQ=27
[    4.489143] mptspi 0000:08:07.1: PCI INT B -> GSI 28 (level, low) -> IRQ 28
[    4.489361] mptbase: ioc1: Initiating bringup
[   19.486696] mptbase: ioc0: WARNING - Issuing Reset from mpt_config!!, doorbell=0x24000000
[   20.126685] mptbase: ioc0: Attempting Retry Config request type 0x4, page 0x1, action 2
[   20.126831] mptbase: ioc0: Retry completed ret=0x0 timeleft=4500
[   20.127697] scsi 2:0:0:0: Direct-Access     IBM-ESXS DTN073C3UCDY10FN S25J PQ: 0 ANSI: 3
[   20.127805] scsi target2:0:0: Beginning Domain Validation
[   20.138358] scsi target2:0:0: Ending Domain Validation
[   20.138485] scsi target2:0:0: FAST-160 WIDE SCSI 320.0 MB/s DT IU QAS HMCS (6.25 ns, offset 127)
[   20.139657] scsi 2:0:1:0: Direct-Access     IBM-ESXS DTN073C3UCDY10FN S27M PQ: 0 ANSI: 3
[   20.139757] scsi target2:0:1: Beginning Domain Validation
[   20.150181] scsi target2:0:1: Ending Domain Validation
[   20.150308] scsi target2:0:1: FAST-160 WIDE SCSI 320.0 MB/s DT IU QAS HMCS (6.25 ns, offset 127)
[   20.151467] scsi 2:0:2:0: Direct-Access     IBM-ESXS DTN073C3UCDY10FN S27M PQ: 0 ANSI: 3
[   20.151568] scsi target2:0:2: Beginning Domain Validation
[   20.161983] scsi target2:0:2: Ending Domain Validation
[   20.162111] scsi target2:0:2: FAST-160 WIDE SCSI 320.0 MB/s DT IU QAS HMCS (6.25 ns, offset 127)
[   20.163285] scsi 2:0:3:0: Direct-Access     IBM-ESXS DTN073C3UCDY10FN S27M PQ: 0 ANSI: 3
[   20.163399] scsi target2:0:3: Beginning Domain Validation
[   20.173803] scsi target2:0:3: Ending Domain Validation
[   20.173932] scsi target2:0:3: FAST-160 WIDE SCSI 320.0 MB/s DT IU QAS HMCS (6.25 ns, offset 127)
[   20.177932] scsi 2:0:4:0: Direct-Access     IBM-ESXS MAW3073NC     FN C206 PQ: 0 ANSI: 4
[   20.178036] scsi target2:0:4: Beginning Domain Validation
[   20.197999] scsi target2:0:4: Ending Domain Validation
[   20.198126] scsi target2:0:4: FAST-160 WIDE SCSI 320.0 MB/s DT IU HMCS (6.25 ns, offset 127)
[   20.699166] scsi 2:0:8:0: Processor         IBM      32P0032a S320  1 1    PQ: 0 ANSI: 2
[   20.699271] scsi target2:0:8: Beginning Domain Validation
[   20.701305] scsi target2:0:8: Ending Domain Validation
[   20.701433] scsi target2:0:8: asynchronous
[   22.455641] sd 2:0:0:0: [sda] 143374000 512-byte logical blocks: (73.4 GB/68.3 GiB)
[   22.456769] sd 2:0:0:0: [sda] Write Protect is off
[   22.456884] sd 2:0:0:0: [sda] Mode Sense: cb 00 00 08
[   22.458039] sd 2:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[   22.461351] sd 2:0:1:0: [sdb] 143374000 512-byte logical blocks: (73.4 GB/68.3 GiB)
[   22.462396] sd 2:0:1:0: [sdb] Write Protect is off
[   22.462513] sd 2:0:1:0: [sdb] Mode Sense: cb 00 00 08
[   22.463600] sd 2:0:1:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[   22.466840] sd 2:0:2:0: [sdc] 143374000 512-byte logical blocks: (73.4 GB/68.3 GiB)
[   22.467968] sd 2:0:2:0: [sdc] Write Protect is off
[   22.468057] sd 2:0:2:0: [sdc] Mode Sense: cb 00 00 08
[   22.469469] sd 2:0:2:0: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[   22.471825] sd 2:0:3:0: [sdd] 143374000 512-byte logical blocks: (73.4 GB/68.3 GiB)
[   22.472990] sd 2:0:3:0: [sdd] Write Protect is off
[   22.473079] sd 2:0:3:0: [sdd] Mode Sense: cb 00 00 08
[   22.474481] sd 2:0:3:0: [sdd] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[   22.478704] sd 2:0:4:0: [sde] 143374000 512-byte logical blocks: (73.4 GB/68.3 GiB)
[   22.481389] sd 2:0:4:0: [sde] Write Protect is off
[   22.481484] sd 2:0:4:0: [sde] Mode Sense: cf 00 00 08
[   22.482867] sd 2:0:4:0: [sde] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[   22.488021]  sda: sda1 sda2 sda3 sda4 < sda5 sda6 sda7 >
[   22.495096]  sdb: sdb1 sdb2 sdb3 sdb4 < sdb5 sdb6 sdb7 >
[   22.497675] sd 2:0:0:0: [sda] Attached SCSI disk
[   22.500312]  sdd: sdd1 sdd2 sdd3 sdd4 < sdd5 sdd6 sdd7 >
[   22.506406]  sdc: sdc1 sdc2 sdc3 sdc4 < sdc5 sdc6 sdc7 >
[   22.508003]  sde: sde1 sde2 sde3 sde4 < sde5 sde6 sde7 >
[   22.586994] sd 2:0:1:0: [sdb] Attached SCSI disk
[   22.604075] sd 2:0:3:0: [sdd] Attached SCSI disk
[   22.635177] ------------[ cut here ]------------
[   22.635290] WARNING: at block/genhd.c:1474 __disk_unblock_events+0xe1/0xf0()
[   22.635371] Hardware name: eserver xSeries 345 -[867052G]-
[   22.635448] Modules linked in: mptspi(+) mptscsih mptbase scsi_transport_spi e1000 ohci_hcd pata_serverworks libata usbhid hid usbcore sd_mod scsi_mod crc_t10dif
[   22.636260] Pid: 271, comm: blkid Not tainted 3.0.0-i686 #3.0.23
[   22.636334] Call Trace:
[   22.636407]  [<c1040118>] ? warn_slowpath_common+0x78/0xb0
[   22.636482]  [<c1188d81>] ? __disk_unblock_events+0xe1/0xf0
[   22.636556]  [<c1188d81>] ? __disk_unblock_events+0xe1/0xf0
[   22.636631]  [<c104016b>] ? warn_slowpath_null+0x1b/0x20
[   22.636705]  [<c1188d81>] ? __disk_unblock_events+0xe1/0xf0
[   22.636783]  [<c1124f57>] ? __blkdev_get+0x157/0x3c0
[   22.636856]  [<c1125203>] ? blkdev_get+0x43/0x2b0
[   22.636931]  [<c11616b0>] ? security_dentry_open+0x70/0x80
[   22.637006]  [<c11245f8>] ? bd_acquire+0x28/0xc0
[   22.637080]  [<c10f70c8>] ? __dentry_open+0x118/0x2b0
[   22.637154]  [<c10f7348>] ? nameidata_to_filp+0x68/0x70
[   22.637228]  [<c1125470>] ? blkdev_get+0x2b0/0x2b0
[   22.637302]  [<c110429d>] ? do_last+0xcd/0x820
[   22.637375]  [<c10fa48b>] ? get_empty_filp+0x11b/0x1d0
[   22.637449]  [<c1105601>] ? path_openat+0xa1/0x370
[   22.637523]  [<c11059b4>] ? do_filp_open+0x34/0x90
[   22.637597]  [<c10464c1>] ? irq_exit+0x31/0xa0
[   22.637672]  [<c12e69d2>] ? call_function_interrupt+0x2a/0x30
[   22.637746]  [<c10f6cce>] ? do_sys_open+0xee/0x1c0
[   22.637820]  [<c10f6e1c>] ? sys_open+0x2c/0x40
[   22.637892]  [<c12e70d8>] ? sysenter_do_call+0x12/0x28
[   22.637963] ---[ end trace 9071b164502805fa ]---
[   22.645185] sd 2:0:2:0: [sdc] Attached SCSI disk
[   22.659254] sd 2:0:4:0: [sde] Attached SCSI disk
[   34.493348] mptbase: ioc1: ERROR - Wait IOC_READY state (0x20000000) timeout(15)!
[   38.883345] ioc1: LSI53C1030 B2: Capabilities={Initiator}
[   39.283343] ioc0: LSI53C1030 B2: Capabilities={Initiator}
[   40.567328] scsi3 : ioc1: LSI53C1030 B2, FwRev=01000e00h, Ports=1, MaxQ=222, IRQ=28
[   41.087234] configuring raid array md1
[   41.089401] mdadm: sending ioctl 800c0910 to a partition!
[   41.089480] mdadm: sending ioctl 800c0910 to a partition!
[   41.089567] mdadm: sending ioctl 1261 to a partition!
[   41.089638] mdadm: sending ioctl 1261 to a partition!
[   41.106558] mdadm: sending ioctl 1261 to a partition!
[   41.106630] mdadm: sending ioctl 1261 to a partition!
[   41.108013] mdadm: sending ioctl 1261 to a partition!
[   41.108084] mdadm: sending ioctl 1261 to a partition!
[   41.108832] mdadm: sending ioctl 1261 to a partition!
[   41.108903] mdadm: sending ioctl 1261 to a partition!
[   41.485564] md: md1 stopped.
[   41.623942] md: bind<sdb1>
[   41.627272] md: bind<sdc1>
[   41.636989] md: bind<sdd1>
[   41.690496] md: bind<sde1>
[   41.759812] md: bind<sda1>
[   41.763241] md: raid1 personality registered for level 1
[   41.763610] bio: create slab <bio-1> at 1
[   41.763853] md/raid1:md1: active with 5 out of 5 mirrors
[   41.763978] md1: detected capacity change from 0 to 263061504
[   41.764407] mdadm: /dev/md1 has been started with 5 drives.
[   41.765219] mounting ext4 fs on /dev/md1 (options: relatime,nodiratime,data=ordered)
[   41.798356]  md1: unknown partition table
[   41.868159] EXT4-fs (md1): warning: checktime reached, running e2fsck is recommended
[   41.990762] EXT4-fs (md1): mounted filesystem with ordered data mode. Opts: data=ordered
[   42.155948] mount: mounting /run on /root/run failed: No such file or directory
[   44.708416] EXT4-fs (md1): re-mounted. Opts: (null)
[   57.520667] EXT4-fs (md1): re-mounted. Opts: data=ordered
[   57.777857] scsi_verify_blk_ioctl: 224 callbacks suppressed
[   57.777935] mdadm: sending ioctl 800c0910 to a partition!
[   57.778007] mdadm: sending ioctl 800c0910 to a partition!
[   57.778092] mdadm: sending ioctl 1261 to a partition!
[   57.778162] mdadm: sending ioctl 1261 to a partition!
[   57.796934] mdadm: sending ioctl 1261 to a partition!
[   57.797007] mdadm: sending ioctl 1261 to a partition!
[   57.798379] mdadm: sending ioctl 1261 to a partition!
[   57.798450] mdadm: sending ioctl 1261 to a partition!
[   57.811598] mdadm: sending ioctl 1261 to a partition!
[   57.811670] mdadm: sending ioctl 1261 to a partition!
[   58.614204] md: md2 stopped.
[   58.663890] md: bind<sdb2>
[   58.665318] md: bind<sdc2>
[   58.666644] md: bind<sdd2>
[   58.669065] md: bind<sde2>
[   58.670420] md: bind<sda2>
[   58.833384] raid6: int32x1    731 MB/s
[   58.890028] raid6: int32x2    905 MB/s
[   58.946721] raid6: int32x4    862 MB/s
[   59.003434] raid6: int32x8    567 MB/s
[   59.060022] raid6: mmxx1     2200 MB/s
[   59.116679] raid6: mmxx2     2763 MB/s
[   59.173374] raid6: sse1x1    1498 MB/s
[   59.230029] raid6: sse1x2    2442 MB/s
[   59.286676] raid6: sse2x1    2968 MB/s
[   59.343340] raid6: sse2x2    2997 MB/s
[   59.343409] raid6: using algorithm sse2x2 (2997 MB/s)
[   59.363383] xor: automatically using best checksumming function: pIII_sse
[   59.380002]    pIII_sse  :  3386.400 MB/sec
[   59.380074] xor: using function: pIII_sse (3386.400 MB/sec)
[   59.432762] md: raid6 personality registered for level 6
[   59.432847] md: raid5 personality registered for level 5
[   59.432922] md: raid4 personality registered for level 4
[   59.433280] md/raid:md2: device sda2 operational as raid disk 0
[   59.433423] md/raid:md2: device sde2 operational as raid disk 4
[   59.433505] md/raid:md2: device sdd2 operational as raid disk 3
[   59.433591] md/raid:md2: device sdc2 operational as raid disk 2
[   59.433676] md/raid:md2: device sdb2 operational as raid disk 1
[   59.434627] md/raid:md2: allocated 5265kB
[   59.434783] md/raid:md2: raid level 5 active with 5 out of 5 devices, algorithm 2
[   59.434873] RAID conf printout:
[   59.434876]  --- level:5 rd:5 wd:5
[   59.434879]  disk 0, o:1, dev:sda2
[   59.434882]  disk 1, o:1, dev:sdb2
[   59.434885]  disk 2, o:1, dev:sdc2
[   59.434888]  disk 3, o:1, dev:sdd2
[   59.434891]  disk 4, o:1, dev:sde2
[   59.435241] created bitmap (1 pages) for device md2
[   59.435679] md2: bitmap initialized from disk: read 1/1 pages, set 0 of 2040 bits
[   59.447002] md2: detected capacity change from 0 to 4276617216
[   59.450131]  md2: unknown partition table
[   59.624321] md: md3 stopped.
[   59.635243] md: bind<sdb3>
[   59.636578] md: bind<sdc3>
[   59.637967] md: bind<sdd3>
[   59.640357] md: bind<sde3>
[   59.641749] md: bind<sda3>
[   59.659807] md: raid10 personality registered for level 10
[   59.661696] md/raid10:md3: active with 5 out of 5 devices
[   59.661818] md3: detected capacity change from 0 to 2672558080
[   59.668310]  md3: unknown partition table
[   59.770927] md: md5 stopped.
[   59.790201] md: bind<sdb5>
[   59.791551] md: bind<sdc5>
[   59.792843] md: bind<sdd5>
[   59.795230] md: bind<sde5>
[   59.796582] md: bind<sda5>
[   59.798759] md/raid:md5: device sda5 operational as raid disk 0
[   59.798834] md/raid:md5: device sde5 operational as raid disk 4
[   59.798907] md/raid:md5: device sdd5 operational as raid disk 3
[   59.798979] md/raid:md5: device sdc5 operational as raid disk 2
[   59.799051] md/raid:md5: device sdb5 operational as raid disk 1
[   59.799896] md/raid:md5: allocated 5265kB
[   59.800108] md/raid:md5: raid level 5 active with 5 out of 5 devices, algorithm 2
[   59.800198] RAID conf printout:
[   59.800201]  --- level:5 rd:5 wd:5
[   59.800205]  disk 0, o:1, dev:sda5
[   59.800208]  disk 1, o:1, dev:sdb5
[   59.800210]  disk 2, o:1, dev:sdc5
[   59.800213]  disk 3, o:1, dev:sdd5
[   59.800216]  disk 4, o:1, dev:sde5
[   59.800544] created bitmap (2 pages) for device md5
[   59.801164] md5: bitmap initialized from disk: read 1/1 pages, set 0 of 4095 bits
[   59.810979] md5: detected capacity change from 0 to 17173577728
[   59.814005]  md5: unknown partition table
[   59.893269] md: md6 stopped.
[   59.912734] md: bind<sdb6>
[   59.914073] md: bind<sdc6>
[   59.915366] md: bind<sdd6>
[   59.917752] md: bind<sde6>
[   59.919148] md: bind<sda6>
[   59.921278] md/raid:md6: device sda6 operational as raid disk 0
[   59.921352] md/raid:md6: device sde6 operational as raid disk 4
[   59.921425] md/raid:md6: device sdd6 operational as raid disk 3
[   59.921497] md/raid:md6: device sdc6 operational as raid disk 2
[   59.921570] md/raid:md6: device sdb6 operational as raid disk 1
[   59.922416] md/raid:md6: allocated 5265kB
[   59.922555] md/raid:md6: raid level 5 active with 5 out of 5 devices, algorithm 2
[   59.922646] RAID conf printout:
[   59.922649]  --- level:5 rd:5 wd:5
[   59.922652]  disk 0, o:1, dev:sda6
[   59.922655]  disk 1, o:1, dev:sdb6
[   59.922658]  disk 2, o:1, dev:sdc6
[   59.922661]  disk 3, o:1, dev:sdd6
[   59.922663]  disk 4, o:1, dev:sde6
[   59.923029] created bitmap (2 pages) for device md6
[   59.923693] md6: bitmap initialized from disk: read 1/1 pages, set 0 of 4095 bits
[   59.931475] md6: detected capacity change from 0 to 8586788864
[   59.934532]  md6: unknown partition table
[   59.986496] md: md7 stopped.
[   60.006071] md: bind<sdb7>
[   60.007411] md: bind<sdc7>
[   60.008707] md: bind<sdd7>
[   60.011095] md: bind<sde7>
[   60.012490] md: bind<sda7>
[   60.014613] md/raid:md7: device sda7 operational as raid disk 0
[   60.014688] md/raid:md7: device sde7 operational as raid disk 4
[   60.014760] md/raid:md7: device sdd7 operational as raid disk 3
[   60.014833] md/raid:md7: device sdc7 operational as raid disk 2
[   60.014906] md/raid:md7: device sdb7 operational as raid disk 1
[   60.015743] md/raid:md7: allocated 5265kB
[   60.015875] md/raid:md7: raid level 5 active with 5 out of 5 devices, algorithm 2
[   60.015965] RAID conf printout:
[   60.015968]  --- level:5 rd:5 wd:5
[   60.015971]  disk 0, o:1, dev:sda7
[   60.015974]  disk 1, o:1, dev:sdb7
[   60.015977]  disk 2, o:1, dev:sdc7
[   60.015979]  disk 3, o:1, dev:sdd7
[   60.015982]  disk 4, o:1, dev:sde7
[   60.016345] created bitmap (8 pages) for device md7
[   60.017696] md7: bitmap initialized from disk: read 1/1 pages, set 0 of 15279 bits
[   60.032209] md7: detected capacity change from 0 to 256331743232
[   60.035474]  md7: unknown partition table
[   60.163404] Adding 2609916k swap on /dev/md3.  Priority:-1 extents:1 across:2609916k 
[  596.221382] EXT4-fs (md2): mounted filesystem with ordered data mode. Opts: data=ordered
[  596.322628] EXT4-fs (md5): mounted filesystem with ordered data mode. Opts: data=ordered
[  596.416414] EXT4-fs (md6): mounted filesystem with ordered data mode. Opts: data=ordered,usrquota,grpquota
[  596.773165] SGI XFS with ACLs, security attributes, large block/inode numbers, no debug enabled
[  596.773849] SGI XFS Quota Management subsystem
[  596.830744] XFS (md7): Mounting Filesystem
[  599.085866] XFS (md7): Ending clean mount
[  603.084899] IPv6: Loaded, but administratively disabled, reboot required to enable
[  603.147044] e1000: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX
[  605.489829] ip_tables: (C) 2000-2006 Netfilter Core Team
[  605.533016] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
[  607.803732] sshd (1037): /proc/1037/oom_adj is deprecated, please use /proc/1037/oom_score_adj instead.
[  645.153899] RPC: Registered named UNIX socket transport module.
[  645.153941] RPC: Registered udp transport module.
[  645.153975] RPC: Registered tcp transport module.
[  645.154006] RPC: Registered tcp NFSv4.1 backchannel transport module.
[  645.295822] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[  645.350044] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
[  645.356085] NFSD: starting 90-second grace period
[  649.531126] tun: Universal TUN/TAP device driver, 1.6
[  649.531167] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: WARNING: at block/genhd.c:1474 __disk_unblock_events+0xe1/0xf0() -- should I be concerned?
  2012-03-05  6:35 ` Michael Tokarev
@ 2012-03-05 17:56   ` Bart Van Assche
  2012-03-13 15:56     ` Michael Tokarev
  0 siblings, 1 reply; 7+ messages in thread
From: Bart Van Assche @ 2012-03-05 17:56 UTC (permalink / raw)
  To: Michael Tokarev; +Cc: SCSI Mailing List

On 03/05/12 06:35, Michael Tokarev wrote:
> [   22.635290] WARNING: at block/genhd.c:1474 __disk_unblock_events+0xe1/0xf0()

I've seen that warning message a few times myself, but no longer after
having applied this patch:*[PATCH] Fix NULL pointer dereference in
sd_revalidate_disk <http://marc.info/?t=113987361900002&r=1&w=2>*
(http://marc.info/?l=linux-kernel&m=132987267132047).

Bart.

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: WARNING: at block/genhd.c:1474 __disk_unblock_events+0xe1/0xf0() -- should I be concerned?
  2012-03-05 17:56   ` Bart Van Assche
@ 2012-03-13 15:56     ` Michael Tokarev
  2012-03-14 18:59       ` Bart Van Assche
  0 siblings, 1 reply; 7+ messages in thread
From: Michael Tokarev @ 2012-03-13 15:56 UTC (permalink / raw)
  To: Bart Van Assche; +Cc: SCSI Mailing List

On 05.03.2012 21:56, Bart Van Assche wrote:
> On 03/05/12 06:35, Michael Tokarev wrote:
>> [   22.635290] WARNING: at block/genhd.c:1474 __disk_unblock_events+0xe1/0xf0()
> 
> I've seen that warning message a few times myself, but no longer after
> having applied this patch:*[PATCH] Fix NULL pointer dereference in
> sd_revalidate_disk <http://marc.info/?t=113987361900002&r=1&w=2>*
> (http://marc.info/?l=linux-kernel&m=132987267132047).

So, should this patch go to -stable 3.0 maybe?  I tried 3.0
kernel on a few more machines here, and many of them shows
this very warning.  Here's a fresh example from today:

[    0.784600] aic7xxx 0000:01:07.0: PCI INT A -> Link[APC2] -> GSI 17 (level, low) -> IRQ 17
[    5.996733] scsi0 : Adaptec AIC7XXX EISA/VLB/PCI SCSI HBA DRIVER, Rev 7.0
[    5.996735]         <Adaptec 3950B Ultra2 SCSI adapter>
[    5.996736]         aic7896/97: Ultra2 Wide Channel A, SCSI Id=7, 32/253 SCBs
..
[    8.976584]  sda: sda1 sda2 sda3 sda4 < sda5 sda6 sda7 sda8 >
[    8.985840]  sdc: sdc1 sdc2 sdc3 sdc4 < sdc5 sdc6 sdc7 sdc8 >
[    8.986271]  sdb: sdb1 sdb2 sdb3 sdb4 < sdb5 sdb6 sdb7 sdb8 >
[    8.986886]  sdd: sdd1 sdd2 sdd3 sdd4 < sdd5 sdd6 sdd7 sdd8 >
[    9.065080] ------------[ cut here ]------------
[    9.065134] WARNING: at block/genhd.c:1474 __disk_unblock_events+0x123/0x130()
[    9.065193] Hardware name: System Product Name
[    9.065234] Modules linked in: aic7xxx(+) scsi_transport_spi sd_mod scsi_mod crc_t10dif
[    9.065411] Pid: 280, comm: blkid Not tainted 3.0.0-amd64 #3.0.23
[    9.065455] Call Trace:
[    9.065496]  [<ffffffff8104c0db>] ? warn_slowpath_common+0x7b/0xc0
[    9.065542]  [<ffffffff811d7fc3>] ? __disk_unblock_events+0x123/0x130
[    9.065588]  [<ffffffff8115fefa>] ? __blkdev_get+0x19a/0x420
[    9.065632]  [<ffffffff811604b0>] ? blkdev_get+0x330/0x330
[    9.065675]  [<ffffffff811601cb>] ? blkdev_get+0x4b/0x330
[    9.065718]  [<ffffffff811604b0>] ? blkdev_get+0x330/0x330
[    9.065763]  [<ffffffff8112bb36>] ? __dentry_open+0x156/0x320
[    9.065807]  [<ffffffff8113733e>] ? path_get+0x1e/0x30
[    9.065850]  [<ffffffff8113a7b0>] ? do_last+0xe0/0x8f0
[    9.065893]  [<ffffffff8113bd43>] ? path_openat+0xd3/0x420
[    9.065937]  [<ffffffff8113c1bd>] ? do_filp_open+0x4d/0xc0
[    9.065981]  [<ffffffff81147dbb>] ? alloc_fd+0x4b/0x130
[    9.066024]  [<ffffffff8112b781>] ? do_sys_open+0x101/0x1e0
[    9.066069]  [<ffffffff81360f60>] ? cstar_dispatch+0x7/0x2e
[    9.066113] ---[ end trace 14d0a5b9a96ea726 ]---
[    9.121871] sd 0:0:0:0: [sda] Attached SCSI disk
[    9.130588] sd 0:0:1:0: [sdb] Attached SCSI disk
[    9.147290] sd 0:0:2:0: [sdc] Attached SCSI disk
[    9.189841] sd 0:0:3:0: [sdd] Attached SCSI disk
[   11.210126] scsi1 : Adaptec AIC7XXX EISA/VLB/PCI SCSI HBA DRIVER, Rev 7.0
[   11.210128]         <Adaptec 3950B Ultra2 SCSI adapter>
[   11.210129]         aic7896/97: Ultra2 Wide Channel B, SCSI Id=7, 32/253 SCBs

The pattern is common, it is always blkid, the same calltrace,
and after this message, there's a long delay in discovery of
next adaptor/address, sometimes with reported errors like the
one from mptbase in my first message.

I'll try applying the patch mentioned, but really, if that's
a known and fixed issue, shouldn't it go to -stable?

Thank you!

/mjt

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: WARNING: at block/genhd.c:1474 __disk_unblock_events+0xe1/0xf0() -- should I be concerned?
  2012-03-13 15:56     ` Michael Tokarev
@ 2012-03-14 18:59       ` Bart Van Assche
  2012-03-14 22:05         ` Michael Tokarev
  0 siblings, 1 reply; 7+ messages in thread
From: Bart Van Assche @ 2012-03-14 18:59 UTC (permalink / raw)
  To: Michael Tokarev; +Cc: SCSI Mailing List

On 03/13/12 15:56, Michael Tokarev wrote:
> WARNING: at block/genhd.c:1474 __disk_unblock_events+0x123/0x130()

Does the patch below help ? This patch prevents that sd_probe_async()
races with scsi_host_remove().

---
 drivers/scsi/sd.c |    2 ++
 1 files changed, 2 insertions(+), 0 deletions(-)

diff --git a/drivers/scsi/sd.c b/drivers/scsi/sd.c
index c691fb5..7161fc1 100644
--- a/drivers/scsi/sd.c
+++ b/drivers/scsi/sd.c
@@ -2544,6 +2544,7 @@ static void sd_probe_async(void *data, async_cookie_t cookie)
 	sd_printk(KERN_NOTICE, sdkp, "Attached SCSI %sdisk\n",
 		  sdp->removable ? "removable " : "");
 	scsi_autopm_put_device(sdp);
+	scsi_host_put(sdp->host);
 	put_device(&sdkp->dev);
 }
 
@@ -2635,6 +2636,7 @@ static int sd_probe(struct device *dev)
 	dev_set_drvdata(dev, sdkp);
 
 	get_device(&sdkp->dev);	/* prevent release before async_schedule */
+	scsi_host_get(sdp->host);
 	async_schedule(sd_probe_async, sdkp);
 
 	return 0;
-- 
1.7.7


^ permalink raw reply related	[flat|nested] 7+ messages in thread

* Re: WARNING: at block/genhd.c:1474 __disk_unblock_events+0xe1/0xf0() -- should I be concerned?
  2012-03-14 18:59       ` Bart Van Assche
@ 2012-03-14 22:05         ` Michael Tokarev
  2012-03-16 22:27           ` Michael Tokarev
  0 siblings, 1 reply; 7+ messages in thread
From: Michael Tokarev @ 2012-03-14 22:05 UTC (permalink / raw)
  To: Bart Van Assche; +Cc: SCSI Mailing List

On 14.03.2012 22:59, Bart Van Assche wrote:
> On 03/13/12 15:56, Michael Tokarev wrote:
>> WARNING: at block/genhd.c:1474 __disk_unblock_events+0x123/0x130()
> 
> Does the patch below help ? This patch prevents that sd_probe_async()
> races with scsi_host_remove().

It does not change anything (new dmesg is shown below).  Note
the problem in my case happens when the system just discovers
scsi devices (all of which are static, so always present, and
there's no single hot-unpluggable device ever, like usb/cdrom
or whatever it can be - it is a server with 4 parallel scsi
disks inside it).  So there should be no _remove()s called.

The proble does not happen _always_, from 10 reboots it does
not happen about once or twice (so 10..20% it works without
a warning), so it is a race indeed.

Thank you!

/mjt

...
[    5.996733] scsi0 : Adaptec AIC7XXX EISA/VLB/PCI SCSI HBA DRIVER, Rev 7.0
[    5.996734]         <Adaptec 3950B Ultra2 SCSI adapter>
[    5.996735]         aic7896/97: Ultra2 Wide Channel A, SCSI Id=7, 32/253 SCBs
[    5.996736]
[    5.998033] aic7xxx 0000:01:07.1: PCI INT A -> Link[APC2] -> GSI 17 (level, low) -> IRQ 17
[    5.999700] scsi 0:0:0:0: Direct-Access     SEAGATE  ST373207LW       0005 PQ: 0 ANSI: 3
[    5.999794] scsi0:A:0:0: Tagged Queuing enabled.  Depth 32
[    5.999910] scsi target0:0:0: Beginning Domain Validation
[    6.005242] scsi target0:0:0: wide asynchronous
[    6.007717] scsi target0:0:0: FAST-40 WIDE SCSI 80.0 MB/s ST (25 ns, offset 63)
[    6.009589] scsi target0:0:0: Domain Validation skipping write tests
[    6.009634] scsi target0:0:0: Ending Domain Validation
[    6.023158] scsi 0:0:1:0: Direct-Access     SEAGATE  ST373207LW       0005 PQ: 0 ANSI: 3
[    6.023220] scsi0:A:1:0: Tagged Queuing enabled.  Depth 32
[    6.023284] scsi target0:0:1: Beginning Domain Validation
...
[    8.933059] sd 0:0:1:0: [sdb] Write Protect is off
[    8.933102] sd 0:0:1:0: [sdb] Mode Sense: ab 00 10 08
[    8.934812] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, supports DPO and FUA
[    8.934889] sd 0:0:3:0: [sdd] Write cache: enabled, read cache: enabled, supports DPO and FUA
[    8.934968] sd 0:0:2:0: [sdc] Write cache: enabled, read cache: enabled, supports DPO and FUA
[    8.935055] sd 0:0:1:0: [sdb] Write cache: enabled, read cache: enabled, supports DPO and FUA
[    8.966949]  sda: sda1 sda2 sda3 sda4 < sda5 sda6 sda7 sda8 >
[    8.968219]  sdc: sdc1 sdc2 sdc3 sdc4 < sdc5 sdc6 sdc7 sdc8 >
[    8.972419]  sdb: sdb1 sdb2 sdb3 sdb4 < sdb5 sdb6 sdb7 sdb8 >
[    8.973123]  sdd: sdd1 sdd2 sdd3 sdd4 < sdd5 sdd6 sdd7 sdd8 >
[    9.056361] ------------[ cut here ]------------
[    9.056412] WARNING: at block/genhd.c:1474 __disk_unblock_events+0x123/0x130()
[    9.056471] Hardware name: System Product Name
[    9.056512] Modules linked in: aic7xxx(+) scsi_transport_spi sd_mod scsi_mod crc_t10dif
[    9.056686] Pid: 238, comm: blkid Not tainted 3.0.0-amd64 #3.0.23
[    9.056730] Call Trace:
[    9.056771]  [<ffffffff8104c0db>] ? warn_slowpath_common+0x7b/0xc0
[    9.056817]  [<ffffffff811d7fc3>] ? __disk_unblock_events+0x123/0x130
[    9.056863]  [<ffffffff8115fefa>] ? __blkdev_get+0x19a/0x420
[    9.056907]  [<ffffffff811604b0>] ? blkdev_get+0x330/0x330
[    9.056950]  [<ffffffff811601cb>] ? blkdev_get+0x4b/0x330
[    9.056993]  [<ffffffff811604b0>] ? blkdev_get+0x330/0x330
[    9.057037]  [<ffffffff8112bb36>] ? __dentry_open+0x156/0x320
[    9.057081]  [<ffffffff8113733e>] ? path_get+0x1e/0x30
[    9.057124]  [<ffffffff8113a7b0>] ? do_last+0xe0/0x8f0
[    9.057166]  [<ffffffff8113bd43>] ? path_openat+0xd3/0x420
[    9.057210]  [<ffffffff8113cf06>] ? user_path_at_empty+0x66/0xb0
[    9.057254]  [<ffffffff8113c1bd>] ? do_filp_open+0x4d/0xc0
[    9.057298]  [<ffffffff81131d8e>] ? vfs_fstatat+0x6e/0x80
[    9.057394]  [<ffffffff81147dbb>] ? alloc_fd+0x4b/0x130
[    9.057497]  [<ffffffff8112b781>] ? do_sys_open+0x101/0x1e0
[    9.057551]  [<ffffffff81360f60>] ? cstar_dispatch+0x7/0x2e
[    9.057594] ---[ end trace cd10fe0d127c59d2 ]---
[    9.087141] sd 0:0:0:0: [sda] Attached SCSI disk
[    9.093295] sd 0:0:2:0: [sdc] Attached SCSI disk
[    9.106434] sd 0:0:1:0: [sdb] Attached SCSI disk
[    9.181850] sd 0:0:3:0: [sdd] Attached SCSI disk
[   11.210145] scsi1 : Adaptec AIC7XXX EISA/VLB/PCI SCSI HBA DRIVER, Rev 7.0
[   11.210147]         <Adaptec 3950B Ultra2 SCSI adapter>
[   11.210148]         aic7896/97: Ultra2 Wide Channel B, SCSI Id=7, 32/253 SCBs


> ---
>  drivers/scsi/sd.c |    2 ++
>  1 files changed, 2 insertions(+), 0 deletions(-)
> 
> diff --git a/drivers/scsi/sd.c b/drivers/scsi/sd.c
> index c691fb5..7161fc1 100644
> --- a/drivers/scsi/sd.c
> +++ b/drivers/scsi/sd.c
> @@ -2544,6 +2544,7 @@ static void sd_probe_async(void *data, async_cookie_t cookie)
>  	sd_printk(KERN_NOTICE, sdkp, "Attached SCSI %sdisk\n",
>  		  sdp->removable ? "removable " : "");
>  	scsi_autopm_put_device(sdp);
> +	scsi_host_put(sdp->host);
>  	put_device(&sdkp->dev);
>  }
>  
> @@ -2635,6 +2636,7 @@ static int sd_probe(struct device *dev)
>  	dev_set_drvdata(dev, sdkp);
>  
>  	get_device(&sdkp->dev);	/* prevent release before async_schedule */
> +	scsi_host_get(sdp->host);
>  	async_schedule(sd_probe_async, sdkp);
>  
>  	return 0;


^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: WARNING: at block/genhd.c:1474 __disk_unblock_events+0xe1/0xf0() -- should I be concerned?
  2012-03-14 22:05         ` Michael Tokarev
@ 2012-03-16 22:27           ` Michael Tokarev
  0 siblings, 0 replies; 7+ messages in thread
From: Michael Tokarev @ 2012-03-16 22:27 UTC (permalink / raw)
  To: Bart Van Assche; +Cc: SCSI Mailing List

On 15.03.2012 02:05, Michael Tokarev wrote:
> On 14.03.2012 22:59, Bart Van Assche wrote:
>> On 03/13/12 15:56, Michael Tokarev wrote:
>>> WARNING: at block/genhd.c:1474 __disk_unblock_events+0x123/0x130()

The original issue is fixed for me by the following:

commit 9f53d2fe815b4011ff930a7b6db98385d45faa68
Author: Stanislaw Gruszka <sgruszka@redhat.com>
Date:   Fri Mar 2 10:43:28 2012 +0100

    block: fix __blkdev_get and add_disk race condition

Thanks,

/mjt

^ permalink raw reply	[flat|nested] 7+ messages in thread

end of thread, other threads:[~2012-03-16 22:27 UTC | newest]

Thread overview: 7+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2012-03-04 14:19 WARNING: at block/genhd.c:1474 __disk_unblock_events+0xe1/0xf0() -- should I be concerned? Michael Tokarev
2012-03-05  6:35 ` Michael Tokarev
2012-03-05 17:56   ` Bart Van Assche
2012-03-13 15:56     ` Michael Tokarev
2012-03-14 18:59       ` Bart Van Assche
2012-03-14 22:05         ` Michael Tokarev
2012-03-16 22:27           ` Michael Tokarev

This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.