All of lore.kernel.org
 help / color / mirror / Atom feed
From: Chris J Arges <chris.j.arges@canonical.com>
To: Ben Myers <bpm@sgi.com>
Cc: linux-xfs@oss.sgi.com, tinguely@sgi.com
Subject: Re: Still seeing hangs in xlog_grant_log_space
Date: Fri, 18 May 2012 11:53:57 -0500	[thread overview]
Message-ID: <4FB67EA5.20609@canonical.com> (raw)
In-Reply-To: <4FB565C6.8080303@canonical.com>

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

On 05/17/2012 03:55 PM, Chris J Arges wrote:
> On 05/16/2012 01:42 PM, Ben Myers wrote:
>> Hey Chris,
>>
>> On Thu, May 10, 2012 at 04:11:27PM +0000, Chris J Arges wrote:
>>> <snip>
>>>> Canonical attached them to the bug report that they filed yesterday:
>>>> http://oss.sgi.com/bugzilla/show_bug.cgi?id=922
>>>>
>>>> ...Juerg
>>>>
>>>
>>> Hello,
>>> I am able to reproduce this bug with the instructions posted in this bug. Let me 
>>> know what I can do to help.
>>
>> The bug shows:
>>
>> |This has been tested on the following kernels which all exhibit the same
>> |failures:
>> |- 3.2.0-24 (Ubuntu Precise)
>> |- 3.4.0-rc4
>> |- 3.0.29
>> |- 3.1.10
>> |- 3.2.15
>> |- 3.3.2
>>
>> Can you find an older kernel that isn't broken?
>>
> Tested with Ubuntu Lucid 2.6.32-38-generic #38 (upstream 2.6.32.52), so
> far I am able to run the test for 5 hours, which on the same machine I
> have typically been able to reproduce in 2 hours. Will continue to run
> this, and on another system to verify.
> 

Tested with  Ubuntu Lucid 2.6.32-38-generic #38 (upstream 2.6.32.52).
This also fails, attaching the dmesg output as the backtrace looks
similar. Let me know if you'd like me to try any other versions.

The first test I did with lucid, it ran for 7 hours, and I stopped the
test. This morning I re-ran and it failed within 10 minutes. I guess I
got lucky the second time.

> --chris
> 
>> -Ben
>>
> 


[-- Attachment #2: lucid-2.6.32-xfs-hang.dmesg --]
[-- Type: text/plain, Size: 68856 bytes --]

[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Linux version 2.6.32-38-generic (buildd@allspice) (gcc version 4.4.3 (Ubuntu 4.4.3-4ubuntu5) ) #83-Ubuntu SMP Wed Jan 4 11:12:07 UTC 2012 (Ubuntu 2.6.32-38.83-generic 2.6.32.52+drm33.21)
[    0.000000] Command line: noprompt cdrom-detect/try-usb=true file=/cdrom/preseed/ubuntu.seed boot=casper initrd=/casper/initrd.lz quiet splash -- BOOT_IMAGE=/casper/vmlinuz 
[    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 - 000000000009d800 (usable)
[    0.000000]  BIOS-e820: 000000000009d800 - 00000000000a0000 (reserved)
[    0.000000]  BIOS-e820: 00000000000e0000 - 0000000000100000 (reserved)
[    0.000000]  BIOS-e820: 0000000000100000 - 0000000020000000 (usable)
[    0.000000]  BIOS-e820: 0000000020000000 - 0000000020200000 (reserved)
[    0.000000]  BIOS-e820: 0000000020200000 - 0000000040000000 (usable)
[    0.000000]  BIOS-e820: 0000000040000000 - 0000000040200000 (reserved)
[    0.000000]  BIOS-e820: 0000000040200000 - 00000000da99f000 (usable)
[    0.000000]  BIOS-e820: 00000000da99f000 - 00000000dae9f000 (reserved)
[    0.000000]  BIOS-e820: 00000000dae9f000 - 00000000daf9f000 (ACPI NVS)
[    0.000000]  BIOS-e820: 00000000daf9f000 - 00000000dafff000 (ACPI data)
[    0.000000]  BIOS-e820: 00000000dafff000 - 00000000db000000 (usable)
[    0.000000]  BIOS-e820: 00000000db000000 - 00000000dfa00000 (reserved)
[    0.000000]  BIOS-e820: 00000000f8000000 - 00000000fc000000 (reserved)
[    0.000000]  BIOS-e820: 00000000fec00000 - 00000000fec01000 (reserved)
[    0.000000]  BIOS-e820: 00000000fed08000 - 00000000fed09000 (reserved)
[    0.000000]  BIOS-e820: 00000000fed10000 - 00000000fed1a000 (reserved)
[    0.000000]  BIOS-e820: 00000000fed1c000 - 00000000fed20000 (reserved)
[    0.000000]  BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved)
[    0.000000]  BIOS-e820: 00000000ffd20000 - 0000000100000000 (reserved)
[    0.000000]  BIOS-e820: 0000000100000000 - 000000021e600000 (usable)
[    0.000000]  BIOS-e820: 000000021e600000 - 000000021e800000 (reserved)
[    0.000000] DMI 2.6 present.
[    0.000000] last_pfn = 0x21e600 max_arch_pfn = 0x400000000
[    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-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 0FFC00000 mask FFFC00000 write-protect
[    0.000000]   1 base 000000000 mask F80000000 write-back
[    0.000000]   2 base 080000000 mask FC0000000 write-back
[    0.000000]   3 base 0C0000000 mask FE0000000 write-back
[    0.000000]   4 base 0DC000000 mask FFC000000 uncachable
[    0.000000]   5 base 0DB000000 mask FFF000000 uncachable
[    0.000000]   6 base 100000000 mask F00000000 write-back
[    0.000000]   7 base 200000000 mask FE0000000 write-back
[    0.000000]   8 base 21F000000 mask FFF000000 uncachable
[    0.000000]   9 base 21E800000 mask FFF800000 uncachable
[    0.000000] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[    0.000000] last_pfn = 0xdb000 max_arch_pfn = 0x400000000
[    0.000000] e820 update range: 0000000000001000 - 0000000000006000 (usable) ==> (reserved)
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] modified physical RAM map:
[    0.000000]  modified: 0000000000000000 - 0000000000001000 (usable)
[    0.000000]  modified: 0000000000001000 - 0000000000006000 (reserved)
[    0.000000]  modified: 0000000000006000 - 000000000009d800 (usable)
[    0.000000]  modified: 000000000009d800 - 00000000000a0000 (reserved)
[    0.000000]  modified: 00000000000e0000 - 0000000000100000 (reserved)
[    0.000000]  modified: 0000000000100000 - 0000000020000000 (usable)
[    0.000000]  modified: 0000000020000000 - 0000000020200000 (reserved)
[    0.000000]  modified: 0000000020200000 - 0000000040000000 (usable)
[    0.000000]  modified: 0000000040000000 - 0000000040200000 (reserved)
[    0.000000]  modified: 0000000040200000 - 00000000da99f000 (usable)
[    0.000000]  modified: 00000000da99f000 - 00000000dae9f000 (reserved)
[    0.000000]  modified: 00000000dae9f000 - 00000000daf9f000 (ACPI NVS)
[    0.000000]  modified: 00000000daf9f000 - 00000000dafff000 (ACPI data)
[    0.000000]  modified: 00000000dafff000 - 00000000db000000 (usable)
[    0.000000]  modified: 00000000db000000 - 00000000dfa00000 (reserved)
[    0.000000]  modified: 00000000f8000000 - 00000000fc000000 (reserved)
[    0.000000]  modified: 00000000fec00000 - 00000000fec01000 (reserved)
[    0.000000]  modified: 00000000fed08000 - 00000000fed09000 (reserved)
[    0.000000]  modified: 00000000fed10000 - 00000000fed1a000 (reserved)
[    0.000000]  modified: 00000000fed1c000 - 00000000fed20000 (reserved)
[    0.000000]  modified: 00000000fee00000 - 00000000fee01000 (reserved)
[    0.000000]  modified: 00000000ffd20000 - 0000000100000000 (reserved)
[    0.000000]  modified: 0000000100000000 - 000000021e600000 (usable)
[    0.000000]  modified: 000000021e600000 - 000000021e800000 (reserved)
[    0.000000] initial memory mapped : 0 - 20000000
[    0.000000] init_memory_mapping: 0000000000000000-00000000db000000
[    0.000000] NX (Execute Disable) protection: active
[    0.000000]  0000000000 - 00db000000 page 2M
[    0.000000] kernel direct mapping tables up to db000000 @ 8000-d000
[    0.000000] init_memory_mapping: 0000000100000000-000000021e600000
[    0.000000] NX (Execute Disable) protection: active
[    0.000000]  0100000000 - 021e600000 page 2M
[    0.000000] kernel direct mapping tables up to 21e600000 @ b000-15000
[    0.000000] RAMDISK: 1f690000 - 1fffe884
[    0.000000] ACPI: RSDP 00000000000f00e0 00024 (v02 LENOVO)
[    0.000000] ACPI: XSDT 00000000daffe120 000AC (v01 LENOVO TP-8D    00001250 PTEC 00000002)
[    0.000000] ACPI: FACP 00000000dafe7000 000F4 (v04 LENOVO TP-8D    00001250 PTL  00000002)
[    0.000000] ACPI: DSDT 00000000dafea000 0F6A7 (v01 LENOVO TP-8D    00001250 INTL 20061109)
[    0.000000] ACPI: FACS 00000000daf2d000 00040
[    0.000000] ACPI: SLIC 00000000daffd000 00176 (v01 LENOVO TP-8D    00001250 PTEC 00000001)
[    0.000000] ACPI: SSDT 00000000daffc000 00249 (v01 LENOVO TP-SSDT2 00000200 INTL 20061109)
[    0.000000] ACPI: SSDT 00000000daffb000 00033 (v01 LENOVO TP-SSDT1 00000100 INTL 20061109)
[    0.000000] ACPI: SSDT 00000000daffa000 007D1 (v01 LENOVO SataAhci 00001000 INTL 20061109)
[    0.000000] ACPI: HPET 00000000dafe6000 00038 (v01 LENOVO TP-8D    00001250 PTL  00000002)
[    0.000000] ACPI: APIC 00000000dafe5000 00098 (v01 LENOVO TP-8D    00001250 PTL  00000002)
[    0.000000] ACPI: MCFG 00000000dafe4000 0003C (v01 LENOVO TP-8D    00001250 PTL  00000002)
[    0.000000] ACPI: ECDT 00000000dafe3000 00052 (v01 LENOVO TP-8D    00001250 PTL  00000002)
[    0.000000] ACPI: ASF! 00000000dafe9000 000A5 (v32 LENOVO TP-8D    00001250 PTL  00000002)
[    0.000000] ACPI: TCPA 00000000dafe2000 00032 (v02    PTL   LENOVO 06040000 LNVO 00000001)
[    0.000000] ACPI: SSDT 00000000dafe1000 00A27 (v01  PmRef  Cpu0Ist 00003000 INTL 20061109)
[    0.000000] ACPI: SSDT 00000000dafe0000 00996 (v01  PmRef    CpuPm 00003000 INTL 20061109)
[    0.000000] ACPI: DMAR 00000000dafdf000 000E8 (v01 INTEL      SNB  00000001 INTL 00000001)
[    0.000000] ACPI: UEFI 00000000dafde000 0003E (v01 LENOVO TP-8D    00001250 PTL  00000002)
[    0.000000] ACPI: UEFI 00000000dafdd000 00042 (v01 PTL      COMBUF 00000001 PTL  00000001)
[    0.000000] ACPI: UEFI 00000000dafdc000 00292 (v01 LENOVO TP-8D    00001250 PTL  00000002)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at 0000000000000000-000000021e600000
[    0.000000] Bootmem setup node 0 0000000000000000-000000021e600000
[    0.000000]   NODE_DATA [0000000000010000 - 0000000000014fff]
[    0.000000]   bootmap [0000000000015000 -  0000000000058cbf] pages 44
[    0.000000] (8 early reservations) ==> bootmem [0000000000 - 021e600000]
[    0.000000]   #0 [0000000000 - 0000001000]   BIOS data page ==> [0000000000 - 0000001000]
[    0.000000]   #1 [0000006000 - 0000008000]       TRAMPOLINE ==> [0000006000 - 0000008000]
[    0.000000]   #2 [0001000000 - 0001a35ac4]    TEXT DATA BSS ==> [0001000000 - 0001a35ac4]
[    0.000000]   #3 [001f690000 - 001fffe884]          RAMDISK ==> [001f690000 - 001fffe884]
[    0.000000]   #4 [000009d800 - 0000100000]    BIOS reserved ==> [000009d800 - 0000100000]
[    0.000000]   #5 [0001a36000 - 0001a360ed]              BRK ==> [0001a36000 - 0001a360ed]
[    0.000000]   #6 [0000008000 - 000000b000]          PGTABLE ==> [0000008000 - 000000b000]
[    0.000000]   #7 [000000b000 - 0000010000]          PGTABLE ==> [000000b000 - 0000010000]
[    0.000000]  [ffffea0000000000-ffffea00077fffff] PMD -> [ffff88002c600000-ffff8800337fffff] on node 0
[    0.000000] Zone PFN ranges:
[    0.000000]   DMA      0x00000000 -> 0x00001000
[    0.000000]   DMA32    0x00001000 -> 0x00100000
[    0.000000]   Normal   0x00100000 -> 0x0021e600
[    0.000000] Movable zone start PFN for each node
[    0.000000] early_node_map[7] active PFN ranges
[    0.000000]     0: 0x00000000 -> 0x00000001
[    0.000000]     0: 0x00000006 -> 0x0000009d
[    0.000000]     0: 0x00000100 -> 0x00020000
[    0.000000]     0: 0x00020200 -> 0x00040000
[    0.000000]     0: 0x00040200 -> 0x000da99f
[    0.000000]     0: 0x000dafff -> 0x000db000
[    0.000000]     0: 0x00100000 -> 0x0021e600
[    0.000000] On node 0 totalpages: 2067256
[    0.000000]   DMA zone: 56 pages used for memmap
[    0.000000]   DMA zone: 109 pages reserved
[    0.000000]   DMA zone: 3827 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 14280 pages used for memmap
[    0.000000]   DMA32 zone: 875992 pages, LIFO batch:31
[    0.000000]   Normal zone: 16037 pages used for memmap
[    0.000000]   Normal zone: 1156955 pages, LIFO batch:31
[    0.000000] ACPI: PM-Timer IO Port: 0x408
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x00] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x01] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x02] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x04] lapic_id[0x03] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x05] lapic_id[0x00] disabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x06] lapic_id[0x00] disabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x07] lapic_id[0x00] disabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x08] lapic_id[0x00] disabled)
[    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: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0])
[    0.000000] IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    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] ACPI: HPET id: 0x8086a301 base: 0xfed00000
[    0.000000] SMP: Allowing 8 CPUs, 4 hotplug CPUs
[    0.000000] nr_irqs_gsi: 24
[    0.000000] PM: Registered nosave memory: 0000000000001000 - 0000000000006000
[    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] PM: Registered nosave memory: 0000000020000000 - 0000000020200000
[    0.000000] PM: Registered nosave memory: 0000000040000000 - 0000000040200000
[    0.000000] PM: Registered nosave memory: 00000000da99f000 - 00000000dae9f000
[    0.000000] PM: Registered nosave memory: 00000000dae9f000 - 00000000daf9f000
[    0.000000] PM: Registered nosave memory: 00000000daf9f000 - 00000000dafff000
[    0.000000] PM: Registered nosave memory: 00000000db000000 - 00000000dfa00000
[    0.000000] PM: Registered nosave memory: 00000000dfa00000 - 00000000f8000000
[    0.000000] PM: Registered nosave memory: 00000000f8000000 - 00000000fc000000
[    0.000000] PM: Registered nosave memory: 00000000fc000000 - 00000000fec00000
[    0.000000] PM: Registered nosave memory: 00000000fec00000 - 00000000fec01000
[    0.000000] PM: Registered nosave memory: 00000000fec01000 - 00000000fed08000
[    0.000000] PM: Registered nosave memory: 00000000fed08000 - 00000000fed09000
[    0.000000] PM: Registered nosave memory: 00000000fed09000 - 00000000fed10000
[    0.000000] PM: Registered nosave memory: 00000000fed10000 - 00000000fed1a000
[    0.000000] PM: Registered nosave memory: 00000000fed1a000 - 00000000fed1c000
[    0.000000] PM: Registered nosave memory: 00000000fed1c000 - 00000000fed20000
[    0.000000] PM: Registered nosave memory: 00000000fed20000 - 00000000fee00000
[    0.000000] PM: Registered nosave memory: 00000000fee00000 - 00000000fee01000
[    0.000000] PM: Registered nosave memory: 00000000fee01000 - 00000000ffd20000
[    0.000000] PM: Registered nosave memory: 00000000ffd20000 - 0000000100000000
[    0.000000] Allocating PCI resources starting at dfa00000 (gap: dfa00000:18600000)
[    0.000000] Booting paravirtualized kernel on bare hardware
[    0.000000] NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:8 nr_node_ids:1
[    0.000000] PERCPU: Embedded 30 pages/cpu @ffff88002c200000 s91608 r8192 d23080 u262144
[    0.000000] pcpu-alloc: s91608 r8192 d23080 u262144 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 1 2 3 4 5 6 7 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 2036774
[    0.000000] Policy zone: Normal
[    0.000000] Kernel command line: noprompt cdrom-detect/try-usb=true file=/cdrom/preseed/ubuntu.seed boot=casper initrd=/casper/initrd.lz quiet splash -- BOOT_IMAGE=/casper/vmlinuz 
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] Initializing CPU#0
[    0.000000] xsave/xrstor: enabled xstate_bv 0x7, cntxt size 0x340
[    0.000000] Checking aperture...
[    0.000000] No AGP bridge found
[    0.000000] Calgary: detecting Calgary via BIOS EBDA area
[    0.000000] Calgary: Unable to locate Rio Grande table in EBDA - bailing!
[    0.000000] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[    0.000000] Placing 64MB software IO TLB between ffff880024000000 - ffff880028000000
[    0.000000] software IO TLB at phys 0x24000000 - 0x28000000
[    0.000000] Memory: 8064904k/8886272k available (5436k kernel code, 617248k absent, 204120k reserved, 2982k data, 884k init)
[    0.000000] SLUB: Genslabs=14, HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] NR_IRQS:4352 nr_irqs:472
[    0.000000] Extended CMOS year: 2000
[    0.000000] Console: colour VGA+ 80x25
[    0.000000] console [tty0] enabled
[    0.000000] allocated 83886080 bytes of page_cgroup
[    0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups
[    0.000000] hpet clockevent registered
[    0.000000] Fast TSC calibration using PIT
[    0.010000] Detected 2491.853 MHz processor.
[    0.000003] Calibrating delay loop (skipped), value calculated using timer frequency.. 4983.70 BogoMIPS (lpj=24918530)
[    0.000017] Security Framework initialized
[    0.000028] AppArmor: AppArmor initialized
[    0.000595] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes)
[    0.002213] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.002888] Mount-cache hash table entries: 256
[    0.002970] Initializing cgroup subsys ns
[    0.002973] Initializing cgroup subsys cpuacct
[    0.002975] Initializing cgroup subsys memory
[    0.002979] Initializing cgroup subsys devices
[    0.002981] Initializing cgroup subsys freezer
[    0.002983] Initializing cgroup subsys net_cls
[    0.002997] CPU: Physical Processor ID: 0
[    0.002998] CPU: Processor Core ID: 0
[    0.003002] CPU: L1 I cache: 32K, L1 D cache: 32K
[    0.003003] CPU: L2 cache: 256K
[    0.003004] CPU: L3 cache: 3072K
[    0.003007] CPU 0/0x0 -> Node 0
[    0.003009] mce: CPU supports 7 MCE banks
[    0.003019] CPU0: Thermal monitoring enabled (TM1)
[    0.003021] CPU 0 MCA banks CMCI:0 CMCI:1 CMCI:3 CMCI:5 CMCI:6
[    0.003030] using mwait in idle threads.
[    0.003031] Performance Events: Nehalem/Corei7 events, Intel PMU driver.
[    0.003035] ... version:                3
[    0.003036] ... bit width:              48
[    0.003037] ... generic registers:      4
[    0.003038] ... value mask:             0000ffffffffffff
[    0.003040] ... max period:             000000007fffffff
[    0.003041] ... fixed-purpose events:   3
[    0.003042] ... event mask:             000000070000000f
[    0.005291] ACPI: Core revision 20090903
[    0.023306] ftrace: converting mcount calls to 0f 1f 44 00 00
[    0.023309] ftrace: allocating 22567 entries in 89 pages
[    0.031442] Not enabling x2apic, Intr-remapping init failed.
[    0.031445] Setting APIC routing to flat
[    0.031789] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.131568] CPU0: Intel(R) Core(TM) i5-2520M CPU @ 2.50GHz stepping 07
[    0.247700] Booting processor 1 APIC 0x1 ip 0x6000
[    0.258022] Initializing CPU#1
[    0.407246] CPU: Physical Processor ID: 0
[    0.407248] CPU: Processor Core ID: 0
[    0.407250] CPU: L1 I cache: 32K, L1 D cache: 32K
[    0.407252] CPU: L2 cache: 256K
[    0.407253] CPU: L3 cache: 3072K
[    0.407256] CPU 1/0x1 -> Node 0
[    0.407268] CPU1: Thermal monitoring enabled (TM1)
[    0.407270] CPU 1 MCA banks SHD:0 SHD:1 SHD:3 SHD:5 SHD:6
[    0.407348] CPU1: Intel(R) Core(TM) i5-2520M CPU @ 2.50GHz stepping 07
[    0.407357] checking TSC synchronization [CPU#0 -> CPU#1]: passed.
[    0.427385] Booting processor 2 APIC 0x2 ip 0x6000
[    0.437684] Initializing CPU#2
[    0.586837] CPU: Physical Processor ID: 0
[    0.586838] CPU: Processor Core ID: 1
[    0.586840] CPU: L1 I cache: 32K, L1 D cache: 32K
[    0.586842] CPU: L2 cache: 256K
[    0.586842] CPU: L3 cache: 3072K
[    0.586844] CPU 2/0x2 -> Node 0
[    0.586855] CPU2: Thermal monitoring enabled (TM1)
[    0.586857] CPU 2 MCA banks CMCI:0 CMCI:1 CMCI:3 SHD:5 SHD:6
[    0.586914] CPU2: Intel(R) Core(TM) i5-2520M CPU @ 2.50GHz stepping 07
[    0.586921] checking TSC synchronization [CPU#0 -> CPU#2]: passed.
[    0.606946] Booting processor 3 APIC 0x3 ip 0x6000
[    0.617245] Initializing CPU#3
[    0.766427] CPU: Physical Processor ID: 0
[    0.766428] CPU: Processor Core ID: 1
[    0.766430] CPU: L1 I cache: 32K, L1 D cache: 32K
[    0.766431] CPU: L2 cache: 256K
[    0.766432] CPU: L3 cache: 3072K
[    0.766434] CPU 3/0x3 -> Node 0
[    0.766444] CPU3: Thermal monitoring enabled (TM1)
[    0.766446] CPU 3 MCA banks SHD:0 SHD:1 SHD:3 SHD:5 SHD:6
[    0.766469] CPU3: Intel(R) Core(TM) i5-2520M CPU @ 2.50GHz stepping 07
[    0.766476] checking TSC synchronization [CPU#0 -> CPU#3]: passed.
[    0.786448] Brought up 4 CPUs
[    0.786449] Total of 4 processors activated (19935.08 BogoMIPS).
[    0.788648] CPU0 attaching sched-domain:
[    0.788651]  domain 0: span 0-1 level SIBLING
[    0.788653]   groups: 0 (cpu_power = 589) 1 (cpu_power = 589)
[    0.788657]   domain 1: span 0-3 level MC
[    0.788658]    groups: 0-1 (cpu_power = 1178) 2-3 (cpu_power = 1178)
[    0.788664] CPU1 attaching sched-domain:
[    0.788665]  domain 0: span 0-1 level SIBLING
[    0.788666]   groups: 1 (cpu_power = 589) 0 (cpu_power = 589)
[    0.788670]   domain 1: span 0-3 level MC
[    0.788671]    groups: 0-1 (cpu_power = 1178) 2-3 (cpu_power = 1178)
[    0.788674] CPU2 attaching sched-domain:
[    0.788676]  domain 0: span 2-3 level SIBLING
[    0.788677]   groups: 2 (cpu_power = 589) 3 (cpu_power = 589)
[    0.788680]   domain 1: span 0-3 level MC
[    0.788681]    groups: 2-3 (cpu_power = 1178) 0-1 (cpu_power = 1178)
[    0.788685] CPU3 attaching sched-domain:
[    0.788686]  domain 0: span 2-3 level SIBLING
[    0.788687]   groups: 3 (cpu_power = 589) 2 (cpu_power = 589)
[    0.788690]   domain 1: span 0-3 level MC
[    0.788692]    groups: 2-3 (cpu_power = 1178) 0-1 (cpu_power = 1178)
[    0.788883] devtmpfs: initialized
[    0.789117] regulator: core version 0.5
[    0.789141] Time: 16:40:10  Date: 05/18/12
[    0.789171] NET: Registered protocol family 16
[    0.789240] Trying to unpack rootfs image as initramfs...
[    0.789252] ACPI FADT declares the system doesn't support PCIe ASPM, so disable it
[    0.789254] ACPI: bus type pci registered
[    0.789472] PCI: MCFG configuration 0: base f8000000 segment 0 buses 0 - 63
[    0.789474] PCI: MCFG area at f8000000 reserved in E820
[    0.791029] PCI: Using MMCONFIG at f8000000 - fbffffff
[    0.791030] PCI: Using configuration type 1 for base access
[    0.791609] bio: create slab <bio-0> at 0
[    0.792672] ACPI: EC: EC description table is found, configuring boot EC
[    0.798776] ACPI: BIOS _OSI(Linux) query ignored
[    0.802120] ACPI: Interpreter enabled
[    0.802123] ACPI: (supports S0 S3 S4 S5)
[    0.802144] ACPI: Using IOAPIC for interrupt routing
[    0.808802] ACPI: EC: GPE = 0x11, I/O: command/status = 0x66, data = 0x62
[    0.808966] ACPI: Power Resource [PUBS] (on)
[    0.810841] ACPI: ACPI Dock Station Driver: 3 docks/bays found
[    0.811028] ACPI: PCI Root Bridge [PCI0] (0000:00)
[    0.811095] pci 0000:00:02.0: reg 10 64bit mmio: [0xf0000000-0xf03fffff]
[    0.811100] pci 0000:00:02.0: reg 18 64bit mmio pref: [0xe0000000-0xefffffff]
[    0.811103] pci 0000:00:02.0: reg 20 io port: [0x5000-0x503f]
[    0.811176] pci 0000:00:16.0: reg 10 64bit mmio: [0xf2525000-0xf252500f]
[    0.811222] pci 0000:00:16.0: PME# supported from D0 D3hot D3cold
[    0.811226] pci 0000:00:16.0: PME# disabled
[    0.811277] pci 0000:00:19.0: reg 10 32bit mmio: [0xf2500000-0xf251ffff]
[    0.811283] pci 0000:00:19.0: reg 14 32bit mmio: [0xf252b000-0xf252bfff]
[    0.811289] pci 0000:00:19.0: reg 18 io port: [0x5080-0x509f]
[    0.811327] pci 0000:00:19.0: PME# supported from D0 D3hot D3cold
[    0.811331] pci 0000:00:19.0: PME# disabled
[    0.811378] pci 0000:00:1a.0: reg 10 32bit mmio: [0xf252a000-0xf252a3ff]
[    0.811431] pci 0000:00:1a.0: PME# supported from D0 D3hot D3cold
[    0.811435] pci 0000:00:1a.0: PME# disabled
[    0.811475] pci 0000:00:1b.0: reg 10 64bit mmio: [0xf2520000-0xf2523fff]
[    0.811516] pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold
[    0.811520] pci 0000:00:1b.0: PME# disabled
[    0.811587] pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold
[    0.811591] pci 0000:00:1c.0: PME# disabled
[    0.811659] pci 0000:00:1c.1: PME# supported from D0 D3hot D3cold
[    0.811663] pci 0000:00:1c.1: PME# disabled
[    0.811733] pci 0000:00:1c.3: PME# supported from D0 D3hot D3cold
[    0.811736] pci 0000:00:1c.3: PME# disabled
[    0.811839] pci 0000:00:1c.4: PME# supported from D0 D3hot D3cold
[    0.811842] pci 0000:00:1c.4: PME# disabled
[    0.811904] pci 0000:00:1d.0: reg 10 32bit mmio: [0xf2529000-0xf25293ff]
[    0.811956] pci 0000:00:1d.0: PME# supported from D0 D3hot D3cold
[    0.811960] pci 0000:00:1d.0: PME# disabled
[    0.812094] pci 0000:00:1f.2: reg 10 io port: [0x50a8-0x50af]
[    0.812100] pci 0000:00:1f.2: reg 14 io port: [0x50bc-0x50bf]
[    0.812106] pci 0000:00:1f.2: reg 18 io port: [0x50a0-0x50a7]
[    0.812111] pci 0000:00:1f.2: reg 1c io port: [0x50b8-0x50bb]
[    0.812117] pci 0000:00:1f.2: reg 20 io port: [0x5060-0x507f]
[    0.812123] pci 0000:00:1f.2: reg 24 32bit mmio: [0xf2528000-0xf25287ff]
[    0.812152] pci 0000:00:1f.2: PME# supported from D3hot
[    0.812156] pci 0000:00:1f.2: PME# disabled
[    0.812184] pci 0000:00:1f.3: reg 10 64bit mmio: [0xf2524000-0xf25240ff]
[    0.812197] pci 0000:00:1f.3: reg 20 io port: [0xefa0-0xefbf]
[    0.812359] pci 0000:03:00.0: reg 10 64bit mmio: [0xf2400000-0xf2401fff]
[    0.812486] pci 0000:03:00.0: PME# supported from D0 D3hot D3cold
[    0.812493] pci 0000:03:00.0: PME# disabled
[    0.812562] pci 0000:00:1c.1: bridge 32bit mmio: [0xf2400000-0xf24fffff]
[    0.812605] pci 0000:00:1c.3: bridge io port: [0x4000-0x4fff]
[    0.812609] pci 0000:00:1c.3: bridge 32bit mmio: [0xf1c00000-0xf23fffff]
[    0.812615] pci 0000:00:1c.3: bridge 64bit mmio pref: [0xf0400000-0xf0bfffff]
[    0.812837] pci 0000:0d:00.0: reg 10 32bit mmio: [0xf1400000-0xf14000ff]
[    0.812953] pci 0000:0d:00.0: supports D1 D2
[    0.812954] pci 0000:0d:00.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.812960] pci 0000:0d:00.0: PME# disabled
[    0.813039] pci 0000:00:1c.4: bridge io port: [0x3000-0x3fff]
[    0.813043] pci 0000:00:1c.4: bridge 32bit mmio: [0xf1400000-0xf1bfffff]
[    0.813052] pci 0000:00:1c.4: bridge 64bit mmio pref: [0xf0c00000-0xf13fffff]
[    0.813077] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[    0.813199] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.EXP1._PRT]
[    0.813255] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.EXP2._PRT]
[    0.813309] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.EXP4._PRT]
[    0.813372] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.EXP5._PRT]
[    0.816139] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 9 10 *11)
[    0.816293] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 *7 9 10 11)
[    0.816447] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 7 9 10 *11)
[    0.816596] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 7 9 10 *11)
[    0.816743] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 7 9 *10 11)
[    0.816880] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 9 10 11) *0, disabled.
[    0.817026] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 *7 9 10 11)
[    0.817173] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 6 7 9 *10 11)
[    0.817270] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    0.817276] vgaarb: loaded
[    0.817351] SCSI subsystem initialized
[    0.817434] libata version 3.00 loaded.
[    0.817490] usbcore: registered new interface driver usbfs
[    0.817497] usbcore: registered new interface driver hub
[    0.817517] usbcore: registered new device driver usb
[    0.817633] ACPI: WMI: Mapper loaded
[    0.817634] PCI: Using ACPI for IRQ routing
[    0.818034] NetLabel: Initializing
[    0.818035] NetLabel:  domain hash size = 128
[    0.818036] NetLabel:  protocols = UNLABELED CIPSOv4
[    0.818045] NetLabel:  unlabeled traffic allowed by default
[    0.818079] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0
[    0.818084] hpet0: 8 comparators, 64-bit 14.318180 MHz counter
[    0.820093] Switching to clocksource tsc
[    2.689543] AppArmor: AppArmor Filesystem Enabled
[    2.689555] pnp: PnP ACPI init
[    2.689567] ACPI: bus type pnp registered
[    2.691958] pnp: PnP ACPI: found 11 devices
[    2.691961] ACPI: ACPI bus type pnp unregistered
[    2.691976] system 00:00: iomem range 0x0-0x9ffff could not be reserved
[    2.691978] system 00:00: iomem range 0xc0000-0xc3fff has been reserved
[    2.691980] system 00:00: iomem range 0xc4000-0xc7fff has been reserved
[    2.691982] system 00:00: iomem range 0xc8000-0xcbfff has been reserved
[    2.691984] system 00:00: iomem range 0xcc000-0xcffff has been reserved
[    2.691985] system 00:00: iomem range 0xd0000-0xd3fff has been reserved
[    2.691987] system 00:00: iomem range 0xd4000-0xd7fff has been reserved
[    2.691992] system 00:00: iomem range 0xd8000-0xdbfff has been reserved
[    2.691994] system 00:00: iomem range 0xdc000-0xdffff has been reserved
[    2.691996] system 00:00: iomem range 0xe0000-0xe3fff could not be reserved
[    2.691998] system 00:00: iomem range 0xe4000-0xe7fff could not be reserved
[    2.691999] system 00:00: iomem range 0xe8000-0xebfff could not be reserved
[    2.692001] system 00:00: iomem range 0xec000-0xeffff could not be reserved
[    2.692003] system 00:00: iomem range 0xf0000-0xfffff could not be reserved
[    2.692005] system 00:00: iomem range 0x100000-0xdf9fffff could not be reserved
[    2.692008] system 00:00: iomem range 0xfec00000-0xfed3ffff could not be reserved
[    2.692010] system 00:00: iomem range 0xfed4c000-0xffffffff could not be reserved
[    2.692018] system 00:02: ioport range 0x400-0x47f has been reserved
[    2.692020] system 00:02: ioport range 0x500-0x57f has been reserved
[    2.692022] system 00:02: ioport range 0x800-0x80f has been reserved
[    2.692024] system 00:02: ioport range 0x15e0-0x15ef has been reserved
[    2.692026] system 00:02: ioport range 0x1600-0x167f has been reserved
[    2.692028] system 00:02: iomem range 0xf8000000-0xfbffffff has been reserved
[    2.692030] system 00:02: iomem range 0x0-0xfff could not be reserved
[    2.692052] system 00:02: iomem range 0xfed1c000-0xfed1ffff has been reserved
[    2.692054] system 00:02: iomem range 0xfed10000-0xfed13fff has been reserved
[    2.692056] system 00:02: iomem range 0xfed18000-0xfed18fff has been reserved
[    2.692059] system 00:02: iomem range 0xfed19000-0xfed19fff has been reserved
[    2.692061] system 00:02: iomem range 0xfed45000-0xfed4bfff has been reserved
[    2.696756] pci 0000:00:1c.0: PCI bridge, secondary bus 0000:02
[    2.696758] pci 0000:00:1c.0:   IO window: disabled
[    2.696763] pci 0000:00:1c.0:   MEM window: disabled
[    2.696767] pci 0000:00:1c.0:   PREFETCH window: disabled
[    2.696774] pci 0000:00:1c.1: PCI bridge, secondary bus 0000:03
[    2.696775] pci 0000:00:1c.1:   IO window: disabled
[    2.696780] pci 0000:00:1c.1:   MEM window: 0xf2400000-0xf24fffff
[    2.696784] pci 0000:00:1c.1:   PREFETCH window: disabled
[    2.696790] pci 0000:00:1c.3: PCI bridge, secondary bus 0000:05
[    2.696793] pci 0000:00:1c.3:   IO window: 0x4000-0x4fff
[    2.696798] pci 0000:00:1c.3:   MEM window: 0xf1c00000-0xf23fffff
[    2.696803] pci 0000:00:1c.3:   PREFETCH window: 0x000000f0400000-0x000000f0bfffff
[    2.696809] pci 0000:00:1c.4: PCI bridge, secondary bus 0000:0d
[    2.696813] pci 0000:00:1c.4:   IO window: 0x3000-0x3fff
[    2.696819] pci 0000:00:1c.4:   MEM window: 0xf1400000-0xf1bfffff
[    2.696824] pci 0000:00:1c.4:   PREFETCH window: 0x000000f0c00000-0x000000f13fffff
[    2.696844]   alloc irq_desc for 16 on node -1
[    2.696846]   alloc kstat_irqs on node -1
[    2.696852] pci 0000:00:1c.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[    2.696859] pci 0000:00:1c.0: setting latency timer to 64
[    2.696868]   alloc irq_desc for 17 on node -1
[    2.696870]   alloc kstat_irqs on node -1
[    2.696872] pci 0000:00:1c.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
[    2.696876] pci 0000:00:1c.1: setting latency timer to 64
[    2.696885]   alloc irq_desc for 19 on node -1
[    2.696886]   alloc kstat_irqs on node -1
[    2.696888] pci 0000:00:1c.3: PCI INT D -> GSI 19 (level, low) -> IRQ 19
[    2.696892] pci 0000:00:1c.3: setting latency timer to 64
[    2.696903] pci 0000:00:1c.4: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[    2.696908] pci 0000:00:1c.4: setting latency timer to 64
[    2.696912] pci_bus 0000:00: resource 0 io:  [0x00-0xffff]
[    2.696914] pci_bus 0000:00: resource 1 mem: [0x000000-0xffffffffffffffff]
[    2.696916] pci_bus 0000:03: resource 1 mem: [0xf2400000-0xf24fffff]
[    2.696918] pci_bus 0000:05: resource 0 io:  [0x4000-0x4fff]
[    2.696919] pci_bus 0000:05: resource 1 mem: [0xf1c00000-0xf23fffff]
[    2.696921] pci_bus 0000:05: resource 2 pref mem [0xf0400000-0xf0bfffff]
[    2.696923] pci_bus 0000:0d: resource 0 io:  [0x3000-0x3fff]
[    2.696924] pci_bus 0000:0d: resource 1 mem: [0xf1400000-0xf1bfffff]
[    2.696926] pci_bus 0000:0d: resource 2 pref mem [0xf0c00000-0xf13fffff]
[    2.696951] NET: Registered protocol family 2
[    2.697145] IP route cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    2.698847] TCP established hash table entries: 524288 (order: 11, 8388608 bytes)
[    2.700221] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
[    2.700376] TCP: Hash tables configured (established 524288 bind 65536)
[    2.700378] TCP reno registered
[    2.700452] NET: Registered protocol family 1
[    2.700464] pci 0000:00:02.0: Boot video device
[    2.700752] Scanning for low memory corruption every 60 seconds
[    2.700847] audit: initializing netlink socket (disabled)
[    2.700854] type=2000 audit(1337359212.553:1): initialized
[    2.707785] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    2.708756] VFS: Disk quotas dquot_6.5.2
[    2.708794] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    2.709181] fuse init (API version 7.13)
[    2.709235] msgmni has been set to 15751
[    2.709399] alg: No test for stdrng (krng)
[    2.709436] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[    2.709438] io scheduler noop registered
[    2.709439] io scheduler anticipatory registered
[    2.709440] io scheduler deadline registered
[    2.709481] io scheduler cfq registered (default)
[    2.709587]   alloc irq_desc for 24 on node -1
[    2.709589]   alloc kstat_irqs on node -1
[    2.709599] pcieport 0000:00:1c.0: irq 24 for MSI/MSI-X
[    2.709609] pcieport 0000:00:1c.0: setting latency timer to 64
[    2.709708]   alloc irq_desc for 25 on node -1
[    2.709709]   alloc kstat_irqs on node -1
[    2.709716] pcieport 0000:00:1c.1: irq 25 for MSI/MSI-X
[    2.709724] pcieport 0000:00:1c.1: setting latency timer to 64
[    2.709821]   alloc irq_desc for 26 on node -1
[    2.709822]   alloc kstat_irqs on node -1
[    2.709829] pcieport 0000:00:1c.3: irq 26 for MSI/MSI-X
[    2.709837] pcieport 0000:00:1c.3: setting latency timer to 64
[    2.709968]   alloc irq_desc for 27 on node -1
[    2.709970]   alloc kstat_irqs on node -1
[    2.709979] pcieport 0000:00:1c.4: irq 27 for MSI/MSI-X
[    2.709990] pcieport 0000:00:1c.4: setting latency timer to 64
[    2.710079] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    2.710092] Firmware did not grant requested _OSC control
[    2.710111] Firmware did not grant requested _OSC control
[    2.710134] Firmware did not grant requested _OSC control
[    2.710149] Firmware did not grant requested _OSC control
[    2.710163] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    2.710355] ACPI: AC Adapter [AC] (on-line)
[    2.710408] input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0D:00/input/input0
[    2.710569] ACPI: Lid Switch [LID]
[    2.710596] input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1
[    2.710600] ACPI: Sleep Button [SLPB]
[    2.710634] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
[    2.710636] ACPI: Power Button [PWRF]
[    2.711402] ACPI: SSDT 00000000dae8c018 008C0 (v01  PmRef  Cpu0Cst 00003001 INTL 20061109)
[    2.712328] processor LNXCPU:00: registered as cooling_device0
[    2.712749] ACPI: SSDT 00000000dae8da98 00303 (v01  PmRef    ApIst 00003000 INTL 20061109)
[    2.713173] ACPI: SSDT 00000000dae8bd98 00119 (v01  PmRef    ApCst 00003000 INTL 20061109)
[    2.714024] processor LNXCPU:01: registered as cooling_device1
[    2.974193] processor LNXCPU:02: registered as cooling_device2
[    2.975242] processor LNXCPU:03: registered as cooling_device3
[    2.977488] Freeing initrd memory: 9658k freed
[    2.979467] thermal LNXTHERM:01: registered as thermal_zone0
[    2.979474] ACPI: Thermal Zone [THM0] (64 C)
[    2.981085] Linux agpgart interface v0.103
[    2.981107] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    2.982442] brd: module loaded
[    2.982881] loop: module loaded
[    2.982960] input: Macintosh mouse button emulation as /devices/virtual/input/input3
[    2.983444] Fixed MDIO Bus: probed
[    2.983512] PPP generic driver version 2.4.2
[    2.983533] tun: Universal TUN/TAP device driver, 1.6
[    2.983534] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[    2.983631] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    2.984871] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
[    2.986671] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
[    2.986679] ehci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[    2.986714] ehci_hcd 0000:00:1a.0: setting latency timer to 64
[    2.986736] ehci_hcd 0000:00:1a.0: EHCI Host Controller
[    2.986792] ehci_hcd 0000:00:1a.0: new USB bus registered, assigned bus number 1
[    2.986821] ehci_hcd 0000:00:1a.0: debug port 2
[    2.990694] ehci_hcd 0000:00:1a.0: cache line size of 32 is not supported
[    2.990707] ehci_hcd 0000:00:1a.0: irq 16, io mem 0xf252a000
[    2.994328] ACPI: Battery Slot [BAT0] (battery present)
[    3.007668] ehci_hcd 0000:00:1a.0: USB 2.0 started, EHCI 1.00
[    3.007819] usb usb1: configuration #1 chosen from 1 choice
[    3.007838] hub 1-0:1.0: USB hub found
[    3.007844] hub 1-0:1.0: 3 ports detected
[    3.008079] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[    3.008241] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
[    3.008247]   alloc irq_desc for 23 on node -1
[    3.008249]   alloc kstat_irqs on node -1
[    3.008253] ehci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23
[    3.008263] ehci_hcd 0000:00:1d.0: setting latency timer to 64
[    3.008266] ehci_hcd 0000:00:1d.0: EHCI Host Controller
[    3.008291] ehci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2
[    3.008314] ehci_hcd 0000:00:1d.0: debug port 2
[    3.012191] ehci_hcd 0000:00:1d.0: cache line size of 32 is not supported
[    3.012201] ehci_hcd 0000:00:1d.0: irq 23, io mem 0xf2529000
[    3.027616] ehci_hcd 0000:00:1d.0: USB 2.0 started, EHCI 1.00
[    3.027761] usb usb2: configuration #1 chosen from 1 choice
[    3.027777] hub 2-0:1.0: USB hub found
[    3.027781] hub 2-0:1.0: 3 ports detected
[    3.027815] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    3.027823] uhci_hcd: USB Universal Host Controller Interface driver
[    3.027865] PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[    3.031439] serio: i8042 KBD port at 0x60,0x64 irq 1
[    3.031444] serio: i8042 AUX port at 0x60,0x64 irq 12
[    3.031512] mice: PS/2 mouse device common for all mice
[    3.031586] rtc_cmos 00:07: RTC can wake from S4
[    3.031612] rtc_cmos 00:07: rtc core: registered rtc_cmos as rtc0
[    3.031639] rtc0: alarms up to one month, y3k, 114 bytes nvram, hpet irqs
[    3.031715] device-mapper: uevent: version 1.0.3
[    3.031779] device-mapper: ioctl: 4.15.0-ioctl (2009-04-01) initialised: dm-devel@redhat.com
[    3.031845] device-mapper: multipath: version 1.1.0 loaded
[    3.031847] device-mapper: multipath round-robin: version 1.0.0 loaded
[    3.032059] cpuidle: using governor ladder
[    3.032169] cpuidle: using governor menu
[    3.032378] TCP cubic registered
[    3.032476] NET: Registered protocol family 10
[    3.032920] NET: Registered protocol family 17
[    3.035676] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input4
[    3.040284] PM: Resume from disk failed.
[    3.040292] registered taskstats version 1
[    3.040711]   Magic number: 12:176:692
[    3.040792] rtc_cmos 00:07: setting system clock to 2012-05-18 16:40:13 UTC (1337359213)
[    3.040794] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
[    3.040795] EDD information not available.
[    3.040858] Freeing unused kernel memory: 884k freed
[    3.040957] Write protecting the kernel read-only data: 7716k
[    3.052794] udev: starting version 151
[    3.064733] vga16fb: initializing
[    3.064736] vga16fb: mapped to 0xffff8800000a0000
[    3.064779] fb0: VGA16 VGA frame buffer device
[    3.069101] ahci 0000:00:1f.2: version 3.0
[    3.069118] ahci 0000:00:1f.2: PCI INT B -> GSI 19 (level, low) -> IRQ 19
[    3.069163]   alloc irq_desc for 28 on node -1
[    3.069165]   alloc kstat_irqs on node -1
[    3.069175] ahci 0000:00:1f.2: irq 28 for MSI/MSI-X
[    3.069203] ahci: SSS flag set, parallel bus scan disabled
[    3.087539] ahci 0000:00:1f.2: AHCI 0001.0300 32 slots 6 ports 6 Gbps 0x13 impl SATA mode
[    3.087543] ahci 0000:00:1f.2: flags: 64bit ncq sntf ilck stag pm led clo pio slum part ems sxs apst 
[    3.087549] ahci 0000:00:1f.2: setting latency timer to 64
[    3.128065] scsi0 : ahci
[    3.128162] scsi1 : ahci
[    3.128215] scsi2 : ahci
[    3.128267] scsi3 : ahci
[    3.128317] scsi4 : ahci
[    3.128363] scsi5 : ahci
[    3.129401] ata1: SATA max UDMA/133 abar m2048@0xf2528000 port 0xf2528100 irq 28
[    3.129404] ata2: SATA max UDMA/133 abar m2048@0xf2528000 port 0xf2528180 irq 28
[    3.129405] ata3: DUMMY
[    3.129406] ata4: DUMMY
[    3.129408] ata5: SATA max UDMA/133 abar m2048@0xf2528000 port 0xf2528300 irq 28
[    3.129409] ata6: DUMMY
[    3.161700] Console: switching to colour frame buffer device 80x30
[    3.327618] usb 1-1: new high speed USB device using ehci_hcd and address 2
[    3.477234] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[    3.477242] usb 1-1: configuration #1 chosen from 1 choice
[    3.477334] hub 1-1:1.0: USB hub found
[    3.477468] hub 1-1:1.0: 6 ports detected
[    3.478134] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
[    3.478137] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
[    3.478139] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
[    3.479017] ata1.00: ATA-8: HITACHI HTS723232A7A364, EC2ZB70R, max UDMA/100
[    3.479026] ata1.00: 625142448 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
[    3.480131] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
[    3.480139] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
[    3.480146] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
[    3.480995] ata1.00: configured for UDMA/100
[    3.506732] scsi 0:0:0:0: Direct-Access     ATA      HITACHI HTS72323 EC2Z PQ: 0 ANSI: 5
[    3.506855] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    3.506877] sd 0:0:0:0: [sda] 625142448 512-byte logical blocks: (320 GB/298 GiB)
[    3.506916] sd 0:0:0:0: [sda] Write Protect is off
[    3.506918] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    3.506930] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    3.507003]  sda:
[    3.606332] usb 2-1: new high speed USB device using ehci_hcd and address 2
[    3.756652] usb 2-1: configuration #1 chosen from 1 choice
[    3.756855] hub 2-1:1.0: USB hub found
[    3.756921] hub 2-1:1.0: 8 ports detected
[    3.835931] usb 1-1.3: new full speed USB device using ehci_hcd and address 3
[    3.835972]  sda1 sda2 < sda5 > sda3
[    3.860292] sd 0:0:0:0: [sda] Attached SCSI disk
[    3.875737] ata2: SATA link down (SStatus 0 SControl 300)
[    3.956971] usb 1-1.3: configuration #1 chosen from 1 choice
[    4.045430] usb 1-1.6: new high speed USB device using ehci_hcd and address 4
[    4.171817] usb 1-1.6: configuration #1 chosen from 1 choice
[    4.244894] ata5: SATA link down (SStatus 0 SControl 300)
[    4.245135] usb 2-1.2: new high speed USB device using ehci_hcd and address 3
[    4.366453] usb 2-1.2: configuration #1 chosen from 1 choice
[    4.370262] Initializing USB Mass Storage driver...
[    4.370442] scsi6 : SCSI emulation for USB Mass Storage devices
[    4.370545] usb-storage: device found at 3
[    4.370546] usb-storage: waiting for device to settle before scanning
[    4.370553] usbcore: registered new interface driver usb-storage
[    4.370555] USB Mass Storage support registered.
[    4.727336] xor: automatically using best checksumming function: generic_sse
[    4.774230]    generic_sse: 14178.800 MB/sec
[    4.774232] xor: using function: generic_sse (14178.800 MB/sec)
[    4.775554] device-mapper: dm-raid45: initialized v0.2594b
[    4.989448] EXT4-fs (sda1): mounted filesystem with ordered data mode
[    9.353456] usb-storage: device scan complete
[    9.353975] scsi 6:0:0:0: Direct-Access     Real-Way RW8021 PENDRIVE  1.0  PQ: 0 ANSI: 2
[    9.354686] sd 6:0:0:0: Attached scsi generic sg1 type 0
[    9.355404] sd 6:0:0:0: [sdb] 2004992 512-byte logical blocks: (1.02 GB/979 MiB)
[    9.356006] sd 6:0:0:0: [sdb] Write Protect is off
[    9.356013] sd 6:0:0:0: [sdb] Mode Sense: 0b 00 00 08
[    9.356018] sd 6:0:0:0: [sdb] Assuming drive cache: write through
[    9.358354] sd 6:0:0:0: [sdb] Assuming drive cache: write through
[    9.358362]  sdb: sdb1
[    9.361517] sd 6:0:0:0: [sdb] Assuming drive cache: write through
[    9.361529] sd 6:0:0:0: [sdb] Attached SCSI removable disk
[    9.757460] aufs 2-standalone.tree-20091207
[    9.766680] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[   17.825928] udev: starting version 151
[   18.125424] cfg80211: Calling CRDA to update world regulatory domain
[   18.209240] Linux video capture interface: v2.00
[   18.274884] uvcvideo: Found UVC 1.00 device Integrated Camera (04f2:b217)
[   18.275312] Non-volatile memory driver v1.3
[   18.276577] input: Integrated Camera as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.6/1-1.6:1.0/input/input5
[   18.276616] usbcore: registered new interface driver uvcvideo
[   18.276618] USB Video Class driver (v0.1.0)
[   18.332858] iwlagn: Intel(R) Wireless WiFi Link AGN driver for Linux, 1.3.27k
[   18.332860] iwlagn: Copyright(c) 2003-2009 Intel Corporation
[   18.332905] iwlagn 0000:03:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
[   18.332913] iwlagn 0000:03:00.0: setting latency timer to 64
[   18.332954] iwlagn 0000:03:00.0: Detected Intel Wireless WiFi Link 1000 Series BGN REV=0x6C
[   18.340168] tpm_tis 00:0a: 1.2 TPM (device-id 0x0, rev-id 78)
[   18.341325] thinkpad_acpi: ThinkPad ACPI Extras v0.24
[   18.341328] thinkpad_acpi: http://ibm-acpi.sf.net/
[   18.341329] thinkpad_acpi: ThinkPad BIOS 8DET55WW (1.25 ), EC unknown
[   18.341331] thinkpad_acpi: Lenovo ThinkPad X220, model 42872WU
[   18.341920] thinkpad_acpi: radio switch found; radios are enabled
[   18.342176] thinkpad_acpi: possible tablet mode switch found; ThinkPad in laptop mode
[   18.342457] thinkpad_acpi: This ThinkPad has standard ACPI backlight brightness control, supported by the ACPI video driver
[   18.342459] thinkpad_acpi: Disabling thinkpad-acpi brightness events by default...
[   18.350832] thinkpad_acpi: rfkill switch tpacpi_bluetooth_sw: radio is blocked
[   18.351738] Registered led device: tpacpi::thinklight
[   18.351849] Registered led device: tpacpi::power
[   18.352149] Registered led device: tpacpi::standby
[   18.352268] Registered led device: tpacpi::thinkvantage
[   18.352544] thinkpad_acpi: Standard ACPI backlight interface available, not loading native one.
[   18.353091] thinkpad_acpi: Console audio control enabled, mode: monitor (read only)
[   18.357607] input: ThinkPad Extra Buttons as /devices/platform/thinkpad_acpi/input/input6
[   18.366035] iwlagn 0000:03:00.0: Tunable channels: 13 802.11bg, 0 802.11a channels
[   18.366089]   alloc irq_desc for 29 on node -1
[   18.366091]   alloc kstat_irqs on node -1
[   18.366107] iwlagn 0000:03:00.0: irq 29 for MSI/MSI-X
[   18.385167] cfg80211: World regulatory domain updated:
[   18.385170] 	(start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[   18.385173] 	(2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[   18.385175] 	(2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[   18.385177] 	(2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[   18.385179] 	(5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[   18.385181] 	(5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[   18.421475] phy0: Selected rate control algorithm 'iwl-agn-rs'
[   18.479524] iwlagn 0000:03:00.0: firmware: requesting iwlwifi-1000-3.ucode
[   18.533209] iwlagn 0000:03:00.0: loaded firmware version 128.50.3.1
[   18.614789]   alloc irq_desc for 22 on node -1
[   18.614792]   alloc kstat_irqs on node -1
[   18.614798] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
[   18.614849] HDA Intel 0000:00:1b.0: setting latency timer to 64
[   18.626989] Registered led device: iwl-phy0::radio
[   18.627041] Registered led device: iwl-phy0::assoc
[   18.627055] Registered led device: iwl-phy0::RX
[   18.627081] Registered led device: iwl-phy0::TX
[   18.652850] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   18.757860] lp: driver loaded but no devices found
[   18.769510] ppdev: user-space parallel port driver
[   18.801947] Unable to query Synaptics hardware.
[   18.831644] usb 1-1.4: new full speed USB device using ehci_hcd and address 5
[   18.946926] usb 1-1.4: configuration #1 chosen from 1 choice
[   18.972175] Bluetooth: Core ver 2.15
[   18.972229] NET: Registered protocol family 31
[   18.972231] Bluetooth: HCI device and connection manager initialized
[   18.972233] Bluetooth: HCI socket layer initialized
[   18.988322] Bluetooth: Generic Bluetooth USB driver ver 0.6
[   18.988773] usbcore: registered new interface driver btusb
[   19.044668] Bluetooth: L2CAP ver 2.14
[   19.044670] Bluetooth: L2CAP socket layer initialized
[   19.104588] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   19.104590] Bluetooth: BNEP filters: protocol multicast
[   19.153873] Bridge firewalling registered
[   19.170539] Bluetooth: SCO (Voice Link) ver 0.6
[   19.170541] Bluetooth: SCO socket layer initialized
[   19.245834] Bluetooth: RFCOMM TTY layer initialized
[   19.245839] Bluetooth: RFCOMM socket layer initialized
[   19.245840] Bluetooth: RFCOMM ver 1.11
[   19.351209] CPU0 attaching NULL sched-domain.
[   19.351214] CPU1 attaching NULL sched-domain.
[   19.351217] CPU2 attaching NULL sched-domain.
[   19.351219] CPU3 attaching NULL sched-domain.
[   19.376986] input: PS/2 Synaptics TouchPad as /devices/platform/i8042/serio1/input/input7
[   19.422107] CPU0 attaching sched-domain:
[   19.422110]  domain 0: span 0-1 level SIBLING
[   19.422112]   groups: 0 (cpu_power = 589) 1 (cpu_power = 589)
[   19.422115]   domain 1: span 0-3 level MC
[   19.422116]    groups: 0-1 (cpu_power = 1178) 2-3 (cpu_power = 1178)
[   19.422120] CPU1 attaching sched-domain:
[   19.422121]  domain 0: span 0-1 level SIBLING
[   19.422123]   groups: 1 (cpu_power = 589) 0 (cpu_power = 589)
[   19.422125]   domain 1: span 0-3 level MC
[   19.422126]    groups: 0-1 (cpu_power = 1178) 2-3 (cpu_power = 1178)
[   19.422130] CPU2 attaching sched-domain:
[   19.422131]  domain 0: span 2-3 level SIBLING
[   19.422132]   groups: 2 (cpu_power = 589) 3 (cpu_power = 589)
[   19.422134]   domain 1: span 0-3 level MC
[   19.422135]    groups: 2-3 (cpu_power = 1178) 0-1 (cpu_power = 1178)
[   19.422138] CPU3 attaching sched-domain:
[   19.422139]  domain 0: span 2-3 level SIBLING
[   19.422140]   groups: 3 (cpu_power = 589) 2 (cpu_power = 589)
[   19.422143]   domain 1: span 0-3 level MC
[   19.422144]    groups: 2-3 (cpu_power = 1178) 0-1 (cpu_power = 1178)
[   19.422403] CPU0 attaching NULL sched-domain.
[   19.422405] CPU1 attaching NULL sched-domain.
[   19.422406] CPU2 attaching NULL sched-domain.
[   19.422407] CPU3 attaching NULL sched-domain.
[   19.471883] CPU0 attaching sched-domain:
[   19.471886]  domain 0: span 0-1 level SIBLING
[   19.471889]   groups: 0 (cpu_power = 589) 1 (cpu_power = 589)
[   19.471894]   domain 1: span 0-3 level MC
[   19.471896]    groups: 0-1 (cpu_power = 1178) 2-3 (cpu_power = 1178)
[   19.471902] CPU1 attaching sched-domain:
[   19.471904]  domain 0: span 0-1 level SIBLING
[   19.471906]   groups: 1 (cpu_power = 589) 0 (cpu_power = 589)
[   19.471911]   domain 1: span 0-3 level MC
[   19.471912]    groups: 0-1 (cpu_power = 1178) 2-3 (cpu_power = 1178)
[   19.471918] CPU2 attaching sched-domain:
[   19.471919]  domain 0: span 2-3 level SIBLING
[   19.471921]   groups: 2 (cpu_power = 589) 3 (cpu_power = 589)
[   19.471926]   domain 1: span 0-3 level MC
[   19.471928]    groups: 2-3 (cpu_power = 1178) 0-1 (cpu_power = 1178)
[   19.471933] CPU3 attaching sched-domain:
[   19.471934]  domain 0: span 2-3 level SIBLING
[   19.471936]   groups: 3 (cpu_power = 589) 2 (cpu_power = 589)
[   19.471941]   domain 1: span 0-3 level MC
[   19.471942]    groups: 2-3 (cpu_power = 1178) 0-1 (cpu_power = 1178)
[   23.838033] CPU0 attaching NULL sched-domain.
[   23.838036] CPU1 attaching NULL sched-domain.
[   23.838037] CPU2 attaching NULL sched-domain.
[   23.838039] CPU3 attaching NULL sched-domain.
[   23.890608] CPU0 attaching sched-domain:
[   23.890611]  domain 0: span 0-1 level SIBLING
[   23.890613]   groups: 0 (cpu_power = 589) 1 (cpu_power = 589)
[   23.890616]   domain 1: span 0-3 level MC
[   23.890617]    groups: 0-1 (cpu_power = 1178) 2-3 (cpu_power = 1178)
[   23.890622] CPU1 attaching sched-domain:
[   23.890623]  domain 0: span 0-1 level SIBLING
[   23.890624]   groups: 1 (cpu_power = 589) 0 (cpu_power = 589)
[   23.890627]   domain 1: span 0-3 level MC
[   23.890628]    groups: 0-1 (cpu_power = 1178) 2-3 (cpu_power = 1178)
[   23.890631] CPU2 attaching sched-domain:
[   23.890632]  domain 0: span 2-3 level SIBLING
[   23.890633]   groups: 2 (cpu_power = 589) 3 (cpu_power = 589)
[   23.890636]   domain 1: span 0-3 level MC
[   23.890637]    groups: 2-3 (cpu_power = 1178) 0-1 (cpu_power = 1178)
[   23.890640] CPU3 attaching sched-domain:
[   23.890641]  domain 0: span 2-3 level SIBLING
[   23.890642]   groups: 3 (cpu_power = 589) 2 (cpu_power = 589)
[   23.890645]   domain 1: span 0-3 level MC
[   23.890646]    groups: 2-3 (cpu_power = 1178) 0-1 (cpu_power = 1178)
[   23.890907] CPU0 attaching NULL sched-domain.
[   23.890909] CPU1 attaching NULL sched-domain.
[   23.890910] CPU2 attaching NULL sched-domain.
[   23.890911] CPU3 attaching NULL sched-domain.
[   23.950560] CPU0 attaching sched-domain:
[   23.950564]  domain 0: span 0-1 level SIBLING
[   23.950567]   groups: 0 (cpu_power = 589) 1 (cpu_power = 589)
[   23.950572]   domain 1: span 0-3 level MC
[   23.950574]    groups: 0-1 (cpu_power = 1178) 2-3 (cpu_power = 1178)
[   23.950581] CPU1 attaching sched-domain:
[   23.950583]  domain 0: span 0-1 level SIBLING
[   23.950585]   groups: 1 (cpu_power = 589) 0 (cpu_power = 589)
[   23.950589]   domain 1: span 0-3 level MC
[   23.950591]    groups: 0-1 (cpu_power = 1178) 2-3 (cpu_power = 1178)
[   23.950597] CPU2 attaching sched-domain:
[   23.950598]  domain 0: span 2-3 level SIBLING
[   23.950600]   groups: 2 (cpu_power = 589) 3 (cpu_power = 589)
[   23.950605]   domain 1: span 0-3 level MC
[   23.950607]    groups: 2-3 (cpu_power = 1178) 0-1 (cpu_power = 1178)
[   23.950612] CPU3 attaching sched-domain:
[   23.950614]  domain 0: span 2-3 level SIBLING
[   23.950616]   groups: 3 (cpu_power = 589) 2 (cpu_power = 589)
[   23.950620]   domain 1: span 0-3 level MC
[   23.950622]    groups: 2-3 (cpu_power = 1178) 0-1 (cpu_power = 1178)
[   71.172915] SGI XFS with ACLs, security attributes, realtime, large block/inode numbers, no debug enabled
[   71.173819] SGI XFS Quota Management subsystem
[   71.241675] XFS mounting filesystem sda3
[   71.602949] Ending clean XFS mount for filesystem: sda3
[  242.149938] INFO: task copy-files:3386 blocked for more than 120 seconds.
[  242.149946] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  242.149951] copy-files    D 0000000000000000     0  3386   3337 0x00000000
[  242.149961]  ffff880214b17c88 0000000000000086 0000000000015c00 0000000000015c00
[  242.149970]  ffff880208e43198 ffff880214b17fd8 0000000000015c00 ffff880208e42de0
[  242.149978]  0000000000015c00 ffff880214b17fd8 0000000000015c00 ffff880208e43198
[  242.149986] Call Trace:
[  242.150027]  [<ffffffffa034d103>] xlog_grant_log_space+0x173/0x3f0 [xfs]
[  242.150055]  [<ffffffffa035fa4a>] ? kmem_zone_zalloc+0x3a/0x50 [xfs]
[  242.150066]  [<ffffffff8105cd70>] ? default_wake_function+0x0/0x20
[  242.150095]  [<ffffffffa034d454>] xfs_log_reserve+0xd4/0xe0 [xfs]
[  242.150124]  [<ffffffffa0357b20>] xfs_trans_reserve+0xa0/0x210 [xfs]
[  242.150151]  [<ffffffffa035ca45>] xfs_free_eofblocks+0x185/0x2a0 [xfs]
[  242.150179]  [<ffffffffa035d578>] xfs_release+0x128/0x1f0 [xfs]
[  242.150207]  [<ffffffffa0364f35>] xfs_file_release+0x15/0x20 [xfs]
[  242.150217]  [<ffffffff81145ea5>] __fput+0xf5/0x210
[  242.150225]  [<ffffffff81145fe5>] fput+0x25/0x30
[  242.150232]  [<ffffffff8114210d>] filp_close+0x5d/0x90
[  242.150239]  [<ffffffff811421f7>] sys_close+0xb7/0x120
[  242.150249]  [<ffffffff810121b2>] system_call_fastpath+0x16/0x1b
[  242.150255] INFO: task copy-files:3387 blocked for more than 120 seconds.
[  242.150259] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  242.150263] copy-files    D 0000000000000000     0  3387   3337 0x00000000
[  242.150271]  ffff8802149cbd38 0000000000000086 0000000000015c00 0000000000015c00
[  242.150279]  ffff880208e44888 ffff8802149cbfd8 0000000000015c00 ffff880208e444d0
[  242.150286]  0000000000015c00 ffff8802149cbfd8 0000000000015c00 ffff880208e44888
[  242.150293] Call Trace:
[  242.150304]  [<ffffffff81546477>] __mutex_lock_slowpath+0xf7/0x180
[  242.150313]  [<ffffffff81145c1a>] ? get_empty_filp+0x7a/0x170
[  242.150321]  [<ffffffff8154635b>] mutex_lock+0x2b/0x50
[  242.150329]  [<ffffffff81152d49>] do_filp_open+0x3d9/0xba0
[  242.150336]  [<ffffffff81148b74>] ? cp_new_stat+0xe4/0x100
[  242.150345]  [<ffffffff8115e7fa>] ? alloc_fd+0x10a/0x150
[  242.150352]  [<ffffffff811422c9>] do_sys_open+0x69/0x170
[  242.150359]  [<ffffffff81142410>] sys_open+0x20/0x30
[  242.150367]  [<ffffffff810121b2>] system_call_fastpath+0x16/0x1b
[  242.150373] INFO: task copy-files:3390 blocked for more than 120 seconds.
[  242.150377] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  242.150381] copy-files    D 0000000000000000     0  3390   3337 0x00000000
[  242.150388]  ffff880212051c88 0000000000000086 0000000000015c00 0000000000015c00
[  242.150395]  ffff8801ed7903b8 ffff880212051fd8 0000000000015c00 ffff8801ed790000
[  242.150403]  0000000000015c00 ffff880212051fd8 0000000000015c00 ffff8801ed7903b8
[  242.150410] Call Trace:
[  242.150438]  [<ffffffffa034d103>] xlog_grant_log_space+0x173/0x3f0 [xfs]
[  242.150465]  [<ffffffffa035fa4a>] ? kmem_zone_zalloc+0x3a/0x50 [xfs]
[  242.150472]  [<ffffffff8105cd70>] ? default_wake_function+0x0/0x20
[  242.150500]  [<ffffffffa034d454>] xfs_log_reserve+0xd4/0xe0 [xfs]
[  242.150529]  [<ffffffffa0357b20>] xfs_trans_reserve+0xa0/0x210 [xfs]
[  242.150556]  [<ffffffffa035ca45>] xfs_free_eofblocks+0x185/0x2a0 [xfs]
[  242.150583]  [<ffffffffa035d578>] xfs_release+0x128/0x1f0 [xfs]
[  242.150610]  [<ffffffffa0364f35>] xfs_file_release+0x15/0x20 [xfs]
[  242.150618]  [<ffffffff81145ea5>] __fput+0xf5/0x210
[  242.150626]  [<ffffffff81145fe5>] fput+0x25/0x30
[  242.150632]  [<ffffffff8114210d>] filp_close+0x5d/0x90
[  242.150639]  [<ffffffff811421f7>] sys_close+0xb7/0x120
[  242.150647]  [<ffffffff810121b2>] system_call_fastpath+0x16/0x1b
[  242.150653] INFO: task copy-files:3391 blocked for more than 120 seconds.
[  242.150657] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  242.150661] copy-files    D 00000000ffffffff     0  3391   3337 0x00000000
[  242.150668]  ffff880208eafd38 0000000000000086 0000000000015c00 0000000000015c00
[  242.150675]  ffff8802103ec888 ffff880208eaffd8 0000000000015c00 ffff8802103ec4d0
[  242.150683]  0000000000015c00 ffff880208eaffd8 0000000000015c00 ffff8802103ec888
[  242.150690] Call Trace:
[  242.150699]  [<ffffffff81546477>] __mutex_lock_slowpath+0xf7/0x180
[  242.150707]  [<ffffffff81145c1a>] ? get_empty_filp+0x7a/0x170
[  242.150715]  [<ffffffff8154635b>] mutex_lock+0x2b/0x50
[  242.150723]  [<ffffffff81152d49>] do_filp_open+0x3d9/0xba0
[  242.150729]  [<ffffffff81148b74>] ? cp_new_stat+0xe4/0x100
[  242.150737]  [<ffffffff8115e7fa>] ? alloc_fd+0x10a/0x150
[  242.150744]  [<ffffffff811422c9>] do_sys_open+0x69/0x170
[  242.150751]  [<ffffffff81142410>] sys_open+0x20/0x30
[  242.150759]  [<ffffffff810121b2>] system_call_fastpath+0x16/0x1b
[  242.150765] INFO: task copy-files:3394 blocked for more than 120 seconds.
[  242.150768] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  242.150773] copy-files    D ffff8801f2b08f10     0  3394   3337 0x00000000
[  242.150780]  ffff8801ed697d38 0000000000000086 0000000000015c00 0000000000015c00
[  242.150787]  ffff88021402c888 ffff8801ed697fd8 0000000000015c00 ffff88021402c4d0
[  242.150794]  0000000000015c00 ffff8801ed697fd8 0000000000015c00 ffff88021402c888
[  242.150801] Call Trace:
[  242.150809]  [<ffffffff81546477>] __mutex_lock_slowpath+0xf7/0x180
[  242.150818]  [<ffffffff8154635b>] mutex_lock+0x2b/0x50
[  242.150825]  [<ffffffff81152d49>] do_filp_open+0x3d9/0xba0
[  242.150831]  [<ffffffff81148b74>] ? cp_new_stat+0xe4/0x100
[  242.150839]  [<ffffffff8115e7fa>] ? alloc_fd+0x10a/0x150
[  242.150846]  [<ffffffff811422c9>] do_sys_open+0x69/0x170
[  242.150853]  [<ffffffff81142410>] sys_open+0x20/0x30
[  242.150861]  [<ffffffff810121b2>] system_call_fastpath+0x16/0x1b
[  242.150866] INFO: task copy-files:3395 blocked for more than 120 seconds.
[  242.150870] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  242.150874] copy-files    D 0000000000000000     0  3395   3337 0x00000000
[  242.150881]  ffff8801ed68f7a8 0000000000000086 0000000000015c00 0000000000015c00
[  242.150888]  ffff880214b44888 ffff8801ed68ffd8 0000000000015c00 ffff880214b444d0
[  242.150895]  0000000000015c00 ffff8801ed68ffd8 0000000000015c00 ffff880214b44888
[  242.150902] Call Trace:
[  242.150930]  [<ffffffffa034d103>] xlog_grant_log_space+0x173/0x3f0 [xfs]
[  242.150957]  [<ffffffffa035fa4a>] ? kmem_zone_zalloc+0x3a/0x50 [xfs]
[  242.150963]  [<ffffffff8105cd70>] ? default_wake_function+0x0/0x20
[  242.150991]  [<ffffffffa034d454>] xfs_log_reserve+0xd4/0xe0 [xfs]
[  242.151019]  [<ffffffffa0357b20>] xfs_trans_reserve+0xa0/0x210 [xfs]
[  242.151048]  [<ffffffffa0357e8f>] ? xfs_trans_alloc+0x9f/0xb0 [xfs]
[  242.151077]  [<ffffffffa03472cc>] xfs_iomap_write_allocate+0x25c/0x3c0 [xfs]
[  242.151107]  [<ffffffffa0358929>] ? xfs_trans_unlocked_item+0x39/0x60 [xfs]
[  242.151136]  [<ffffffffa0347f8b>] xfs_iomap+0x2ab/0x2e0 [xfs]
[  242.151163]  [<ffffffffa036067d>] xfs_map_blocks+0x2d/0x40 [xfs]
[  242.151190]  [<ffffffffa0361a7a>] xfs_page_state_convert+0x3da/0x720 [xfs]
[  242.151200]  [<ffffffff812b9f05>] ? radix_tree_gang_lookup_tag_slot+0x95/0xe0
[  242.151209]  [<ffffffff810f4581>] ? generic_perform_write+0x161/0x1d0
[  242.151235]  [<ffffffffa0361f2a>] xfs_vm_writepage+0x7a/0x130 [xfs]
[  242.151244]  [<ffffffff8110e0f5>] ? __dec_zone_page_state+0x35/0x40
[  242.151253]  [<ffffffff810fcf07>] __writepage+0x17/0x40
[  242.151259]  [<ffffffff810fe08f>] write_cache_pages+0x1df/0x3e0
[  242.151268]  [<ffffffff810fcef0>] ? __writepage+0x0/0x40
[  242.151275]  [<ffffffff810fe2b4>] generic_writepages+0x24/0x30
[  242.151301]  [<ffffffffa0360d1d>] xfs_vm_writepages+0x5d/0x80 [xfs]
[  242.151307]  [<ffffffff810fe2e1>] do_writepages+0x21/0x40
[  242.151315]  [<ffffffff810f556b>] __filemap_fdatawrite_range+0x5b/0x60
[  242.151323]  [<ffffffff810f589f>] filemap_fdatawrite+0x1f/0x30
[  242.151349]  [<ffffffffa0365199>] xfs_flush_pages+0xa9/0xc0 [xfs]
[  242.151375]  [<ffffffffa035d5cf>] xfs_release+0x17f/0x1f0 [xfs]
[  242.151401]  [<ffffffffa0364f35>] xfs_file_release+0x15/0x20 [xfs]
[  242.151410]  [<ffffffff81145ea5>] __fput+0xf5/0x210
[  242.151417]  [<ffffffff81145fe5>] fput+0x25/0x30
[  242.151424]  [<ffffffff8114210d>] filp_close+0x5d/0x90
[  242.151431]  [<ffffffff811421f7>] sys_close+0xb7/0x120
[  242.151439]  [<ffffffff810121b2>] system_call_fastpath+0x16/0x1b
[  242.151445] INFO: task copy-files:3398 blocked for more than 120 seconds.
[  242.151449] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  242.151453] copy-files    D 0000000000000000     0  3398   3337 0x00000000
[  242.151460]  ffff8801ed4adc88 0000000000000086 0000000000015c00 0000000000015c00
[  242.151467]  ffff8802105b9aa8 ffff8801ed4adfd8 0000000000015c00 ffff8802105b96f0
[  242.151474]  0000000000015c00 ffff8801ed4adfd8 0000000000015c00 ffff8802105b9aa8
[  242.151481] Call Trace:
[  242.151509]  [<ffffffffa034d103>] xlog_grant_log_space+0x173/0x3f0 [xfs]
[  242.151535]  [<ffffffffa035fa4a>] ? kmem_zone_zalloc+0x3a/0x50 [xfs]
[  242.151542]  [<ffffffff8105cd70>] ? default_wake_function+0x0/0x20
[  242.151570]  [<ffffffffa034d454>] xfs_log_reserve+0xd4/0xe0 [xfs]
[  242.151599]  [<ffffffffa0357b20>] xfs_trans_reserve+0xa0/0x210 [xfs]
[  242.151625]  [<ffffffffa035ca45>] xfs_free_eofblocks+0x185/0x2a0 [xfs]
[  242.151652]  [<ffffffffa035d578>] xfs_release+0x128/0x1f0 [xfs]
[  242.151679]  [<ffffffffa0364f35>] xfs_file_release+0x15/0x20 [xfs]
[  242.151687]  [<ffffffff81145ea5>] __fput+0xf5/0x210
[  242.151694]  [<ffffffff81145fe5>] fput+0x25/0x30
[  242.151701]  [<ffffffff8114210d>] filp_close+0x5d/0x90
[  242.151728]  [<ffffffff811421f7>] sys_close+0xb7/0x120
[  242.151737]  [<ffffffff810121b2>] system_call_fastpath+0x16/0x1b
[  242.151743] INFO: task copy-files:3399 blocked for more than 120 seconds.
[  242.151746] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  242.151751] copy-files    D 0000000000000000     0  3399   3337 0x00000000
[  242.151758]  ffff8802149afd38 0000000000000086 0000000000015c00 0000000000015c00
[  242.151765]  ffff8802105bb198 ffff8802149affd8 0000000000015c00 ffff8802105bade0
[  242.151776]  0000000000015c00 ffff8802149affd8 0000000000015c00 ffff8802105bb198
[  242.151794] Call Trace:
[  242.151806]  [<ffffffff81546477>] __mutex_lock_slowpath+0xf7/0x180
[  242.151818]  [<ffffffff81145c1a>] ? get_empty_filp+0x7a/0x170
[  242.151831]  [<ffffffff8154635b>] mutex_lock+0x2b/0x50
[  242.151843]  [<ffffffff81152d49>] do_filp_open+0x3d9/0xba0
[  242.151853]  [<ffffffff81148b74>] ? cp_new_stat+0xe4/0x100
[  242.151866]  [<ffffffff8115e7fa>] ? alloc_fd+0x10a/0x150
[  242.151878]  [<ffffffff811422c9>] do_sys_open+0x69/0x170
[  242.151890]  [<ffffffff81142410>] sys_open+0x20/0x30
[  242.151902]  [<ffffffff810121b2>] system_call_fastpath+0x16/0x1b
[  242.151912] INFO: task copy-files:3401 blocked for more than 120 seconds.
[  242.151919] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  242.151928] copy-files    D 0000000000000001     0  3401   3337 0x00000008
[  242.151942]  ffff8801ed4efcf8 0000000000000086 0000000000015c00 0000000000015c00
[  242.151960]  ffff8802105bdf78 ffff8801ed4effd8 0000000000015c00 ffff8802105bdbc0
[  242.151978]  0000000000015c00 ffff8801ed4effd8 0000000000015c00 ffff8802105bdf78
[  242.151995] Call Trace:
[  242.152006]  [<ffffffff81546477>] __mutex_lock_slowpath+0xf7/0x180
[  242.152019]  [<ffffffff8154635b>] mutex_lock+0x2b/0x50
[  242.152030]  [<ffffffff8114e923>] lock_rename+0xd3/0xe0
[  242.152043]  [<ffffffff81151d13>] sys_renameat+0x113/0x280
[  242.152055]  [<ffffffff81155280>] ? filldir+0x0/0xe0
[  242.152069]  [<ffffffff81145fe5>] ? fput+0x25/0x30
[  242.152080]  [<ffffffff8114210d>] ? filp_close+0x5d/0x90
[  242.152092]  [<ffffffff81151e9b>] sys_rename+0x1b/0x20
[  242.152104]  [<ffffffff810121b2>] system_call_fastpath+0x16/0x1b
[  242.152113] INFO: task copy-files:3402 blocked for more than 120 seconds.
[  242.152121] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  242.152129] copy-files    D 0000000000000000     0  3402   3337 0x00000000
[  242.152143]  ffff880208e09c88 0000000000000086 0000000000015c00 0000000000015c00
[  242.152161]  ffff8801ed6b03b8 ffff880208e09fd8 0000000000015c00 ffff8801ed6b0000
[  242.152178]  0000000000015c00 ffff880208e09fd8 0000000000015c00 ffff8801ed6b03b8
[  242.152196] Call Trace:
[  242.152227]  [<ffffffffa034d103>] xlog_grant_log_space+0x173/0x3f0 [xfs]
[  242.152258]  [<ffffffffa035fa4a>] ? kmem_zone_zalloc+0x3a/0x50 [xfs]
[  242.152270]  [<ffffffff8105cd70>] ? default_wake_function+0x0/0x20
[  242.152302]  [<ffffffffa034d454>] xfs_log_reserve+0xd4/0xe0 [xfs]
[  242.152335]  [<ffffffffa0357b20>] xfs_trans_reserve+0xa0/0x210 [xfs]
[  242.152367]  [<ffffffffa035ca45>] xfs_free_eofblocks+0x185/0x2a0 [xfs]
[  242.152398]  [<ffffffffa035d578>] xfs_release+0x128/0x1f0 [xfs]
[  242.152429]  [<ffffffffa0364f35>] xfs_file_release+0x15/0x20 [xfs]
[  242.152442]  [<ffffffff81145ea5>] __fput+0xf5/0x210
[  242.152454]  [<ffffffff81145fe5>] fput+0x25/0x30
[  242.152464]  [<ffffffff8114210d>] filp_close+0x5d/0x90
[  242.152475]  [<ffffffff811421f7>] sys_close+0xb7/0x120
[  242.152488]  [<ffffffff810121b2>] system_call_fastpath+0x16/0x1b

[-- Attachment #3: Type: text/plain, Size: 121 bytes --]

_______________________________________________
xfs mailing list
xfs@oss.sgi.com
http://oss.sgi.com/mailman/listinfo/xfs

  reply	other threads:[~2012-05-18 16:53 UTC|newest]

Thread overview: 58+ messages / expand[flat|nested]  mbox.gz  Atom feed  top
2012-04-23 12:09 Still seeing hangs in xlog_grant_log_space Juerg Haefliger
2012-04-23 14:38 ` Dave Chinner
2012-04-23 15:33   ` Juerg Haefliger
2012-04-23 23:58     ` Dave Chinner
2012-04-24  8:55       ` Juerg Haefliger
2012-04-24 12:07         ` Dave Chinner
2012-04-24 18:26           ` Juerg Haefliger
2012-04-25 22:38             ` Dave Chinner
2012-04-26 12:37               ` Juerg Haefliger
2012-04-26 22:44                 ` Dave Chinner
2012-04-26 23:00                   ` Juerg Haefliger
2012-04-26 23:07                     ` Dave Chinner
2012-04-27  9:04                       ` Juerg Haefliger
2012-04-27 11:09                         ` Dave Chinner
2012-04-27 13:07                           ` Juerg Haefliger
2012-05-05  7:44                             ` Juerg Haefliger
2012-05-07 17:19                               ` Ben Myers
2012-05-09  7:54                                 ` Juerg Haefliger
2012-05-10 16:11                                   ` Chris J Arges
2012-05-10 21:53                                     ` Mark Tinguely
2012-05-16 18:42                                     ` Ben Myers
2012-05-16 19:03                                       ` Chris J Arges
2012-05-16 21:29                                         ` Mark Tinguely
2012-05-18 10:10                                           ` Dave Chinner
2012-05-18 14:42                                             ` Mark Tinguely
2012-05-22 22:59                                               ` Dave Chinner
2012-06-06 15:00                                             ` Chris J Arges
2012-06-07  0:49                                               ` Dave Chinner
2012-05-17 20:55                                       ` Chris J Arges
2012-05-18 16:53                                         ` Chris J Arges [this message]
2012-05-18 17:19                                   ` Ben Myers
2012-05-19  7:28                                     ` Juerg Haefliger
2012-05-21 17:11                                       ` Ben Myers
2012-05-24  5:45                                         ` Juerg Haefliger
2012-05-24 14:23                                           ` Ben Myers
2012-05-07 22:59                               ` Dave Chinner
2012-05-09  7:35                                 ` Dave Chinner
2012-05-09 21:07                                   ` Mark Tinguely
2012-05-10  2:10                                     ` Mark Tinguely
2012-05-18  9:37                                       ` Dave Chinner
2012-05-18  9:31                                     ` Dave Chinner
2012-05-24 20:18 ` Peter Watkins
2012-05-25  6:28   ` Juerg Haefliger
2012-05-25 17:03     ` Peter Watkins
2012-06-05 23:54       ` Dave Chinner
2012-06-06 13:40         ` Brian Foster
2012-06-06 17:41           ` Mark Tinguely
2012-06-11 20:42             ` Chris J Arges
2012-06-11 23:53               ` Dave Chinner
2012-06-12 13:28                 ` Chris J Arges
2012-06-06 22:03           ` Mark Tinguely
2012-06-06 23:04             ` Brian Foster
2012-06-07  1:35           ` Dave Chinner
2012-06-07 14:16             ` Brian Foster
2012-06-08  0:28               ` Dave Chinner
2012-06-08 17:09                 ` Ben Myers
2012-06-11 20:59         ` Mark Tinguely
2012-06-05 15:21   ` Chris J Arges

Reply instructions:

You may reply publicly to this message via plain-text email
using any one of the following methods:

* Save the following mbox file, import it into your mail client,
  and reply-to-all from there: mbox

  Avoid top-posting and favor interleaved quoting:
  https://en.wikipedia.org/wiki/Posting_style#Interleaved_style

* Reply using the --to, --cc, and --in-reply-to
  switches of git-send-email(1):

  git send-email \
    --in-reply-to=4FB67EA5.20609@canonical.com \
    --to=chris.j.arges@canonical.com \
    --cc=bpm@sgi.com \
    --cc=linux-xfs@oss.sgi.com \
    --cc=tinguely@sgi.com \
    /path/to/YOUR_REPLY

  https://kernel.org/pub/software/scm/git/docs/git-send-email.html

* If your mail client supports setting the In-Reply-To header
  via mailto: links, try the mailto: link
Be sure your reply has a Subject: header at the top and a blank line before the message body.
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.