2009-03-12 06:47:33

by Suresh Jayaraman

[permalink] [raw]
Subject: Re: [NFS] nfsd hangs 2.6.28 through 2.6.28.7

: 0009a800/0009a800
[ 0.000000] Initializing cgroup subsys cpuset
[ 0.000000] Linux version 2.6.28.7 (root@t) (gcc version 4.3.2 (Debian 4.3.2-1.1) ) #1 SMP Sun Mar 1 20:38:25 PST 2009
[ 0.000000] Command line: root=/dev/md0 ro console=ttyS1,19200,n8 console=tty0
[ 0.000000] KERNEL supported cpus:
[ 0.000000] Intel GenuineIntel
[ 0.000000] AMD AuthenticAMD
[ 0.000000] Centaur CentaurHauls
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: 0000000000000000 - 000000000009a800 (usable)
[ 0.000000] BIOS-e820: 000000000009a800 - 00000000000a0000 (reserved)
[ 0.000000] BIOS-e820: 00000000000cc000 - 00000000000d0000 (reserved)
[ 0.000000] BIOS-e820: 00000000000e4000 - 0000000000100000 (reserved)
[ 0.000000] BIOS-e820: 0000000000100000 - 00000000cff60000 (usable)
[ 0.000000] BIOS-e820: 00000000cff60000 - 00000000cff69000 (ACPI data)
[ 0.000000] BIOS-e820: 00000000cff69000 - 00000000cff80000 (ACPI NVS)
[ 0.000000] BIOS-e820: 00000000cff80000 - 00000000d0000000 (reserved)
[ 0.000000] BIOS-e820: 00000000e0000000 - 00000000f0000000 (reserved)
[ 0.000000] BIOS-e820: 00000000fec00000 - 00000000fec10000 (reserved)
[ 0.000000] BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved)
[ 0.000000] BIOS-e820: 00000000ff000000 - 0000000100000000 (reserved)
[ 0.000000] BIOS-e820: 0000000100000000 - 0000000130000000 (usable)
[ 0.000000] DMI present.
[ 0.000000] Phoenix BIOS detected: BIOS may corrupt low RAM, working it around.
[ 0.000000] last_pfn = 0x130000 max_arch_pfn = 0x3ffffffff
[ 0.000000] last_pfn = 0xcff60 max_arch_pfn = 0x3ffffffff
[ 0.000000] init_memory_mapping: 0000000000000000-00000000cff60000
[ 0.000000] 0000000000 - 00cfe00000 page 2M
[ 0.000000] 00cfe00000 - 00cff60000 page 4k
[ 0.000000] kernel direct mapping tables up to cff60000 @ 10000-16000
[ 0.000000] last_map_addr: cff60000 end: cff60000
[ 0.000000] init_memory_mapping: 0000000100000000-0000000130000000
[ 0.000000] 0100000000 - 0130000000 page 2M
[ 0.000000] kernel direct mapping tables up to 130000000 @ 14000-1a000
[ 0.000000] last_map_addr: 130000000 end: 130000000
[ 0.000000] RAMDISK: 37b5a000 - 37fef8d0
[ 0.000000] ACPI: RSDP 000F6100, 0014 (r0 PTLTD )
[ 0.000000] ACPI: RSDT CFF6265B, 005C (r1 PTLTD RSDT 6040000 LTP 0)
[ 0.000000] ACPI: FACP CFF68E48, 0074 (r1 INTEL TUMWATER 6040000 PTL 3)
[ 0.000000] ACPI: DSDT CFF64A44, 4404 (r1 Intel BLAKFORD 6040000 MSFT 100000E)
[ 0.000000] ACPI: FACS CFF69FC0, 0040
[ 0.000000] ACPI: APIC CFF68EBC, 0090 (r1 PTLTD APIC 6040000 LTP 0)
[ 0.000000] ACPI: MCFG CFF68F4C, 003C (r1 PTLTD MCFG 6040000 LTP 0)
[ 0.000000] ACPI: BOOT CFF68F88, 0028 (r1 PTLTD $SBFTBL$ 6040000 LTP 1)
[ 0.000000] ACPI: SPCR CFF68FB0, 0050 (r1 PTLTD $UCRTBL$ 6040000 PTL 1)
[ 0.000000] ACPI: SSDT CFF63F2A, 025F (r1 PmRef Cpu0Tst 3000 INTL 20050228)
[ 0.000000] ACPI: SSDT CFF63E84, 00A6 (r1 PmRef Cpu7Tst 3000 INTL 20050228)
[ 0.000000] ACPI: SSDT CFF63DDE, 00A6 (r1 PmRef Cpu6Tst 3000 INTL 20050228)
[ 0.000000] ACPI: SSDT CFF63D38, 00A6 (r1 PmRef Cpu5Tst 3000 INTL 20050228)
[ 0.000000] ACPI: SSDT CFF63C92, 00A6 (r1 PmRef Cpu4Tst 3000 INTL 20050228)
[ 0.000000] ACPI: SSDT CFF63BEC, 00A6 (r1 PmRef Cpu3Tst 3000 INTL 20050228)
[ 0.000000] ACPI: SSDT CFF63B46, 00A6 (r1 PmRef Cpu2Tst 3000 INTL 20050228)
[ 0.000000] ACPI: SSDT CFF63AA0, 00A6 (r1 PmRef Cpu1Tst 3000 INTL 20050228)
[ 0.000000] ACPI: SSDT CFF626B7, 13E9 (r1 PmRef CpuPm 3000 INTL 20050228)
[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] (7 early reservations) ==> bootmem [0000000000 - 0130000000]
[ 0.000000] #0 [0000000000 - 0000001000] BIOS data page ==> [0000000000 - 0000001000]
[ 0.000000] #1 [0000006000 - 0000008000] TRAMPOLINE ==> [0000006000 - 0000008000]
[ 0.000000] #2 [0000200000 - 0000647f38] TEXT DATA BSS ==> [0000200000 - 0000647f38]
[ 0.000000] #3 [0037b5a000 - 0037fef8d0] RAMDISK ==> [0037b5a000 - 0037fef8d0]
[ 0.000000] #4 [000009a800 - 0000100000] BIOS reserved ==> [000009a800 - 0000100000]
[ 0.000000] #5 [0000010000 - 0000014000] PGTABLE ==> [0000010000 - 0000014000]
[ 0.000000] #6 [0000014000 - 0000015000] PGTABLE ==> [0000014000 - 0000015000]
[ 0.000000] found SMP MP-table at [ffff8800000f6130] 000f6130
[ 0.000000] Zone PFN ranges:
[ 0.000000] DMA 0x00000010 -> 0x00001000
[ 0.000000] DMA32 0x00001000 -> 0x00100000
[ 0.000000] Normal 0x00100000 -> 0x00130000
[ 0.000000] Movable zone start PFN for each node
[ 0.000000] early_node_map[3] active PFN ranges
[ 0.000000] 0: 0x00000010 -> 0x0000009a
[ 0.000000] 0: 0x00000100 -> 0x000cff60
[ 0.000000] 0: 0x00100000 -> 0x00130000
[ 0.000000] On node 0 totalpages: 1048298
[ 0.000000] DMA zone: 56 pages used for memmap
[ 0.000000] DMA zone: 1206 pages reserved
[ 0.000000] DMA zone: 2716 pages, LIFO batch:0
[ 0.000000] DMA32 zone: 14280 pages used for memmap
[ 0.000000] DMA32 zone: 833432 pages, LIFO batch:31
[ 0.000000] Normal zone: 2688 pages used for memmap
[ 0.000000] Normal zone: 193920 pages, LIFO batch:31
[ 0.000000] Movable zone: 0 pages used for memmap
[ 0.000000] ACPI: PM-Timer IO Port: 0x1008
[ 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[0x01] enabled)
[ 0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x02] enabled)
[ 0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x03] enabled)
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1])
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x02] high edge lint[0x1])
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x03] high edge lint[0x1])
[ 0.000000] ACPI: IOAPIC (id[0x04] address[0xfec00000] gsi_base[0])
[ 0.000000] IOAPIC[0]: apic_id 4, version 0, address 0xfec00000, GSI 0-23
[ 0.000000] ACPI: IOAPIC (id[0x05] address[0xfec80000] gsi_base[24])
[ 0.000000] IOAPIC[1]: apic_id 5, version 0, address 0xfec80000, GSI 24-47
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 high edge)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[ 0.000000] ACPI: IRQ0 used by override.
[ 0.000000] ACPI: IRQ2 used by override.
[ 0.000000] ACPI: IRQ9 used by override.
[ 0.000000] Using ACPI (MADT) for SMP configuration information
[ 0.000000] SMP: Allowing 4 CPUs, 0 hotplug CPUs
[ 0.000000] PM: Registered nosave memory: 000000000009a000 - 000000000009b000
[ 0.000000] PM: Registered nosave memory: 000000000009b000 - 00000000000a0000
[ 0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000cc000
[ 0.000000] PM: Registered nosave memory: 00000000000cc000 - 00000000000d0000
[ 0.000000] PM: Registered nosave memory: 00000000000d0000 - 00000000000e4000
[ 0.000000] PM: Registered nosave memory: 00000000000e4000 - 0000000000100000
[ 0.000000] PM: Registered nosave memory: 00000000cff60000 - 00000000cff69000
[ 0.000000] PM: Registered nosave memory: 00000000cff69000 - 00000000cff80000
[ 0.000000] PM: Registered nosave memory: 00000000cff80000 - 00000000d0000000
[ 0.000000] PM: Registered nosave memory: 00000000d0000000 - 00000000e0000000
[ 0.000000] PM: Registered nosave memory: 00000000e0000000 - 00000000f0000000
[ 0.000000] PM: Registered nosave memory: 00000000f0000000 - 00000000fec00000
[ 0.000000] PM: Registered nosave memory: 00000000fec00000 - 00000000fec10000
[ 0.000000] PM: Registered nosave memory: 00000000fec10000 - 00000000fee00000
[ 0.000000] PM: Registered nosave memory: 00000000fee00000 - 00000000fee01000
[ 0.000000] PM: Registered nosave memory: 00000000fee01000 - 00000000ff000000
[ 0.000000] PM: Registered nosave memory: 00000000ff000000 - 0000000100000000
[ 0.000000] Allocating PCI resources starting at d1000000 (gap: d0000000:10000000)
[ 0.000000] PERCPU: Allocating 49152 bytes of per cpu data
[ 0.000000] NR_CPUS: 32, nr_cpu_ids: 4, nr_node_ids 1
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 1030068
[ 0.000000] Kernel command line: root=/dev/md0 ro console=ttyS1,19200,n8 console=tty0
[ 0.000000] Initializing CPU#0
[ 0.000000] PID hash table entries: 4096 (order: 12, 32768 bytes)
[ 0.000000] TSC: PIT calibration matches PMTIMER. 1 loops
[ 0.000000] Detected 2000.033 MHz processor.
[ 0.004000] Console: colour VGA+ 80x25
[ 0.004000] console [tty0] enabled
[ 0.004000] console [ttyS1] enabled
[ 0.004000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[ 0.004000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[ 0.004000] Checking aperture...
[ 0.004000] No AGP bridge found
[ 0.004000] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[ 0.004000] Placing software IO TLB between 0x20000000 - 0x24000000
[ 0.004000] Memory: 4042592k/4980736k available (2251k kernel code, 787544k absent, 149680k reserved, 1164k data, 296k init)
[ 0.004010] Calibrating delay loop (skipped), value calculated using timer frequency.. 4000.06 BogoMIPS (lpj=8000132)
[ 0.012038] Security Framework initialized
[ 0.016008] SELinux: Disabled at boot.
[ 0.020017] Mount-cache hash table entries: 256
[ 0.024126] Initializing cgroup subsys ns
[ 0.028004] Initializing cgroup subsys cpuacct
[ 0.032020] CPU: L1 I cache: 32K, L1 D cache: 32K
[ 0.040002] CPU: L2 cache: 6144K
[ 0.044004] CPU: Physical Processor ID: 0
[ 0.048002] CPU: Processor Core ID: 0
[ 0.052007] CPU0: Thermal monitoring handled by SMI
[ 0.052009] using mwait in idle threads.
[ 0.056019] ACPI: Core revision 20080926
[ 0.070250] Setting APIC routing to flat
[ 0.072432] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 0.117916] CPU0: Intel(R) Xeon(R) CPU E5405 @ 2.00GHz stepping 06
[ 0.132001] Booting processor 1 APIC 0x1 ip 0x6000
[ 0.004000] Initializing CPU#1
[ 0.004000] Calibrating delay using timer specific routine.. 4000.23 BogoMIPS (lpj=8000470)
[ 0.004000] CPU: L1 I cache: 32K, L1 D cache: 32K
[ 0.004000] CPU: L2 cache: 6144K
[ 0.004000] CPU: Physical Processor ID: 0
[ 0.004000] CPU: Processor Core ID: 1
[ 0.004000] CPU1: Thermal monitoring enabled (TM2)
[ 0.224097] CPU1: Intel(R) Xeon(R) CPU E5405 @ 2.00GHz stepping 06
[ 0.236018] checking TSC synchronization [CPU#0 -> CPU#1]: passed.
[ 0.240129] Booting processor 2 APIC 0x2 ip 0x6000
[ 0.004000] Initializing CPU#2
[ 0.004000] Calibrating delay using timer specific routine.. 4000.24 BogoMIPS (lpj=8000493)
[ 0.004000] CPU: L1 I cache: 32K, L1 D cache: 32K
[ 0.004000] CPU: L2 cache: 6144K
[ 0.004000] CPU: Physical Processor ID: 0
[ 0.004000] CPU: Processor Core ID: 2
[ 0.004000] CPU2: Thermal monitoring enabled (TM2)
[ 0.332060] CPU2: Intel(R) Xeon(R) CPU E5405 @ 2.00GHz stepping 06
[ 0.344019] checking TSC synchronization [CPU#0 -> CPU#2]: passed.
[ 0.348077] Booting processor 3 APIC 0x3 ip 0x6000
[ 0.004000] Initializing CPU#3
[ 0.004000] Calibrating delay using timer specific routine.. 4000.22 BogoMIPS (lpj=8000458)
[ 0.004000] CPU: L1 I cache: 32K, L1 D cache: 32K
[ 0.004000] CPU: L2 cache: 6144K
[ 0.004000] CPU: Physical Processor ID: 0
[ 0.004000] CPU: Processor Core ID: 3
[ 0.004000] CPU3: Thermal monitoring enabled (TM2)
[ 0.444039] CPU3: Intel(R) Xeon(R) CPU E5405 @ 2.00GHz stepping 06
[ 0.456019] checking TSC synchronization [CPU#0 -> CPU#3]: passed.
[ 0.460020] Brought up 4 CPUs
[ 0.464003] Total of 4 processors activated (16000.77 BogoMIPS).
[ 0.468079] net_namespace: 1328 bytes
[ 0.472108] NET: Registered protocol family 16
[ 0.476029] ACPI: bus type pci registered
[ 0.480031] PCI: MCFG configuration 0: base e0000000 segment 0 buses 0 - 10
[ 0.484004] PCI: MCFG area at e0000000 reserved in E820
[ 0.492231] PCI: Using MMCONFIG at e0000000 - e0afffff
[ 0.496003] PCI: Using configuration type 1 for base access
[ 0.500369] ACPI: EC: Look up EC in DSDT
[ 0.505171] ACPI: Interpreter enabled
[ 0.508004] ACPI: (supports S0 S1 S4 S5)
[ 0.517661] ACPI: Using IOAPIC for interrupt routing
[ 0.524386] ACPI: No dock devices found.
[ 0.528014] ACPI: PCI Root Bridge [PCI0] (0000:00)
[ 0.532041] pci 0000:00:00.0: PME# supported from D0 D3hot D3cold
[ 0.536005] pci 0000:00:00.0: PME# disabled
[ 0.540032] pci 0000:00:02.0: PME# supported from D0 D3hot D3cold
[ 0.544004] pci 0000:00:02.0: PME# disabled
[ 0.548030] pci 0000:00:04.0: PME# supported from D0 D3hot D3cold
[ 0.552004] pci 0000:00:04.0: PME# disabled
[ 0.556030] pci 0000:00:06.0: PME# supported from D0 D3hot D3cold
[ 0.560004] pci 0000:00:06.0: PME# disabled
[ 0.564023] pci 0000:00:08.0: reg 10 64bit mmio: [0xda700000-0xda7003ff]
[ 0.564039] pci 0000:00:08.0: PME# supported from D0 D3hot D3cold
[ 0.568004] pci 0000:00:08.0: PME# disabled
[ 0.572200] pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold
[ 0.576005] pci 0000:00:1c.0: PME# disabled
[ 0.580045] pci 0000:00:1d.0: reg 20 io port: [0x1800-0x181f]
[ 0.580086] pci 0000:00:1d.1: reg 20 io port: [0x1820-0x183f]
[ 0.580127] pci 0000:00:1d.2: reg 20 io port: [0x1840-0x185f]
[ 0.580173] pci 0000:00:1d.7: reg 10 32bit mmio: [0xda700400-0xda7007ff]
[ 0.580207] pci 0000:00:1d.7: PME# supported from D0 D3hot D3cold
[ 0.584005] pci 0000:00:1d.7: PME# disabled
[ 0.588095] pci 0000:00:1f.0: Force enabled HPET at 0xfed00000
[ 0.588113] pci 0000:00:1f.1: reg 10 io port: [0x00-0x07]
[ 0.588119] pci 0000:00:1f.1: reg 14 io port: [0x00-0x03]
[ 0.588124] pci 0000:00:1f.1: reg 18 io port: [0x00-0x07]
[ 0.588130] pci 0000:00:1f.1: reg 1c io port: [0x00-0x03]
[ 0.588135] pci 0000:00:1f.1: reg 20 io port: [0x1860-0x186f]
[ 0.588174] pci 0000:00:1f.2: reg 10 io port: [0x18a0-0x18a7]
[ 0.588179] pci 0000:00:1f.2: reg 14 io port: [0x1874-0x1877]
[ 0.588184] pci 0000:00:1f.2: reg 18 io port: [0x1878-0x187f]
[ 0.588189] pci 0000:00:1f.2: reg 1c io port: [0x1870-0x1873]
[ 0.588194] pci 0000:00:1f.2: reg 20 io port: [0x1880-0x189f]
[ 0.588199] pci 0000:00:1f.2: reg 24 32bit mmio: [0xda700800-0xda700bff]
[ 0.588209] pci 0000:00:1f.2: PME# supported from D3hot
[ 0.592005] pci 0000:00:1f.2: PME# disabled
[ 0.596044] pci 0000:00:1f.3: reg 20 io port: [0x1100-0x111f]
[ 0.596094] pci 0000:01:00.0: PME# supported from D0 D3hot D3cold
[ 0.600004] pci 0000:01:00.0: PME# disabled
[ 0.604036] pci 0000:01:00.3: PME# supported from D0 D3hot D3cold
[ 0.608004] pci 0000:01:00.3: PME# disabled
[ 0.612040] pci 0000:00:02.0: bridge io port: [0x2000-0x2fff]
[ 0.612043] pci 0000:00:02.0: bridge 32bit mmio: [0xda000000-0xda1fffff]
[ 0.612078] pci 0000:02:00.0: PME# supported from D0 D3hot D3cold
[ 0.616004] pci 0000:02:00.0: PME# disabled
[ 0.620041] pci 0000:02:02.0: PME# supported from D0 D3hot D3cold
[ 0.624004] pci 0000:02:02.0: PME# disabled
[ 0.628034] pci 0000:01:00.0: bridge io port: [0x2000-0x2fff]
[ 0.628037] pci 0000:01:00.0: bridge 32bit mmio: [0xda000000-0xda0fffff]
[ 0.628072] pci 0000:03:00.0: PXH quirk detected; SHPC device MSI disabled
[ 0.632023] pci 0000:03:00.0: PME# supported from D0 D3hot D3cold
[ 0.636005] pci 0000:03:00.0: PME# disabled
[ 0.640046] pci 0000:03:00.2: PXH quirk detected; SHPC device MSI disabled
[ 0.644023] pci 0000:03:00.2: PME# supported from D0 D3hot D3cold
[ 0.648005] pci 0000:03:00.2: PME# disabled
[ 0.652179] pci 0000:06:00.0: reg 10 32bit mmio: [0xda020000-0xda03ffff]
[ 0.652186] pci 0000:06:00.0: reg 14 32bit mmio: [0xda000000-0xda01ffff]
[ 0.652192] pci 0000:06:00.0: reg 18 io port: [0x2000-0x201f]
[ 0.652212] pci 0000:06:00.0: reg 30 32bit mmio: [0x000000-0x00ffff]
[ 0.652221] pci 0000:06:00.0: PME# supported from D0 D3hot D3cold
[ 0.656005] pci 0000:06:00.0: PME# disabled
[ 0.660046] pci 0000:06:00.1: reg 10 32bit mmio: [0xda060000-0xda07ffff]
[ 0.660052] pci 0000:06:00.1: reg 14 32bit mmio: [0xda040000-0xda05ffff]
[ 0.660058] pci 0000:06:00.1: reg 18 io port: [0x2020-0x203f]
[ 0.660078] pci 0000:06:00.1: reg 30 32bit mmio: [0x000000-0x00ffff]
[ 0.660087] pci 0000:06:00.1: PME# supported from D0 D3hot D3cold
[ 0.664005] pci 0000:06:00.1: PME# disabled
[ 0.668043] pci 0000:02:02.0: bridge io port: [0x2000-0x2fff]
[ 0.668046] pci 0000:02:02.0: bridge 32bit mmio: [0xda000000-0xda0fffff]
[ 0.668155] pci 0000:08:00.0: reg 10 32bit mmio: [0xda220000-0xda23ffff]
[ 0.668165] pci 0000:08:00.0: reg 14 32bit mmio: [0xda200000-0xda21ffff]
[ 0.668174] pci 0000:08:00.0: reg 18 io port: [0x3000-0x301f]
[ 0.668205] pci 0000:08:00.0: reg 30 32bit mmio: [0x000000-0x01ffff]
[ 0.668219] pci 0000:08:00.0: PME# supported from D0 D3hot D3cold
[ 0.672007] pci 0000:08:00.0: PME# disabled
[ 0.676048] pci 0000:00:04.0: bridge io port: [0x3000-0x3fff]
[ 0.676051] pci 0000:00:04.0: bridge 32bit mmio: [0xda200000-0xda2fffff]
[ 0.676083] pci 0000:09:00.0: reg 10 64bit mmio: [0xd8000000-0xd9ffffff]
[ 0.676091] pci 0000:09:00.0: reg 18 64bit mmio: [0xda300000-0xda300fff]
[ 0.676096] pci 0000:09:00.0: reg 20 io port: [0x4000-0x40ff]
[ 0.676104] pci 0000:09:00.0: reg 30 32bit mmio: [0x000000-0x01ffff]
[ 0.676111] pci 0000:09:00.0: supports D1 D2
[ 0.676146] pci 0000:00:06.0: bridge io port: [0x4000-0x4fff]
[ 0.676149] pci 0000:00:06.0: bridge 32bit mmio: [0xda300000-0xda3fffff]
[ 0.676153] pci 0000:00:06.0: bridge 64bit mmio pref: [0xd8000000-0xd9ffffff]
[ 0.676224] pci 0000:0b:01.0: reg 10 32bit mmio: [0xd0000000-0xd7ffffff]
[ 0.676230] pci 0000:0b:01.0: reg 14 io port: [0x5000-0x50ff]
[ 0.676236] pci 0000:0b:01.0: reg 18 32bit mmio: [0xda400000-0xda40ffff]
[ 0.676256] pci 0000:0b:01.0: reg 30 32bit mmio: [0x000000-0x01ffff]
[ 0.676265] pci 0000:0b:01.0: supports D1 D2
[ 0.676306] pci 0000:00:1e.0: transparent bridge
[ 0.680005] pci 0000:00:1e.0: bridge io port: [0x5000-0x5fff]
[ 0.680008] pci 0000:00:1e.0: bridge 32bit mmio: [0xda400000-0xda4fffff]
[ 0.680014] pci 0000:00:1e.0: bridge 64bit mmio pref: [0xd0000000-0xd7ffffff]
[ 0.680030] bus 00 -> node 0
[ 0.680035] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[ 0.680621] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P2._PRT]
[ 0.680693] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P2.BMD0._PRT]
[ 0.680781] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P2.BMD0.BPD0.PXH0._PRT]
[ 0.680924] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P2.BMD0.BPD0.PXH1._PRT]
[ 0.681037] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P2.BMD0.BPD2._PRT]
[ 0.681122] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P2.BMF3._PRT]
[ 0.684071] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P4._PRT]
[ 0.684195] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P6._PRT]
[ 0.684317] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEX0._PRT]
[ 0.684446] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PCIB._PRT]
[ 0.688243] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 10 11 14 15) *9
[ 0.709229] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 *5 6 7 10 11 14 15)
[ 0.728087] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 7 10 *11 14 15)
[ 0.749850] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 7 *10 11 14 15)
[ 0.768025] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 7 10 11 14 15) *0, disabled.
[ 0.796086] ACPI: PCI Interrupt Link [LNKF] (IRQs 4 5 6 7 10 11 14 15) *0, disabled.
[ 0.820087] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 7 10 11 14 15) *0, disabled.
[ 0.845226] ACPI: PCI Interrupt Link [LNKH] (IRQs 4 5 6 7 10 11 14 15) *0, disabled.
[ 0.865187] usbcore: registered new interface driver usbfs
[ 0.868018] usbcore: registered new interface driver hub
[ 0.872018] usbcore: registered new device driver usb
[ 0.876022] PCI: Using ACPI for IRQ routing
[ 0.896145] hpet clockevent registered
[ 0.896148] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[ 0.900006] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[ 0.912004] hpet0: 3 comparators, 64-bit 14.318180 MHz counter
[ 0.944010] pnp: PnP ACPI init
[ 0.962376] ACPI: bus type pnp registered
[ 0.990422] pnp: PnP ACPI: found 13 devices
[ 1.015517] ACPI: ACPI bus type pnp unregistered
[ 1.043237] system 00:01: ioport range 0x4d0-0x4d1 has been reserved
[ 1.081345] system 00:01: ioport range 0x295-0x296 has been reserved
[ 1.119461] system 00:01: ioport range 0xca2-0xca3 has been reserved
[ 1.157577] system 00:01: ioport range 0xca8-0xcaf has been reserved
[ 1.195692] system 00:01: ioport range 0x1000-0x107f has been reserved
[ 1.234846] system 00:01: ioport range 0x1180-0x11bf has been reserved
[ 1.274001] system 00:01: ioport range 0x800-0x80f has been reserved
[ 1.312117] system 00:01: ioport range 0xfe00-0xfe00 has been reserved
[ 1.351272] system 00:01: iomem range 0xe0000000-0xefffffff has been reserved
[ 1.394068] system 00:01: iomem range 0xfee00000-0xfee0ffff could not be reserved
[ 1.438945] system 00:01: iomem range 0xfec80000-0xfec80fff has been reserved
[ 1.481739] system 00:01: iomem range 0xfed1c000-0xfed1ffff has been reserved
[ 1.524535] system 00:01: iomem range 0xfe000000-0xfe01ffff has been reserved
[ 1.567329] system 00:01: iomem range 0xfe600000-0xfe6fffff has been reserved
[ 1.615177] pci 0000:03:00.0: PCI bridge, secondary bus 0000:04
[ 1.650683] pci 0000:03:00.0: IO window: disabled
[ 1.679962] pci 0000:03:00.0: MEM window: disabled
[ 1.709756] pci 0000:03:00.0: PREFETCH window: disabled
[ 1.742153] pci 0000:03:00.2: PCI bridge, secondary bus 0000:05
[ 1.777666] pci 0000:03:00.2: IO window: disabled
[ 1.806943] pci 0000:03:00.2: MEM window: disabled
[ 1.836737] pci 0000:03:00.2: PREFETCH window: disabled
[ 1.869136] pci 0000:02:00.0: PCI bridge, secondary bus 0000:03
[ 1.904648] pci 0000:02:00.0: IO window: disabled
[ 1.934068] pci 0000:02:00.0: MEM window: disabled
[ 1.963823] pci 0000:02:00.0: PREFETCH window: disabled
[ 1.996221] pci 0000:02:02.0: PCI bridge, secondary bus 0000:06
[ 2.031733] pci 0000:02:02.0: IO window: 0x2000-0x2fff
[ 2.063611] pci 0000:02:02.0: MEM window: 0xda000000-0xda0fffff
[ 2.100166] pci 0000:02:02.0: PREFETCH window: 0x000000da500000-0x000000da5fffff
[ 2.145563] pci 0000:01:00.0: PCI bridge, secondary bus 0000:02
[ 2.181076] pci 0000:01:00.0: IO window: 0x2000-0x2fff
[ 2.212951] pci 0000:01:00.0: MEM window: 0xda000000-0xda0fffff
[ 2.249506] pci 0000:01:00.0: PREFETCH window: 0x000000da500000-0x000000da5fffff
[ 2.294904] pci 0000:01:00.3: PCI bridge, secondary bus 0000:07
[ 2.330416] pci 0000:01:00.3: IO window: disabled
[ 2.359694] pci 0000:01:00.3: MEM window: disabled
[ 2.389488] pci 0000:01:00.3: PREFETCH window: disabled
[ 2.421886] pci 0000:00:02.0: PCI bridge, secondary bus 0000:01
[ 2.457399] pci 0000:00:02.0: IO window: 0x2000-0x2fff
[ 2.489275] pci 0000:00:02.0: MEM window: 0xda000000-0xda1fffff
[ 2.525830] pci 0000:00:02.0: PREFETCH window: 0x000000da500000-0x000000da5fffff
[ 2.571228] pci 0000:00:04.0: PCI bridge, secondary bus 0000:08
[ 2.606740] pci 0000:00:04.0: IO window: 0x3000-0x3fff
[ 2.638616] pci 0000:00:04.0: MEM window: 0xda200000-0xda2fffff
[ 2.675171] pci 0000:00:04.0: PREFETCH window: 0x000000da600000-0x000000da6fffff
[ 2.720568] pci 0000:00:06.0: PCI bridge, secondary bus 0000:09
[ 2.756081] pci 0000:00:06.0: IO window: 0x4000-0x4fff
[ 2.787959] pci 0000:00:06.0: MEM window: 0xda300000-0xda3fffff
[ 2.824513] pci 0000:00:06.0: PREFETCH window: 0x000000d8000000-0x000000d9ffffff
[ 2.869910] pci 0000:00:1c.0: PCI bridge, secondary bus 0000:0a
[ 2.905421] pci 0000:00:1c.0: IO window: disabled
[ 2.934700] pci 0000:00:1c.0: MEM window: disabled
[ 2.964494] pci 0000:00:1c.0: PREFETCH window: disabled
[ 2.996893] pci 0000:00:1e.0: PCI bridge, secondary bus 0000:0b
[ 3.032405] pci 0000:00:1e.0: IO window: 0x5000-0x5fff
[ 3.064283] pci 0000:00:1e.0: MEM window: 0xda400000-0xda4fffff
[ 3.100836] pci 0000:00:1e.0: PREFETCH window: 0x000000d0000000-0x000000d7ffffff
[ 3.146243] pci 0000:00:02.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[ 3.186427] pci 0000:00:02.0: setting latency timer to 64
[ 3.186432] pci 0000:01:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[ 3.226622] pci 0000:01:00.0: setting latency timer to 64
[ 3.226628] pci 0000:02:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[ 3.266818] pci 0000:02:00.0: setting latency timer to 64
[ 3.266826] pci 0000:03:00.0: setting latency timer to 64
[ 3.266833] pci 0000:03:00.2: setting latency timer to 64
[ 3.266840] pci 0000:02:02.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
[ 3.307014] pci 0000:02:02.0: setting latency timer to 64
[ 3.307020] pci 0000:01:00.3: setting latency timer to 64
[ 3.307025] pci 0000:00:04.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[ 3.347207] pci 0000:00:04.0: setting latency timer to 64
[ 3.347211] pci 0000:00:06.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[ 3.387403] pci 0000:00:06.0: setting latency timer to 64
[ 3.387410] pci 0000:00:1c.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
[ 3.427600] pci 0000:00:1c.0: setting latency timer to 64
[ 3.427605] pci 0000:00:1e.0: setting latency timer to 64
[ 3.427608] bus: 00 index 0 io port: [0x00-0xffff]
[ 3.456353] bus: 00 index 1 mmio: [0x000000-0xffffffffffffffff]
[ 3.491868] bus: 01 index 0 io port: [0x2000-0x2fff]
[ 3.521664] bus: 01 index 1 mmio: [0xda000000-0xda1fffff]
[ 3.554060] bus: 01 index 2 mmio: [0xda500000-0xda5fffff]
[ 3.586456] bus: 01 index 3 mmio: [0x0-0x0]
[ 3.611569] bus: 02 index 0 io port: [0x2000-0x2fff]
[ 3.641367] bus: 02 index 1 mmio: [0xda000000-0xda0fffff]
[ 3.673762] bus: 02 index 2 mmio: [0xda500000-0xda5fffff]
[ 3.706157] bus: 02 index 3 mmio: [0x0-0x0]
[ 3.731273] bus: 03 index 0 mmio: [0x0-0x0]
[ 3.756389] bus: 03 index 1 mmio: [0x0-0x0]
[ 3.781503] bus: 03 index 2 mmio: [0x0-0x0]
[ 3.806618] bus: 03 index 3 mmio: [0x0-0x0]
[ 3.831735] bus: 04 index 0 mmio: [0x0-0x0]
[ 3.856851] bus: 04 index 1 mmio: [0x0-0x0]
[ 3.881966] bus: 04 index 2 mmio: [0x0-0x0]
[ 3.907081] bus: 04 index 3 mmio: [0x0-0x0]
[ 3.932197] bus: 05 index 0 mmio: [0x0-0x0]
[ 3.957313] bus: 05 index 1 mmio: [0x0-0x0]
[ 3.982429] bus: 05 index 2 mmio: [0x0-0x0]
[ 4.007545] bus: 05 index 3 mmio: [0x0-0x0]
[ 4.032660] bus: 06 index 0 io port: [0x2000-0x2fff]
[ 4.062455] bus: 06 index 1 mmio: [0xda000000-0xda0fffff]
[ 4.094850] bus: 06 index 2 mmio: [0xda500000-0xda5fffff]
[ 4.127245] bus: 06 index 3 mmio: [0x0-0x0]
[ 4.152360] bus: 07 index 0 mmio: [0x0-0x0]
[ 4.177477] bus: 07 index 1 mmio: [0x0-0x0]
[ 4.202592] bus: 07 index 2 mmio: [0x0-0x0]
[ 4.227708] bus: 07 index 3 mmio: [0x0-0x0]
[ 4.252825] bus: 08 index 0 io port: [0x3000-0x3fff]
[ 4.282619] bus: 08 index 1 mmio: [0xda200000-0xda2fffff]
[ 4.315016] bus: 08 index 2 mmio: [0xda600000-0xda6fffff]
[ 4.347411] bus: 08 index 3 mmio: [0x0-0x0]
[ 4.372525] bus: 09 index 0 io port: [0x4000-0x4fff]
[ 4.402320] bus: 09 index 1 mmio: [0xda300000-0xda3fffff]
[ 4.434716] bus: 09 index 2 mmio: [0xd8000000-0xd9ffffff]
[ 4.467112] bus: 09 index 3 mmio: [0x0-0x0]
[ 4.492227] bus: 0a index 0 mmio: [0x0-0x0]
[ 4.517344] bus: 0a index 1 mmio: [0x0-0x0]
[ 4.542458] bus: 0a index 2 mmio: [0x0-0x0]
[ 4.567575] bus: 0a index 3 mmio: [0x0-0x0]
[ 4.592693] bus: 0b index 0 io port: [0x5000-0x5fff]
[ 4.595232] Switched to high resolution mode on CPU 3
[ 4.595253] Switched to high resolution mode on CPU 2
[ 4.595280] Switched to high resolution mode on CPU 1
[ 4.622488] Switched to high resolution mode on CPU 0
[ 4.622502] bus: 0b index 1 mmio: [0xda400000-0xda4fffff]
[ 4.654881] bus: 0b index 2 mmio: [0xd0000000-0xd7ffffff]
[ 4.687278] bus: 0b index 3 io port: [0x00-0xffff]
[ 4.716032] bus: 0b index 4 mmio: [0x000000-0xffffffffffffffff]
[ 4.751581] NET: Registered protocol family 2
[ 4.816061] IP route cache hash table entries: 131072 (order: 8, 1048576 bytes)
[ 4.860210] TCP established hash table entries: 262144 (order: 10, 4194304 bytes)
[ 4.906361] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
[ 4.946951] TCP: Hash tables configured (established 262144 bind 65536)
[ 4.986637] TCP reno registered
[ 5.020090] NET: Registered protocol family 1
[ 5.046333] checking if image is initramfs... it is
[ 5.430340] Freeing initrd memory: 4694k freed
[ 5.457966] Simple Boot Flag at 0x38 set to 0x1
[ 5.486332] audit: initializing netlink socket (disabled)
[ 5.518710] type=2000 audit(1235968924.518:1): initialized
[ 5.552076] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[ 5.590379] VFS: Disk quotas dquot_6.5.1
[ 5.613925] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 5.659039] msgmni has been set to 7906
[ 5.682206] alg: No test for stdrng (krng)
[ 5.706783] io scheduler noop registered
[ 5.730329] io scheduler anticipatory registered
[ 5.758043] io scheduler deadline registered
[ 5.783693] io scheduler cfq registered (default)
[ 5.812017] pci 0000:0b:01.0: Boot video device
[ 5.814272] pcieport-driver 0000:00:02.0: setting latency timer to 64
[ 5.814297] pcieport-driver 0000:00:02.0: found MSI capability
[ 5.849319] pcieport-driver 0000:00:02.0: irq 1279 for MSI/MSI-X
[ 5.849327] pci_express 0000:00:02.0:pcie00: allocate port service
[ 5.849370] pci_express 0000:00:02.0:pcie01: allocate port service
[ 5.849443] pcieport-driver 0000:00:04.0: setting latency timer to 64
[ 5.849468] pcieport-driver 0000:00:04.0: found MSI capability
[ 5.884467] pcieport-driver 0000:00:04.0: irq 1278 for MSI/MSI-X
[ 5.884474] pci_express 0000:00:04.0:pcie00: allocate port service
[ 5.884517] pci_express 0000:00:04.0:pcie01: allocate port service
[ 5.884591] pcieport-driver 0000:00:06.0: setting latency timer to 64
[ 5.884616] pcieport-driver 0000:00:06.0: found MSI capability
[ 5.919618] pcieport-driver 0000:00:06.0: irq 1277 for MSI/MSI-X
[ 5.919625] pci_express 0000:00:06.0:pcie00: allocate port service
[ 5.919666] pci_express 0000:00:06.0:pcie01: allocate port service
[ 5.919738] pcieport-driver 0000:00:1c.0: setting latency timer to 64
[ 5.919767] pcieport-driver 0000:00:1c.0: found MSI capability
[ 5.954778] pcieport-driver 0000:00:1c.0: irq 1276 for MSI/MSI-X
[ 5.954788] pci_express 0000:00:1c.0:pcie00: allocate port service
[ 5.954828] pci_express 0000:00:1c.0:pcie02: allocate port service
[ 5.954867] pci_express 0000:00:1c.0:pcie03: allocate port service
[ 5.954942] pcieport-driver 0000:01:00.0: setting latency timer to 64
[ 5.954969] pci_express 0000:01:00.0:pcie11: allocate port service
[ 5.955053] pcieport-driver 0000:02:00.0: setting latency timer to 64
[ 5.955078] pcieport-driver 0000:02:00.0: found MSI capability
[ 5.990084] pcieport-driver 0000:02:00.0: irq 1275 for MSI/MSI-X
[ 5.990093] pci_express 0000:02:00.0:pcie21: allocate port service
[ 5.990175] pcieport-driver 0000:02:02.0: setting latency timer to 64
[ 5.990200] pcieport-driver 0000:02:02.0: found MSI capability
[ 6.025182] pcieport-driver 0000:02:02.0: irq 1274 for MSI/MSI-X
[ 6.025191] pci_express 0000:02:02.0:pcie21: allocate port service
[ 6.026360] aer 0000:00:02.0:pcie01: AER service couldn't init device: no _OSC support
[ 6.027390] aer 0000:00:04.0:pcie01: AER service couldn't init device: no _OSC support
[ 6.028420] aer 0000:00:06.0:pcie01: AER service couldn't init device: no _OSC support
[ 6.031248] Linux agpgart interface v0.103
[ 6.055843] Serial: 8250/16550 driver4 ports, IRQ sharing enabled
[ 6.092514] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[ 6.128650] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
[ 6.165075] 00:09: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[ 6.198649] 00:0a: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
[ 6.233569] brd: module loaded
[ 6.252051] PNP: PS/2 Controller [PNP0303:KBC0,PNP0f13:MSE0] at 0x60,0x64 irq 1,12
[ 6.299684] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 6.329465] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 6.359874] mice: PS/2 mouse device common for all mice
[ 6.391311] rtc_cmos 00:04: RTC can wake from S4
[ 6.419059] rtc_cmos 00:04: rtc core: registered rtc_cmos as rtc0
[ 6.455633] rtc0: alarms up to one month, y3k, 114 bytes nvram, hpet irqs
[ 6.496363] cpuidle: using governor ladder
[ 6.520924] cpuidle: using governor menu
[ 6.544735] TCP cubic registered
[ 6.564140] NET: Registered protocol family 17
[ 6.590939] registered taskstats version 1
[ 6.615655] rtc_cmos 00:04: setting system clock to 2009-03-02 04:42:14 UTC (1235968934)
[ 6.664225] Freeing unused kernel memory: 296k freed
[ 6.736879] SCSI subsystem initialized
[ 6.769497] libata version 3.00 loaded.
[ 6.771894] ahci 0000:00:1f.2: version 3.0
[ 6.771910] ahci 0000:00:1f.2: PCI INT B -> GSI 19 (level, low) -> IRQ 19
[ 6.812724] ahci 0000:00:1f.2: AHCI 0001.0100 32 slots 6 ports 3 Gbps 0x3f impl SATA mode
[ 6.861778] ahci 0000:00:1f.2: flags: 64bit pm led pmp slum part
[ 6.898337] ahci 0000:00:1f.2: setting latency timer to 64
[ 6.898601] scsi0 : ahci
[ 6.913923] scsi1 : ahci
[ 6.929186] scsi2 : ahci
[ 6.944476] scsi3 : ahci
[ 6.959765] scsi4 : ahci
[ 6.975052] scsi5 : ahci
[ 6.990326] ata1: SATA max UDMA/133 irq_stat 0x00400040, connection status changed irq 19
[ 7.039354] ata2: SATA max UDMA/133 irq_stat 0x00400040, connection status changed irq 19
[ 7.088390] ata3: SATA max UDMA/133 abar m1024@0xda700800 port 0xda700a00 irq 19
[ 7.132744] ata4: SATA max UDMA/133 abar m1024@0xda700800 port 0xda700a80 irq 19
[ 7.177099] ata5: SATA max UDMA/133 abar m1024@0xda700800 port 0xda700b00 irq 19
[ 7.221454] ata6: SATA max UDMA/133 abar m1024@0xda700800 port 0xda700b80 irq 19
[ 8.152010] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 8.211106] ata1.00: ATA-8: WDC WD5000ABPS-01ZZB0, 02.01B01, max UDMA/133
[ 8.251824] ata1.00: 976773168 sectors, multi 0: LBA48 NCQ (depth 0/32)
[ 8.292357] ata1.00: configured for UDMA/133
[ 9.220009] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 9.272169] ata2.00: ATA-8: WDC WD5000ABPS-01ZZB0, 02.01B01, max UDMA/133
[ 9.312863] ata2.00: 976773168 sectors, multi 0: LBA48 NCQ (depth 0/32)
[ 9.353448] ata2.00: configured for UDMA/133
[ 9.712010] ata3: SATA link down (SStatus 0 SControl 300)
[ 10.080010] ata4: SATA link down (SStatus 0 SControl 300)
[ 10.448010] ata5: SATA link down (SStatus 0 SControl 300)
[ 10.816011] ata6: SATA link down (SStatus 0 SControl 300)
[ 10.864092] scsi 0:0:0:0: Direct-Access ATA WDC WD5000ABPS-0 02.0 PQ: 0 ANSI: 5
[ 10.912765] scsi 1:0:0:0: Direct-Access ATA WDC WD5000ABPS-0 02.0 PQ: 0 ANSI: 5
[ 10.965433] Driver 'sd' needs updating - please use bus_type methods
[ 11.003681] sd 0:0:0:0: [sda] 976773168 512-byte hardware sectors: (500 GB/465 GiB)
[ 11.049596] sd 0:0:0:0: [sda] Write Protect is off
[ 11.078336] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 11.078358] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 11.132673] sd 0:0:0:0: [sda] 976773168 512-byte hardware sectors: (500 GB/465 GiB)
[ 11.178600] sd 0:0:0:0: [sda] Write Protect is off
[ 11.207345] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 11.207367] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 11.261635] sda: sda1 sda2 sda3
[ 11.298564] sd 0:0:0:0: [sda] Attached SCSI disk
[ 11.326342] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors: (500 GB/465 GiB)
[ 11.372245] sd 1:0:0:0: [sdb] Write Protect is off
[ 11.400991] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 11.401012] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 11.455312] sd 1:0:0:0: [sdb] 976773168 512-byte hardware sectors: (500 GB/465 GiB)
[ 11.501258] sd 1:0:0:0: [sdb] Write Protect is off
[ 11.530001] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[ 11.530022] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 11.584287] sdb: sdb1 sdb2 sdb3
[ 11.616360] sd 1:0:0:0: [sdb] Attached SCSI disk
[ 11.654659] md: raid1 personality registered for level 1
[ 11.704105] ACPI: SSDT CFF64189, 0518 (r1 PmRef Cpu0Cst 3001 INTL 20050228)
[ 11.749705] Monitor-Mwait will be used to enter C-1 state
[ 11.749708] Monitor-Mwait will be used to enter C-2 state
[ 11.749710] Monitor-Mwait will be used to enter C-3 state
[ 11.749876] ACPI: CPU0 (power states: C1[C1] C2[C2] C3[C3])
[ 11.783750] processor ACPI_CPU:00: registered as cooling_device0
[ 11.819793] ACPI: Processor [CPU0] (supports 8 throttling states)
[ 11.856946] ACPI: SSDT CFF646A1, 0085 (r1 PmRef Cpu1Cst 3000 INTL 20050228)
[ 11.902509] Marking TSC unstable due to TSC halts in idle
[ 11.935107] ACPI: CPU1 (power states: C1[C1] C2[C2] C3[C3])
[ 11.969041] processor ACPI_CPU:01: registered as cooling_device1
[ 12.005071] ACPI: Processor [CPU1] (supports 8 throttling states)
[ 12.042340] ACPI: SSDT CFF64726, 0085 (r1 PmRef Cpu2Cst 3000 INTL 20050228)
[ 12.088280] ACPI: CPU2 (power states: C1[C1] C2[C2] C3[C3])
[ 12.122303] processor ACPI_CPU:02: registered as cooling_device2
[ 12.158312] ACPI: Processor [CPU2] (supports 8 throttling states)
[ 12.195669] ACPI: SSDT CFF647AB, 0085 (r1 PmRef Cpu3Cst 3000 INTL 20050228)
[ 12.241621] ACPI: CPU3 (power states: C1[C1] C2[C2] C3[C3])
[ 12.275632] processor ACPI_CPU:03: registered as cooling_device3
[ 12.311658] ACPI: Processor [CPU3] (supports 8 throttling states)
[ 12.475596] e1000e: Intel(R) PRO/1000 Network Driver - 0.3.3.3-k6
[ 12.512174] e1000e: Copyright (c) 1999-2008 Intel Corporation.
[ 12.547217] e1000e 0000:06:00.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
[ 12.563462] 3ware 9000 Storage Controller device driver for Linux v2.26.02.011.
[ 12.563499] 3w-9xxx 0000:09:00.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
[ 12.563504] 3w-9xxx 0000:09:00.0: setting latency timer to 64
[ 12.576842] uhci_hcd: USB Universal Host Controller Interface driver
[ 12.713216] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
[ 12.713221] e1000e 0000:06:00.0: setting latency timer to 64
[ 12.730999] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 12.731001] Warning! ehci_hcd should always be loaded before uhci_hcd and ohci_hcd, not after
[ 12.791408] Floppy drive(s): fd0 is 1.44M
[ 12.870374] uhci_hcd 0000:00:1d.0: setting latency timer to 64
[ 12.870379] uhci_hcd 0000:00:1d.0: UHCI Host Controller
[ 12.870384] e1000e 0000:06:00.0: irq 1273 for MSI/MSI-X
[ 12.901870] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 1
[ 12.905872] FDC 0 is a post-1991 82077
[ 12.968773] uhci_hcd 0000:00:1d.0: irq 17, io base 0x00001800
[ 13.003352] usb usb1: configuration #1 chosen from 1 choice
[ 13.036831] hub 1-0:1.0: USB hub found
[ 13.059322] hub 1-0:1.0: 2 ports detected
[ 13.083640] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 17 (level, low) -> IRQ 17
[ 13.091474] 0000:06:00.0: eth0: (PCI Express:2.5GB/s:Width x4) 00:30:48:7f:5f:9e
[ 13.091476] 0000:06:00.0: eth0: Intel(R) PRO/1000 Network Connection
[ 13.091552] 0000:06:00.0: eth0: MAC: 4, PHY: 5, PBA No: 2050ff-0ff
[ 13.091576] e1000e 0000:06:00.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19
[ 13.091582] e1000e 0000:06:00.1: setting latency timer to 64
[ 13.287771] e1000e 0000:06:00.1: irq 1272 for MSI/MSI-X
[ 13.288167] ehci_hcd 0000:00:1d.7: setting latency timer to 64
[ 13.288170] ehci_hcd 0000:00:1d.7: EHCI Host Controller
[ 13.319623] ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 2
[ 13.321046] 3w-9xxx: scsi6: AEN: INFO (0x04:0x0053): Battery capacity test is overdue:.
[ 13.415889] ehci_hcd 0000:00:1d.7: debug port 1
[ 13.443096] ehci_hcd 0000:00:1d.7: cache line size of 32 is not supported
[ 13.443101] ehci_hcd 0000:00:1d.7: irq 17, io mem 0xda700400
[ 13.477056] scsi6 : 3ware 9000 Storage Controller
[ 13.505285] ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00
[ 13.505310] 3w-9xxx: scsi6: Found a 3ware 9000 Storage Controller at 0xda300000, IRQ: 18.
[ 13.521807] 0000:06:00.1: eth1: (PCI Express:2.5GB/s:Width x4) 00:30:48:7f:5f:9f
[ 13.521809] 0000:06:00.1: eth1: Intel(R) PRO/1000 Network Connection
[ 13.521885] 0000:06:00.1: eth1: MAC: 4, PHY: 5, PBA No: 2050ff-0ff
[ 13.521915] e1000e 0000:08:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[ 13.521922] e1000e 0000:08:00.0: setting latency timer to 64
[ 13.750122] e1000e 0000:08:00.0: irq 1271 for MSI/MSI-X
[ 13.750137] usb usb2: configuration #1 chosen from 1 choice
[ 13.783598] hub 2-0:1.0: USB hub found
[ 13.806093] hub 2-0:1.0: 6 ports detected
[ 13.830312] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19
[ 13.873129] uhci_hcd 0000:00:1d.1: setting latency timer to 64
[ 13.873132] uhci_hcd 0000:00:1d.1: UHCI Host Controller
[ 13.904528] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3
[ 13.948910] uhci_hcd 0000:00:1d.1: irq 19, io base 0x00001820
[ 13.983423] usb usb3: configuration #1 chosen from 1 choice
[ 14.016873] hub 3-0:1.0: USB hub found
[ 14.019684] 0000:08:00.0: eth2: (PCI Express:2.5GB/s:Width x1) 00:15:17:68:58:0c
[ 14.019686] 0000:08:00.0: eth2: Intel(R) PRO/1000 Network Connection
[ 14.019765] 0000:08:00.0: eth2: MAC: 1, PHY: 4, PBA No: d50861-003
[ 14.158942] 3w-9xxx: scsi6: Firmware FE9X 4.06.00.004, BIOS BE9X 4.05.00.015, Ports: 8.
[ 14.158945] hub 3-0:1.0: 2 ports detected
[ 14.159051] ata_piix 0000:00:1f.1: version 2.12
[ 14.159058] ata_piix 0000:00:1f.1: PCI INT A -> GSI 18 (level, low) -> IRQ 18
[ 14.159096] ata_piix 0000:00:1f.1: setting latency timer to 64
[ 14.159173] scsi7 : ata_piix
[ 14.159223] scsi8 : ata_piix
[ 14.159721] ata7: PATA max UDMA/100 cmd 0x1f0 ctl 0x3f6 bmdma 0x1860 irq 14
[ 14.159724] ata8: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0x1868 irq 15
[ 14.399839] scsi 6:0:0:0: Direct-Access AMCC 9650SE-8LP DISK 4.06 PQ: 0 ANSI: 5
[ 14.448831] ata7.01: ATAPI: MATSHITADVD-ROM SR-8178, PZ16, max UDMA/66
[ 14.488010] ata7.01: limited to UDMA/33 due to 40-wire cable
[ 14.528071] sd 6:0:0:0: [sdc] Very big device. Trying to use READ CAPACITY(16).
[ 14.572024] sd 6:0:0:0: [sdc] 5859311616 512-byte hardware sectors: (2.99 TB/2.72 TiB)
[ 14.572463] ata7.01: configured for UDMA/33
[ 14.573037] usb 2-6: new high speed USB device using ehci_hcd and address 2
[ 14.686447] ata8: port disabled. ignoring.
[ 14.686454] scsi: waiting for bus probes to complete ...
[ 14.686603] sd 6:0:0:0: [sdc] Write Protect is off
[ 14.686606] sd 6:0:0:0: [sdc] Mode Sense: 23 00 00 00
[ 14.686950] sd 6:0:0:0: [sdc] Write cache: enabled, read cache: disabled, doesn't support DPO or FUA
[ 14.801990] sd 6:0:0:0: [sdc] Very big device. Trying to use READ CAPACITY(16).
[ 14.845907] sd 6:0:0:0: [sdc] 5859311616 512-byte hardware sectors: (2.99 TB/2.72 TiB)
[ 14.893588] sd 6:0:0:0: [sdc] Write Protect is off
[ 14.922325] sd 6:0:0:0: [sdc] Mode Sense: 23 00 00 00
[ 14.922666] sd 6:0:0:0: [sdc] Write cache: enabled, read cache: disabled, doesn't support DPO or FUA
[ 14.977456] sdc:<6>usb 2-6: configuration #1 chosen from 1 choice
[ 15.024051] usbcore: registered new interface driver hiddev
[ 15.039793] unknown partition table
[ 15.079000] sd 6:0:0:0: [sdc] Attached SCSI disk
[ 15.107509] input: Peppercon AG Multidevice as /class/input/input0
[ 15.108264] scsi 7:0:1:0: CD-ROM MATSHITA DVD-ROM SR-8178 PZ16 PQ: 0 ANSI: 5
[ 15.117003] Driver 'sr' needs updating - please use bus_type methods
[ 15.231229] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
[ 15.274075] uhci_hcd 0000:00:1d.2: setting latency timer to 64
[ 15.274080] uhci_hcd 0000:00:1d.2: UHCI Host Controller
[ 15.305577] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4
[ 15.349972] uhci_hcd 0000:00:1d.2: irq 18, io base 0x00001840
[ 15.384485] sr0: scsi3-mmc drive: 24x/24x cd/rw xa/form2 cdda tray
[ 15.384557] usb usb4: configuration #1 chosen from 1 choice
[ 15.384582] hub 4-0:1.0: USB hub found
[ 15.384587] hub 4-0:1.0: 2 ports detected
[ 15.501601] Uniform CD-ROM driver Revision: 3.20
[ 15.504052] generic-usb 0003:14DD:0002.0001: input,hidraw0: USB HID v1.01 Mouse [Peppercon AG Multidevice] on usb-0000:00:1d.7-6/input0
[ 15.507963] input: Peppercon AG Multidevice as /class/input/input1
[ 15.639463] sr 7:0:1:0: Attached scsi CD-ROM sr0
[ 15.645137] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 15.677055] sd 1:0:0:0: Attached scsi generic sg1 type 0
[ 15.708990] sd 6:0:0:0: Attached scsi generic sg2 type 0
[ 15.740925] sr 7:0:1:0: Attached scsi generic sg3 type 5
[ 15.745090] generic-usb 0003:14DD:0002.0002: input,hidraw1: USB HID v1.01 Keyboard [Peppercon AG Multidevice] on usb-0000:00:1d.7-6/input1
[ 15.745109] usbcore: registered new interface driver usbhid
[ 15.745111] usbhid: v2.6:USB HID core driver
[ 16.269072] md: md0 stopped.
[ 16.319941] md: bind<sdb1>
[ 16.336353] md: bind<sda1>
[ 16.356362] raid1: raid set md0 active with 2 out of 2 mirrors
[ 16.391683] md: md1 stopped.
[ 16.409089] md0: unknown partition table
[ 16.487526] md: bind<sdb2>
[ 16.503940] md: bind<sda2>
[ 16.523892] raid1: raid set md1 active with 2 out of 2 mirrors
[ 16.559146] md: md2 stopped.
[ 16.576527] md1: unknown partition table
[ 16.624185] md: bind<sdb3>
[ 16.640598] md: bind<sda3>
[ 16.660542] raid1: raid set md2 active with 2 out of 2 mirrors
[ 16.696091] md2: unknown partition table
[ 16.812846] SGI XFS with ACLs, security attributes, realtime, large block/inode numbers, no debug enabled
[ 16.870883] SGI XFS Quota Management subsystem
[ 16.947512] XFS mounting filesystem md0
[ 17.088857] Ending clean XFS mount for filesystem: md0
[ 18.391375] udevd version 125 started
[ 18.737694] input: Power Button (FF) as /class/input/input2
[ 18.800063] ACPI: Power Button (FF) [PWRF]
[ 18.827019] input: Power Button (CM) as /class/input/input3
[ 18.862840] EDAC MC: Ver: 2.1.0 Mar 1 2009
[ 18.905571] ACPI: Power Button (CM) [PWRB]
[ 18.931968] parport_pc 00:0c: reported by Plug and Play ACPI
[ 18.966083] parport0: PC-style at 0x378 (0x778), irq 7, dma 3 [PCSPP,TRISTATE,COMPAT,EPP,ECP,DMA]
[ 19.022915] EDAC MC0: Giving out device to 'i5000_edac.c' 'I5000': DEV 0000:00:10.0
[ 19.068882] EDAC PCI0: Giving out device to module 'i5000_edac' controller 'EDAC PCI controller': DEV '0000:00:10.0' (POLLED)
[ 19.137181] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.05
[ 19.170801] iTCO_wdt: Found a 631xESB/632xESB TCO device (Version=2, TCOBASE=0x1060)
[ 19.217404] iTCO_wdt: initialized. heartbeat=30 sec (nowayout=0)
[ 19.292273] input: PC Speaker as /class/input/input4
[ 19.322535] i801_smbus 0000:00:1f.3: PCI INT B -> GSI 19 (level, low) -> IRQ 19
[ 19.390200] intel_rng: FWH not detected
[ 20.517146] Adding 7911928k swap on /dev/md1. Priority:-1 extents:1 across:7911928k
[ 21.514433] dca service started, version 1.4
[ 21.541594] ioatdma 0000:00:08.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[ 21.583930] ioatdma 0000:00:08.0: setting latency timer to 64
[ 21.583938] ioatdma 0000:00:08.0: Intel(R) I/OAT DMA Engine found, 4 channels, device version 0x12, driver version 3.30
[ 21.648594] ioatdma 0000:00:08.0: irq 1270 for MSI/MSI-X
[ 22.350341] XFS mounting filesystem md2
[ 22.622909] Ending clean XFS mount for filesystem: md2
[ 22.732875] XFS mounting filesystem sdc
[ 22.933466] Ending clean XFS mount for filesystem: sdc
[ 23.760273] e1000e 0000:06:00.0: irq 1273 for MSI/MSI-X
[ 23.816070] e1000e 0000:06:00.0: irq 1273 for MSI/MSI-X
[ 24.433147] NET: Registered protocol family 10
[ 24.460240] lo: Disabled Privacy Extensions
[ 24.485865] ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 26.440892] 0000:06:00.0: eth0: Link is Up 1000 Mbps Full Duplex, Flow Control: None
[ 26.487856] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 26.817296] e1000e 0000:06:00.1: irq 1272 for MSI/MSI-X
[ 26.872060] e1000e 0000:06:00.1: irq 1272 for MSI/MSI-X
[ 26.872950] ADDRCONF(NETDEV_UP): eth1: link is not ready
[ 26.979396] 0000:06:00.1: eth1: changing MTU from 1500 to 9216
[ 27.176239] e1000e 0000:08:00.0: irq 1271 for MSI/MSI-X
[ 27.232083] e1000e 0000:08:00.0: irq 1271 for MSI/MSI-X
[ 27.233397] ADDRCONF(NETDEV_UP): eth2: link is not ready
[ 27.434703] 0000:08:00.0: eth2: changing MTU from 1500 to 9216
[ 27.554395] Bridge firewalling registered
[ 27.678890] device eth1 entered promiscuous mode
[ 27.711076] device eth2 entered promiscuous mode
[ 29.934403] 0000:06:00.1: eth1: Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
[ 29.981970] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
[ 30.016656] br0: topology change detected, propagating
[ 30.047465] br0: port 1(eth1) entering forwarding state
[ 30.048435] 0000:08:00.0: eth2: Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
[ 30.126331] ADDRCONF(NETDEV_CHANGE): eth2: link becomes ready
[ 30.161293] br0: topology change detected, propagating
[ 30.192133] br0: port 2(eth2) entering forwarding state
[ 30.513592] RPC: Registered udp transport module.
[ 30.541821] RPC: Registered tcp transport module.
[ 30.725851] Installing knfsd (copyright (C) 1996 [email protected]).
[ 31.161151] 0000:08:00.0: eth2: Link is Down
[ 31.197525] br0: port 2(eth2) entering disabled state
[ 33.408970] 0000:08:00.0: eth2: Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
[ 33.456561] br0: topology change detected, propagating
[ 33.487367] br0: port 2(eth2) entering forwarding state
[ 35.677744] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
[ 35.724737] NFSD: starting 90-second grace period
[ 36.596016] eth0: no IPv6 routers present
[ 37.864017] br0: no IPv6 routers present
[ 40.568017] eth2: no IPv6 routers present
[ 40.725515] eth1: no IPv6 routers present
[ 43.159304] warning: `ntpd' uses 32-bit capabilities (legacy support in use)
[ 150.190506] 0000:06:00.1: eth1: Link is Down
[ 150.225539] br0: port 1(eth1) entering disabled state
[ 152.032896] 0000:06:00.1: eth1: Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX
[ 152.079334] 0000:06:00.1: eth1: 10/100 speed: disabling TSO
[ 152.113303] br0: topology change detected, propagating
[ 152.144131] br0: port 1(eth1) entering forwarding state
[ 161.100145] 0000:08:00.0: eth2: Link is Down
[ 161.136530] br0: port 2(eth2) entering disabled state
[ 162.668961] 0000:08:00.0: eth2: Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX
[ 162.715401] 0000:08:00.0: eth2: 10/100 speed: disabling TSO
[ 162.749375] br0: topology change detected, propagating
[ 162.780189] br0: port 2(eth2) entering forwarding state
[ 448.408142] 0000:08:00.0: eth2: Link is Down
[ 448.445536] br0: port 2(eth2) entering disabled state
[ 455.536963] 0000:08:00.0: eth2: Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX
[ 455.583431] 0000:08:00.0: eth2: 10/100 speed: disabling TSO
[ 455.617447] br0: topology change detected, propagating
[ 455.648281] br0: port 2(eth2) entering forwarding state
[ 459.548141] 0000:08:00.0: eth2: Link is Down
[ 459.585991] br0: port 2(eth2) entering disabled state
[ 461.520963] 0000:08:00.0: eth2: Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX
[ 461.567404] 0000:08:00.0: eth2: 10/100 speed: disabling TSO
[ 461.601391] br0: topology change detected, propagating
[ 461.632251] br0: port 2(eth2) entering forwarding state
[ 481.732144] 0000:08:00.0: eth2: Link is Down
[ 481.770036] br0: port 2(eth2) entering disabled state
[ 484.208966] 0000:08:00.0: eth2: Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
[ 484.256565] br0: topology change detected, propagating
[ 484.287424] br0: port 2(eth2) entering forwarding state
[ 485.140153] 0000:08:00.0: eth2: Link is Down
[ 485.266015] br0: port 2(eth2) entering disabled state
[ 487.445460] 0000:08:00.0: eth2: Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
[ 487.493067] br0: topology change detected, propagating
[ 487.523922] br0: port 2(eth2) entering forwarding state
[ 535.014501] 0000:06:00.1: eth1: Link is Down
[ 535.049032] br0: port 1(eth1) entering disabled state
[ 552.556903] 0000:06:00.1: eth1: Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX
[ 552.603391] 0000:06:00.1: eth1: 10/100 speed: disabling TSO
[ 552.637388] br0: topology change detected, propagating
[ 552.668242] br0: port 1(eth1) entering forwarding state
[ 556.582498] 0000:06:00.1: eth1: Link is Down
[ 556.620534] br0: port 1(eth1) entering disabled state
[ 558.415535] 0000:06:00.1: eth1: Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX
[ 558.461984] 0000:06:00.1: eth1: 10/100 speed: disabling TSO
[ 558.495932] br0: topology change detected, propagating
[ 558.526781] br0: port 1(eth1) entering forwarding state
[ 571.150503] 0000:06:00.1: eth1: Link is Down
[ 571.185002] br0: port 1(eth1) entering disabled state
[ 573.685862] 0000:06:00.1: eth1: Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
[ 573.733373] br0: topology change detected, propagating
[ 573.764186] br0: port 1(eth1) entering forwarding state
[ 578.800141] 0000:08:00.0: eth2: Link is Down
[ 578.836501] br0: port 2(eth2) entering disabled state
[ 580.418466] 0000:08:00.0: eth2: Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX
[ 580.464959] 0000:08:00.0: eth2: 10/100 speed: disabling TSO
[ 580.499117] br0: topology change detected, propagating
[ 580.529961] br0: port 2(eth2) entering forwarding state
[ 602.768143] 0000:08:00.0: eth2: Link is Down
[ 602.804538] br0: port 2(eth2) entering disabled state
[ 608.132964] 0000:08:00.0: eth2: Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
[ 608.180510] br0: topology change detected, propagating
[ 608.211333] br0: port 2(eth2) entering forwarding state
[ 629.952143] 0000:08:00.0: eth2: Link is Down
[ 629.988538] br0: port 2(eth2) entering disabled state
[ 632.612965] 0000:08:00.0: eth2: Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
[ 632.660526] br0: topology change detected, propagating
[ 632.691361] br0: port 2(eth2) entering forwarding state
[ 636.284644] 0000:08:00.0: eth2: Link is Down
[ 636.329532] br0: port 2(eth2) entering disabled state
[ 638.532961] 0000:08:00.0: eth2: Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
[ 638.580513] br0: topology change detected, propagating
[ 638.611368] br0: port 2(eth2) entering forwarding state
[ 639.624141] 0000:08:00.0: eth2: Link is Down
[ 639.661983] br0: port 2(eth2) entering disabled state
[ 641.872960] 0000:08:00.0: eth2: Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
[ 641.920523] br0: topology change detected, propagating
[ 641.951351] br0: port 2(eth2) entering forwarding state
[ 675.830501] 0000:06:00.1: eth1: Link is Down
[ 675.865040] br0: port 1(eth1) entering disabled state
[ 677.660901] 0000:06:00.1: eth1: Link is Up 100 Mbps Full Duplex, Flow Control: RX/TX
[ 677.707428] 0000:06:00.1: eth1: 10/100 speed: disabling TSO
[ 677.741517] br0: topology change detected, propagating
[ 677.772381] br0: port 1(eth1) entering forwarding state
[ 693.159570] 0000:06:00.1: eth1: Link is Down
[ 693.194025] br0: port 1(eth1) entering disabled state
[ 695.752902] 0000:06:00.1: eth1: Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
[ 695.800470] br0: topology change detected, propagating
[ 695.831315] br0: port 1(eth1) entering forwarding state
[ 722.715466] 0000:06:00.1: eth1: Link is Down
[ 722.753531] br0: port 1(eth1) entering disabled state
[ 725.345937] 0000:06:00.1: eth1: Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
[ 725.393593] br0: topology change detected, propagating
[ 725.424467] br0: port 1(eth1) entering forwarding state
[ 730.294504] 0000:06:00.1: eth1: Link is Down
[ 730.330016] br0: port 1(eth1) entering disabled state
[ 732.753407] 0000:06:00.1: eth1: Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
[ 732.801054] br0: topology change detected, propagating
[ 732.831900] br0: port 1(eth1) entering forwarding state
[12173.668774] nfsd: inode locked twice during operation.
[98572.661534] nfsd: inode locked twice during operation.
[98583.632433] 3w-9xxx: scsi6: AEN: INFO (0x04:0x0029): Verify started:unit=0.
[100870.581899] 3w-9xxx: scsi6: AEN: INFO (0x04:0x002B): Verify completed:unit=0.
[100924.718169] 3w-9xxx: scsi6: AEN: INFO (0x04:0x0029): Verify started:unit=1.
[100924.761018] 3w-9xxx: scsi6: AEN: INFO (0x04:0x0029): Verify started:unit=0.
[112560.733724] 3w-9xxx: scsi6: AEN: INFO (0x04:0x002B): Verify completed:unit=1.
[152853.702517] 3w-9xxx: scsi6: AEN: INFO (0x04:0x0055): Battery charging started:.
[152877.373187] 3w-9xxx: scsi6: AEN: INFO (0x04:0x0056): Battery charging completed:.
[181215.731227] 3w-9xxx: scsi6: AEN: INFO (0x04:0x002B): Verify completed:unit=0.
[181266.965501] 3w-9xxx: scsi6: AEN: INFO (0x04:0x0029): Verify started:unit=1.
[181267.008169] 3w-9xxx: scsi6: AEN: INFO (0x04:0x0029): Verify started:unit=0.
[184712.603178] 3w-9xxx: scsi6: AEN: INFO (0x04:0x003D): Verify paused:unit=1.
[184974.361402] nfsd: inode locked twice during operation.
[271373.460483] nfsd: inode locked twice during operation.
[327580.885533] device br0 entered promiscuous mode
[327616.437030] device br0 left promiscuous mode
[327618.712028] device br0 entered promiscuous mode
[328486.593521] device br0 left promiscuous mode
[357773.524783] nfsd: inode locked twice during operation.
[444178.745207] nfsd: inode locked twice during operation.
[530573.066807] nfsd: inode locked twice during operation.
[613374.214454] nfsd: inode locked twice during operation.
[655851.637116] 3w-9xxx: scsi6: AEN: INFO (0x04:0x001A): Drive inserted:port=4.
[699772.931696] nfsd: inode locked twice during operation.
[703400.827266] 3w-9xxx: scsi6: AEN: INFO (0x04:0x0029): Verify started:unit=0.
[748031.230879] nfsd: last server has exited, flushing export cache
[748031.952208] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
[748272.256202] SysRq : Show Blocked State
[748272.260073] task PC stack pid father
[748272.260073] rpc.nfsd D 0000000000001000 0 26913 26906
[748272.260073] ffff88010efa7798 0000000000000086 ffff88012fb34300 ffff88005d4af380
[748272.260073] ffffffff805e6e40 ffff88005d4af380 ffff88001b077c00 ffff88012f99e050
[748272.260073] ffff88012d670cf0 ffff88012f99e2c0 0000000100000000 ffff88012f99e2c0
[748272.260073] Call Trace:
[748272.260073] [<ffffffff804000ab>] udp_push_pending_frames+0x2bf/0x320
[748272.260073] [<ffffffff8042e309>] schedule_timeout+0x1e/0xc9
[748272.260073] [<ffffffff8042ec71>] __down+0x5e/0x8b
[748272.260073] [<ffffffff802471fb>] down+0x27/0x36
[748272.260073] [<ffffffffa019af10>] _xfs_buf_find+0x163/0x1f6 [xfs]
[748272.260073] [<ffffffffa019affd>] xfs_buf_get_flags+0x5a/0x148 [xfs]
[748272.260073] [<ffffffffa019b0fd>] xfs_buf_read_flags+0x12/0x81 [xfs]
[748272.260073] [<ffffffffa0190ef1>] xfs_trans_read_buf+0x47/0x2af [xfs]
[748272.260073] [<ffffffffa016be99>] xfs_da_do_buf+0x410/0x622 [xfs]
[748272.260073] [<ffffffff80228a4b>] dequeue_entity+0x18/0x11f
[748272.260073] [<ffffffffa016c115>] xfs_da_read_buf+0x24/0x29 [xfs]
[748272.260073] [<ffffffffa016f784>] xfs_dir2_block_lookup_int+0x47/0x1b2 [xfs]
[748272.260073] [<ffffffffa016f784>] xfs_dir2_block_lookup_int+0x47/0x1b2 [xfs]
[748272.260073] [<ffffffffa016fd2b>] xfs_dir2_block_lookup+0x18/0xb1 [xfs]
[748272.260073] [<ffffffffa016ea5d>] xfs_dir_lookup+0xdd/0x14f [xfs]
[748272.260073] [<ffffffffa019539a>] xfs_lookup+0x48/0xa5 [xfs]
[748272.260073] [<ffffffff80388b8b>] wait_for_xmitr+0x40/0x87
[748272.260073] [<ffffffffa019d9ef>] xfs_vn_lookup+0x3c/0x78 [xfs]
[748272.260073] [<ffffffff8029e3d9>] __lookup_hash+0xfa/0x11e
[748272.260073] [<ffffffff8029e523>] lookup_one_len+0x6c/0x7f
[748272.260073] [<ffffffffa034e2d8>] nfsd4_build_dentrylist+0x2f/0x7a [nfsd]
[748272.260073] [<ffffffffa016fb00>] xfs_dir2_block_getdents+0x15d/0x1bc [xfs]
[748272.260073] [<ffffffffa034e2a9>] nfsd4_build_dentrylist+0x0/0x7a [nfsd]
[748272.260073] [<ffffffffa034e2a9>] nfsd4_build_dentrylist+0x0/0x7a [nfsd]
[748272.260073] [<ffffffffa016e834>] xfs_readdir+0x93/0xb8 [xfs]
[748272.260073] [<ffffffffa034e2a9>] nfsd4_build_dentrylist+0x0/0x7a [nfsd]
[748272.260073] [<ffffffffa019b4c9>] xfs_file_readdir+0x31/0x40 [xfs]
[748272.260073] [<ffffffff802a31f7>] vfs_readdir+0x75/0xa7
[748272.260073] [<ffffffffa034e323>] load_recdir+0x0/0x2a [nfsd]
[748272.260073] [<ffffffffa034e197>] nfsd4_list_rec_dir+0xde/0x1b9 [nfsd]
[748272.260073] [<ffffffffa034e286>] nfsd4_recdir_load+0x14/0x37 [nfsd]
[748272.260073] [<ffffffffa034a09d>] nfs4_state_start+0x2b/0xf6 [nfsd]
[748272.260073] [<ffffffffa0332592>] nfsd_svc+0x5a/0xfd [nfsd]
[748272.260073] [<ffffffffa033321b>] write_threads+0x0/0xad [nfsd]
[748272.260073] [<ffffffffa0333280>] write_threads+0x65/0xad [nfsd]
[748272.260073] [<ffffffff802757b3>] get_zeroed_page+0x1a/0x7d
[748272.260073] [<ffffffff802ae3c1>] simple_transaction_get+0x8a/0xa4
[748272.260073] [<ffffffffa033321b>] write_threads+0x0/0xad [nfsd]
[748272.260073] [<ffffffffa0332985>] nfsctl_transaction_write+0x43/0x72 [nfsd]
[748272.260073] [<ffffffff8029777c>] vfs_write+0xad/0x156
[748272.260073] [<ffffffff802978e1>] sys_write+0x45/0x6e
[748272.260073] [<ffffffff8020bd7b>] system_call_fastpath+0x16/0x1b


Attachments:
sysrq-w (61.15 kB)

2009-03-13 20:17:01

by J. Bruce Fields

[permalink] [raw]
Subject: Re: [NFS] nfsd hangs 2.6.28 through 2.6.28.7

On Thu, Mar 12, 2009 at 12:17:20PM +0530, Suresh Jayaraman wrote:
> David Warren wrote:
> >>> We are seeing nfsd getting stuck in d wait at boot time. The fix seems
> >>> to be stopping in single user mode prior to nfs starting, renaming
> >>> /var/lib/nfs/v4recovery/ and making a new one, then letting the system
> >>> continue. When it boots and hangs, all processes that touch
> >>> /var/lib/nfs/v4recovery/ go into D-wait. However, if you rename it and
...
> [748031.952208] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
> [748272.256202] SysRq : Show Blocked State
> [748272.260073] task PC stack pid father
> [748272.260073] rpc.nfsd D 0000000000001000 0 26913 26906
> [748272.260073] ffff88010efa7798 0000000000000086 ffff88012fb34300 ffff88005d4af380
> [748272.260073] ffffffff805e6e40 ffff88005d4af380 ffff88001b077c00 ffff88012f99e050
> [748272.260073] ffff88012d670cf0 ffff88012f99e2c0 0000000100000000 ffff88012f99e2c0
> [748272.260073] Call Trace:
> [748272.260073] [<ffffffff804000ab>] udp_push_pending_frames+0x2bf/0x320
> [748272.260073] [<ffffffff8042e309>] schedule_timeout+0x1e/0xc9
> [748272.260073] [<ffffffff8042ec71>] __down+0x5e/0x8b
> [748272.260073] [<ffffffff802471fb>] down+0x27/0x36
> [748272.260073] [<ffffffffa019af10>] _xfs_buf_find+0x163/0x1f6 [xfs]
> [748272.260073] [<ffffffffa019affd>] xfs_buf_get_flags+0x5a/0x148 [xfs]
> [748272.260073] [<ffffffffa019b0fd>] xfs_buf_read_flags+0x12/0x81 [xfs]
> [748272.260073] [<ffffffffa0190ef1>] xfs_trans_read_buf+0x47/0x2af [xfs]
> [748272.260073] [<ffffffffa016be99>] xfs_da_do_buf+0x410/0x622 [xfs]
> [748272.260073] [<ffffffff80228a4b>] dequeue_entity+0x18/0x11f
> [748272.260073] [<ffffffffa016c115>] xfs_da_read_buf+0x24/0x29 [xfs]
> [748272.260073] [<ffffffffa016f784>] xfs_dir2_block_lookup_int+0x47/0x1b2 [xfs]
> [748272.260073] [<ffffffffa016f784>] xfs_dir2_block_lookup_int+0x47/0x1b2 [xfs]
> [748272.260073] [<ffffffffa016fd2b>] xfs_dir2_block_lookup+0x18/0xb1 [xfs]
> [748272.260073] [<ffffffffa016ea5d>] xfs_dir_lookup+0xdd/0x14f [xfs]
> [748272.260073] [<ffffffffa019539a>] xfs_lookup+0x48/0xa5 [xfs]
> [748272.260073] [<ffffffff80388b8b>] wait_for_xmitr+0x40/0x87
> [748272.260073] [<ffffffffa019d9ef>] xfs_vn_lookup+0x3c/0x78 [xfs]
> [748272.260073] [<ffffffff8029e3d9>] __lookup_hash+0xfa/0x11e
> [748272.260073] [<ffffffff8029e523>] lookup_one_len+0x6c/0x7f
> [748272.260073] [<ffffffffa034e2d8>] nfsd4_build_dentrylist+0x2f/0x7a [nfsd]
> [748272.260073] [<ffffffffa016fb00>] xfs_dir2_block_getdents+0x15d/0x1bc [xfs]
> [748272.260073] [<ffffffffa034e2a9>] nfsd4_build_dentrylist+0x0/0x7a [nfsd]
> [748272.260073] [<ffffffffa034e2a9>] nfsd4_build_dentrylist+0x0/0x7a [nfsd]
> [748272.260073] [<ffffffffa016e834>] xfs_readdir+0x93/0xb8 [xfs]
> [748272.260073] [<ffffffffa034e2a9>] nfsd4_build_dentrylist+0x0/0x7a [nfsd]
> [748272.260073] [<ffffffffa019b4c9>] xfs_file_readdir+0x31/0x40 [xfs]
> [748272.260073] [<ffffffff802a31f7>] vfs_readdir+0x75/0xa7
> [748272.260073] [<ffffffffa034e323>] load_recdir+0x0/0x2a [nfsd]
> [748272.260073] [<ffffffffa034e197>] nfsd4_list_rec_dir+0xde/0x1b9 [nfsd]
> [748272.260073] [<ffffffffa034e286>] nfsd4_recdir_load+0x14/0x37 [nfsd]
> [748272.260073] [<ffffffffa034a09d>] nfs4_state_start+0x2b/0xf6 [nfsd]
> [748272.260073] [<ffffffffa0332592>] nfsd_svc+0x5a/0xfd [nfsd]
> [748272.260073] [<ffffffffa033321b>] write_threads+0x0/0xad [nfsd]
> [748272.260073] [<ffffffffa0333280>] write_threads+0x65/0xad [nfsd]
> [748272.260073] [<ffffffff802757b3>] get_zeroed_page+0x1a/0x7d
> [748272.260073] [<ffffffff802ae3c1>] simple_transaction_get+0x8a/0xa4
> [748272.260073] [<ffffffffa033321b>] write_threads+0x0/0xad [nfsd]
> [748272.260073] [<ffffffffa0332985>] nfsctl_transaction_write+0x43/0x72 [nfsd]
> [748272.260073] [<ffffffff8029777c>] vfs_write+0xad/0x156
> [748272.260073] [<ffffffff802978e1>] sys_write+0x45/0x6e
> [748272.260073] [<ffffffff8020bd7b>] system_call_fastpath+0x16/0x1b

Thanks for the report; does this (only lightly tested!) fix the problem?

--b.

commit 33954a0f2cba831801f047813566791ef254b80b
Author: J. Bruce Fields <[email protected]>
Date: Fri Mar 13 16:02:59 2009 -0400

nfsd4: don't do lookup within readdir in recovery code

The main nfsd code was recently modified to no longer do lookups from
withing the readdir callback, to avoid locking problems on certain
filesystems.

This (rather hacky, and overdue for replacement) NFSv4 recovery code has
the same problem. Fix it to build up a list of names (instead of
dentries) and do the lookups afterwards.

Reported symptoms were a deadlock in the xfs code (called from
nfsd4_recdir_load), with /var/lib/nfs on xfs.

Signed-off-by: J. Bruce Fields <[email protected]>
Reported-by: David Warren <[email protected]>

diff --git a/fs/nfsd/nfs4recover.c b/fs/nfsd/nfs4recover.c
index 74f7b67..b0fdc33 100644
--- a/fs/nfsd/nfs4recover.c
+++ b/fs/nfsd/nfs4recover.c
@@ -182,36 +182,26 @@ out_unlock:

typedef int (recdir_func)(struct dentry *, struct dentry *);

-struct dentry_list {
- struct dentry *dentry;
+struct name_list {
+ char name[HEXDIR_LEN];
struct list_head list;
};

-struct dentry_list_arg {
- struct list_head dentries;
- struct dentry *parent;
-};
-
static int
-nfsd4_build_dentrylist(void *arg, const char *name, int namlen,
+nfsd4_build_namelist(void *arg, const char *name, int namlen,
loff_t offset, u64 ino, unsigned int d_type)
{
- struct dentry_list_arg *dla = arg;
- struct list_head *dentries = &dla->dentries;
- struct dentry *parent = dla->parent;
- struct dentry *dentry;
- struct dentry_list *child;
+ struct list_head *names = arg;
+ struct name_list *entry;

- if (name && isdotent(name, namlen))
+ if (namlen != HEXDIR_LEN -1)
return 0;
- dentry = lookup_one_len(name, parent, namlen);
- if (IS_ERR(dentry))
- return PTR_ERR(dentry);
- child = kmalloc(sizeof(*child), GFP_KERNEL);
- if (child == NULL)
+ entry = kmalloc(sizeof(struct name_list), GFP_KERNEL);
+ if (entry == NULL)
return -ENOMEM;
- child->dentry = dentry;
- list_add(&child->list, dentries);
+ memcpy(entry->name, name, HEXDIR_LEN - 1);
+ entry->name[HEXDIR_LEN] = '\0';
+ list_add(&entry->list, names);
return 0;
}

@@ -220,11 +210,9 @@ nfsd4_list_rec_dir(struct dentry *dir, recdir_func *f)
{
const struct cred *original_cred;
struct file *filp;
- struct dentry_list_arg dla = {
- .parent = dir,
- };
- struct list_head *dentries = &dla.dentries;
- struct dentry_list *child;
+ LIST_HEAD(names);
+ struct name_list *entry;
+ struct dentry *dentry;
int status;

if (!rec_dir_init)
@@ -233,31 +221,34 @@ nfsd4_list_rec_dir(struct dentry *dir, recdir_func *f)
status = nfs4_save_creds(&original_cred);
if (status < 0)
return status;
- INIT_LIST_HEAD(dentries);

filp = dentry_open(dget(dir), mntget(rec_dir.mnt), O_RDONLY,
current_cred());
status = PTR_ERR(filp);
if (IS_ERR(filp))
goto out;
- INIT_LIST_HEAD(dentries);
- status = vfs_readdir(filp, nfsd4_build_dentrylist, &dla);
+ status = vfs_readdir(filp, nfsd4_build_namelist, &names);
fput(filp);
- while (!list_empty(dentries)) {
- child = list_entry(dentries->next, struct dentry_list, list);
- status = f(dir, child->dentry);
+ while (!list_empty(&names)) {
+ entry = list_entry(names.next, struct name_list, list);
+
+ dentry = lookup_one_len(entry->name, dir, HEXDIR_LEN-1);
+ if (IS_ERR(dentry)) {
+ status = PTR_ERR(dentry);
+ goto out;
+ }
+ status = f(dir, dentry);
+ dput(dentry);
if (status)
goto out;
- list_del(&child->list);
- dput(child->dentry);
- kfree(child);
+ list_del(&entry->list);
+ kfree(entry);
}
out:
- while (!list_empty(dentries)) {
- child = list_entry(dentries->next, struct dentry_list, list);
- list_del(&child->list);
- dput(child->dentry);
- kfree(child);
+ while (!list_empty(&names)) {
+ entry = list_entry(names.next, struct name_list, list);
+ list_del(&entry->list);
+ kfree(entry);
}
nfs4_reset_creds(original_cred);
return status;