Re: linux-3.2.1 + KVM: TSC unstable on Core i5-2400S / H67

From: Jan Kiszka
Date: Thu Jan 26 2012 - 09:43:59 EST

Next message: Kirill A. Shutemov: "Re: [67/90] intel idle: Make idle driver more robust"
Previous message: Sasha Levin: "Re: [PATCH] netns: fix net_alloc_generic()"
In reply to: Johannes Stezenbach: "Re: linux-3.2.1 + KVM: TSC unstable on Core i5-2400S / H67"
Next in thread: Johannes Stezenbach: "Re: linux-3.2.1 + KVM: TSC unstable on Core i5-2400S / H67"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]

On 2012-01-26 10:46, Johannes Stezenbach wrote:
> On Wed, Jan 25, 2012 at 02:25:03PM +0100, Johannes Stezenbach wrote:
>> On Wed, Jan 25, 2012 at 10:27:03AM -0200, Marcelo Tosatti wrote:
>>> On Tue, Jan 24, 2012 at 12:36:08PM +0100, Johannes Stezenbach wrote:
>>>>
>>>> [23379.612251] usb 2-1.2: new high-speed USB device number 6 using ehci_hcd
>>>> [ 0.006666] Marking TSC unstable due to KVM discovered backwards TSC
>>>> [23421.511167] Switching to clocksource hpet
>> ...
>>> Can you please apply the following patch, and report back when it
>>> reproduces again.
>>
>> I applied the patch and rebooted, I'll let you know
>> as soon as the issue reappears.
>
> Today I got a "BUG: spinlock bad magic" right after resume,
> and then the TSC message. I'll just add the full dmesg
> from after resume. I didn't get this BUG before.
>
> Thanks,
> Johannes
>
> [ 0.000000] Initializing cgroup subsys cpu
> [ 0.000000] Linux version 3.2.1 (root@zzz) (gcc version 4.6.2 (Debian 4.6.2-12) ) #2 SMP PREEMPT Wed Jan 25 14:14:58 CET 2012
> [ 0.000000] Command line: root=/dev/mapper/zzz-croot libata.force=3:1.5G ro
> [ 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 - 00000000cac19000 (usable)
> [ 0.000000] BIOS-e820: 00000000cac19000 - 00000000cac75000 (ACPI NVS)
> [ 0.000000] BIOS-e820: 00000000cac75000 - 00000000cada8000 (reserved)
> [ 0.000000] BIOS-e820: 00000000cada8000 - 00000000cadb9000 (ACPI NVS)
> [ 0.000000] BIOS-e820: 00000000cadb9000 - 00000000cadd0000 (reserved)
> [ 0.000000] BIOS-e820: 00000000cadd0000 - 00000000cadd2000 (usable)
> [ 0.000000] BIOS-e820: 00000000cadd2000 - 00000000cadd3000 (ACPI NVS)
> [ 0.000000] BIOS-e820: 00000000cadd3000 - 00000000caddb000 (reserved)
> [ 0.000000] BIOS-e820: 00000000caddb000 - 00000000cade5000 (ACPI NVS)
> [ 0.000000] BIOS-e820: 00000000cade5000 - 00000000cae41000 (reserved)
> [ 0.000000] BIOS-e820: 00000000cae41000 - 00000000cae84000 (ACPI NVS)
> [ 0.000000] BIOS-e820: 00000000cae84000 - 00000000cb000000 (usable)
> [ 0.000000] BIOS-e820: 00000000cb800000 - 00000000cfa00000 (reserved)
> [ 0.000000] BIOS-e820: 00000000fed1c000 - 00000000fed20000 (reserved)
> [ 0.000000] BIOS-e820: 00000000ff000000 - 0000000100000000 (reserved)
> [ 0.000000] BIOS-e820: 0000000100000000 - 000000012fe00000 (usable)
> [ 0.000000] NX (Execute Disable) protection: active
> [ 0.000000] DMI 2.6 present.
> [ 0.000000] DMI: System manufacturer System Product Name/P8H67-V, BIOS 0806 10/26/2011
> [ 0.000000] e820 update range: 0000000000000000 - 0000000000010000 (usable) ==> (reserved)
> [ 0.000000] e820 remove range: 00000000000a0000 - 0000000000100000 (usable)
> [ 0.000000] No AGP bridge found
> [ 0.000000] last_pfn = 0x12fe00 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-CFFFF write-protect
> [ 0.000000] D0000-E7FFF uncachable
> [ 0.000000] E8000-FFFFF write-protect
> [ 0.000000] MTRR variable ranges enabled:
> [ 0.000000] 0 base 000000000 mask F00000000 write-back
> [ 0.000000] 1 base 100000000 mask FC0000000 write-back
> [ 0.000000] 2 base 0CB800000 mask FFF800000 uncachable
> [ 0.000000] 3 base 0CC000000 mask FFC000000 uncachable
> [ 0.000000] 4 base 0D0000000 mask FF0000000 uncachable
> [ 0.000000] 5 base 0E0000000 mask FE0000000 uncachable
> [ 0.000000] 6 base 12FE00000 mask FFFE00000 uncachable
> [ 0.000000] 7 base 130000000 mask FF0000000 uncachable
> [ 0.000000] 8 disabled
> [ 0.000000] 9 disabled
> [ 0.000000] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
> [ 0.000000] e820 update range: 00000000cb800000 - 0000000100000000 (usable) ==> (reserved)
> [ 0.000000] last_pfn = 0xcb000 max_arch_pfn = 0x400000000
> [ 0.000000] initial memory mapped : 0 - 20000000
> [ 0.000000] Base memory trampoline at [ffff880000098000] 98000 size 20480
> [ 0.000000] init_memory_mapping: 0000000000000000-00000000cb000000
> [ 0.000000] 0000000000 - 00cb000000 page 2M
> [ 0.000000] kernel direct mapping tables up to cb000000 @ 1fffb000-20000000
> [ 0.000000] init_memory_mapping: 0000000100000000-000000012fe00000
> [ 0.000000] 0100000000 - 012fe00000 page 2M
> [ 0.000000] kernel direct mapping tables up to 12fe00000 @ caffa000-cb000000
> [ 0.000000] RAMDISK: 1fd66000 - 1fff0000
> [ 0.000000] ACPI: RSDP 00000000000f0420 00024 (v02 ALASKA)
> [ 0.000000] ACPI: XSDT 00000000cac68068 0004C (v01 ALASKA A M I 01072009 AMI 00010013)
> [ 0.000000] ACPI: FACP 00000000cac718e0 000F4 (v04 ALASKA A M I 01072009 AMI 00010013)
> [ 0.000000] ACPI: DSDT 00000000cac68140 0979E (v02 ALASKA A M I 00000000 INTL 20051117)
> [ 0.000000] ACPI: FACS 00000000caddcf80 00040
> [ 0.000000] ACPI: APIC 00000000cac719d8 00072 (v03 ALASKA A M I 01072009 AMI 00010013)
> [ 0.000000] ACPI: SSDT 00000000cac71a50 00102 (v01 AMICPU PROC 00000001 MSFT 03000001)
> [ 0.000000] ACPI: MCFG 00000000cac71b58 0003C (v01 ALASKA A M I 01072009 MSFT 00000097)
> [ 0.000000] ACPI: HPET 00000000cac71b98 00038 (v01 ALASKA A M I 01072009 AMI. 00000004)
> [ 0.000000] ACPI: Local APIC address 0xfee00000
> [ 0.000000] [ffffea0000000000-ffffea0004bfffff] PMD -> [ffff88012b400000-ffff88012f3fffff] on node 0
> [ 0.000000] Zone PFN ranges:
> [ 0.000000] DMA 0x00000010 -> 0x00001000
> [ 0.000000] DMA32 0x00001000 -> 0x00100000
> [ 0.000000] Normal 0x00100000 -> 0x0012fe00
> [ 0.000000] Movable zone start PFN for each node
> [ 0.000000] early_node_map[7] active PFN ranges
> [ 0.000000] 0: 0x00000010 -> 0x0000009d
> [ 0.000000] 0: 0x00000100 -> 0x00020000
> [ 0.000000] 0: 0x00020200 -> 0x00040000
> [ 0.000000] 0: 0x00040200 -> 0x000cac19
> [ 0.000000] 0: 0x000cadd0 -> 0x000cadd2
> [ 0.000000] 0: 0x000cae84 -> 0x000cb000
> [ 0.000000] 0: 0x00100000 -> 0x0012fe00
> [ 0.000000] On node 0 totalpages: 1025828
> [ 0.000000] DMA zone: 64 pages used for memmap
> [ 0.000000] DMA zone: 5 pages reserved
> [ 0.000000] DMA zone: 3912 pages, LIFO batch:0
> [ 0.000000] DMA32 zone: 16320 pages used for memmap
> [ 0.000000] DMA32 zone: 809431 pages, LIFO batch:31
> [ 0.000000] Normal zone: 3064 pages used for memmap
> [ 0.000000] Normal zone: 193032 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[0x02] enabled)
> [ 0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x04] enabled)
> [ 0.000000] ACPI: LAPIC (acpi_id[0x04] lapic_id[0x06] enabled)
> [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] high edge lint[0x1])
> [ 0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0])
> [ 0.000000] IOAPIC[0]: apic_id 0, 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: 0x8086a701 base: 0xfed00000
> [ 0.000000] SMP: Allowing 4 CPUs, 0 hotplug CPUs
> [ 0.000000] nr_irqs_gsi: 40
> [ 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: 00000000cac19000 - 00000000cac75000
> [ 0.000000] PM: Registered nosave memory: 00000000cac75000 - 00000000cada8000
> [ 0.000000] PM: Registered nosave memory: 00000000cada8000 - 00000000cadb9000
> [ 0.000000] PM: Registered nosave memory: 00000000cadb9000 - 00000000cadd0000
> [ 0.000000] PM: Registered nosave memory: 00000000cadd2000 - 00000000cadd3000
> [ 0.000000] PM: Registered nosave memory: 00000000cadd3000 - 00000000caddb000
> [ 0.000000] PM: Registered nosave memory: 00000000caddb000 - 00000000cade5000
> [ 0.000000] PM: Registered nosave memory: 00000000cade5000 - 00000000cae41000
> [ 0.000000] PM: Registered nosave memory: 00000000cae41000 - 00000000cae84000
> [ 0.000000] PM: Registered nosave memory: 00000000cb000000 - 00000000cb800000
> [ 0.000000] PM: Registered nosave memory: 00000000cb800000 - 00000000cfa00000
> [ 0.000000] PM: Registered nosave memory: 00000000cfa00000 - 00000000fed1c000
> [ 0.000000] PM: Registered nosave memory: 00000000fed1c000 - 00000000fed20000
> [ 0.000000] PM: Registered nosave memory: 00000000fed20000 - 00000000ff000000
> [ 0.000000] PM: Registered nosave memory: 00000000ff000000 - 0000000100000000
> [ 0.000000] Allocating PCI resources starting at cfa00000 (gap: cfa00000:2f31c000)
> [ 0.000000] Booting paravirtualized kernel on bare hardware
> [ 0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:4 nr_node_ids:1
> [ 0.000000] PERCPU: Embedded 475 pages/cpu @ffff88012ac00000 s1914688 r8192 d22720 u2097152
> [ 0.000000] pcpu-alloc: s1914688 r8192 d22720 u2097152 alloc=1*2097152
> [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
> [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 1006375
> [ 0.000000] Kernel command line: root=/dev/mapper/zzz-croot libata.force=3:1.5G ro
> [ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
> [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
> [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
> [ 0.000000] xsave/xrstor: enabled xstate_bv 0x7, cntxt size 0x340
> [ 0.000000] Checking aperture...
> [ 0.000000] No AGP bridge found
> [ 0.000000] Memory: 3933028k/4978688k available (5497k kernel code, 875376k absent, 170284k reserved, 5579k data, 2572k init)
> [ 0.000000] SLUB: Genslabs=15, HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
> [ 0.000000] Preemptible hierarchical RCU implementation.
> [ 0.000000] CONFIG_RCU_FANOUT set to non-default value of 32
> [ 0.000000] NR_IRQS:4352 nr_irqs:712 16
> [ 0.000000] Extended CMOS year: 2000
> [ 0.000000] Console: colour VGA+ 80x25
> [ 0.000000] console [tty0] enabled
> [ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
> [ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8
> [ 0.000000] ... MAX_LOCK_DEPTH: 48
> [ 0.000000] ... MAX_LOCKDEP_KEYS: 8191
> [ 0.000000] ... CLASSHASH_SIZE: 4096
> [ 0.000000] ... MAX_LOCKDEP_ENTRIES: 16384
> [ 0.000000] ... MAX_LOCKDEP_CHAINS: 32768
> [ 0.000000] ... CHAINHASH_SIZE: 16384
> [ 0.000000] memory used by lock dependency info: 6335 kB
> [ 0.000000] per task-struct memory footprint: 2688 bytes
> [ 0.000000] allocated 33554432 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.003333] Detected 2508.599 MHz processor.
> [ 0.000002] Calibrating delay loop (skipped), value calculated using timer frequency.. 5019.24 BogoMIPS (lpj=8361996)
> [ 0.000194] pid_max: default: 32768 minimum: 301
> [ 0.000358] Mount-cache hash table entries: 256
> [ 0.000849] Initializing cgroup subsys debug
> [ 0.000943] Initializing cgroup subsys cpuacct
> [ 0.001042] Initializing cgroup subsys memory
> [ 0.001174] Initializing cgroup subsys devices
> [ 0.001269] Initializing cgroup subsys freezer
> [ 0.001363] Initializing cgroup subsys blkio
> [ 0.001464] Initializing cgroup subsys perf_event
> [ 0.001606] CPU: Physical Processor ID: 0
> [ 0.001699] CPU: Processor Core ID: 0
> [ 0.001794] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
> [ 0.001795] ENERGY_PERF_BIAS: View and update with x86_energy_perf_policy(8)
> [ 0.001989] mce: CPU supports 9 MCE banks
> [ 0.002092] CPU0: Thermal monitoring enabled (TM1)
> [ 0.002199] using mwait in idle threads.
> [ 0.003309] ACPI: Core revision 20110623
> [ 0.016301] ftrace: allocating 23282 entries in 92 pages
> [ 0.025721] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
> [ 0.058807] CPU0: Intel(R) Core(TM) i5-2400S CPU @ 2.50GHz stepping 07
> [ 0.164331] Performance Events: PEBS fmt1+, SandyBridge events, Intel PMU driver.
> [ 0.164592] PEBS disabled due to CPU errata.
> [ 0.164691] ... version: 3
> [ 0.164783] ... bit width: 48
> [ 0.164875] ... generic registers: 8
> [ 0.164967] ... value mask: 0000ffffffffffff
> [ 0.165061] ... max period: 000000007fffffff
> [ 0.165157] ... fixed-purpose events: 3
> [ 0.165248] ... event mask: 00000007000000ff
> [ 0.191178] NMI watchdog enabled, takes one hw-pmu counter.
> [ 0.210999] lockdep: fixing up alternatives.
> [ 0.217703] Booting Node 0, Processors #1
> [ 0.217789] smpboot cpu 1: start_ip = 98000
> [ 0.331096] NMI watchdog enabled, takes one hw-pmu counter.
> [ 0.350909] lockdep: fixing up alternatives.
> [ 0.351043] #2
> [ 0.351085] smpboot cpu 2: start_ip = 98000
> [ 0.464277] NMI watchdog enabled, takes one hw-pmu counter.
> [ 0.484165] lockdep: fixing up alternatives.
> [ 0.484298] #3 Ok.
> [ 0.484387] smpboot cpu 3: start_ip = 98000
> [ 0.597594] NMI watchdog enabled, takes one hw-pmu counter.
> [ 0.604093] Brought up 4 CPUs
> [ 0.604196] Total of 4 processors activated (20075.68 BogoMIPS).
> [ 0.607633] devtmpfs: initialized
> [ 0.608062] PM: Registering ACPI NVS region at cac19000 (376832 bytes)
> [ 0.608167] PM: Registering ACPI NVS region at cada8000 (69632 bytes)
> [ 0.608265] PM: Registering ACPI NVS region at cadd2000 (4096 bytes)
> [ 0.609680] PM: Registering ACPI NVS region at caddb000 (40960 bytes)
> [ 0.609778] PM: Registering ACPI NVS region at cae41000 (274432 bytes)
> [ 0.610884] NET: Registered protocol family 16
> [ 0.611801] ACPI: bus type pci registered
> [ 0.612065] PCI: MMCONFIG for domain 0000 [bus 00-3f] at [mem 0xe0000000-0xe3ffffff] (base 0xe0000000)
> [ 0.612214] PCI: not using MMCONFIG
> [ 0.612305] PCI: Using configuration type 1 for base access
> [ 0.625738] bio: create slab <bio-0> at 0
> [ 0.626042] ACPI: Added _OSI(Module Device)
> [ 0.626136] ACPI: Added _OSI(Processor Device)
> [ 0.626230] ACPI: Added _OSI(3.0 _SCP Extensions)
> [ 0.626324] ACPI: Added _OSI(Processor Aggregator Device)
> [ 0.633898] ACPI: EC: Look up EC in DSDT
> [ 0.641821] ACPI: Executed 1 blocks of module-level executable AML code
> [ 0.646493] ACPI Error: [RAMB] Namespace lookup failure, AE_NOT_FOUND (20110623/psargs-359)
> [ 0.646758] ACPI Exception: AE_NOT_FOUND, Could not execute arguments for [RAMW] (Region) (20110623/nsinit-349)
> [ 0.647299] ACPI: SSDT 00000000caddbc18 0038C (v01 AMI IST 00000001 MSFT 03000001)
> [ 0.649469] ACPI: Dynamic OEM Table Load:
> [ 0.649638] ACPI: SSDT (null) 0038C (v01 AMI IST 00000001 MSFT 03000001)
> [ 0.649887] ACPI: SSDT 00000000caddce18 00084 (v01 AMI CST 00000001 MSFT 03000001)
> [ 0.651996] ACPI: Dynamic OEM Table Load:
> [ 0.652166] ACPI: SSDT (null) 00084 (v01 AMI CST 00000001 MSFT 03000001)
> [ 0.653287] ACPI: Interpreter enabled
> [ 0.653381] ACPI: (supports S0 S1 S3 S4 S5)
> [ 0.653700] ACPI: Using IOAPIC for interrupt routing
> [ 0.653823] PCI: MMCONFIG for domain 0000 [bus 00-3f] at [mem 0xe0000000-0xe3ffffff] (base 0xe0000000)
> [ 0.654060] PCI: MMCONFIG at [mem 0xe0000000-0xe3ffffff] reserved in ACPI motherboard resources
> [ 0.678629] [Firmware Bug]: ACPI: BIOS _OSI(Linux) query ignored
> [ 0.695762] ACPI: No dock devices found.
> [ 0.695857] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
> [ 0.696232] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
> [ 0.696640] pci_root PNP0A08:00: host bridge window [io 0x0000-0x0cf7]
> [ 0.696738] pci_root PNP0A08:00: host bridge window [io 0x0d00-0xffff]
> [ 0.696835] pci_root PNP0A08:00: host bridge window [mem 0x000a0000-0x000bffff]
> [ 0.696980] pci_root PNP0A08:00: host bridge window [mem 0x000c8000-0x000dffff]
> [ 0.697124] pci_root PNP0A08:00: host bridge window [mem 0xcfa00000-0xffffffff]
> [ 0.697273] pci_root PNP0A08:00: ignoring host bridge window [mem 0x000c8000-0x000dffff] (conflicts with Video ROM [mem 0x000c0000-0x000cdfff])
> [ 0.697458] pci 0000:00:00.0: [8086:0100] type 0 class 0x000600
> [ 0.697541] pci 0000:00:01.0: [8086:0101] type 1 class 0x000604
> [ 0.697601] pci 0000:00:01.0: PME# supported from D0 D3hot D3cold
> [ 0.697615] pci 0000:00:01.0: PME# disabled
> [ 0.697657] pci 0000:00:02.0: [8086:0102] type 0 class 0x000300
> [ 0.697681] pci 0000:00:02.0: reg 10: [mem 0xfe000000-0xfe3fffff 64bit]
> [ 0.697694] pci 0000:00:02.0: reg 18: [mem 0xd0000000-0xdfffffff 64bit pref]
> [ 0.697703] pci 0000:00:02.0: reg 20: [io 0xf000-0xf03f]
> [ 0.697821] pci 0000:00:16.0: [8086:1c3a] type 0 class 0x000780
> [ 0.697866] pci 0000:00:16.0: reg 10: [mem 0xfe608000-0xfe60800f 64bit]
> [ 0.697989] pci 0000:00:16.0: PME# supported from D0 D3hot D3cold
> [ 0.697996] pci 0000:00:16.0: PME# disabled
> [ 0.698062] pci 0000:00:1a.0: [8086:1c2d] type 0 class 0x000c03
> [ 0.698103] pci 0000:00:1a.0: reg 10: [mem 0xfe607000-0xfe6073ff]
> [ 0.698251] pci 0000:00:1a.0: PME# supported from D0 D3hot D3cold
> [ 0.698258] pci 0000:00:1a.0: PME# disabled
> [ 0.698305] pci 0000:00:1b.0: [8086:1c20] type 0 class 0x000403
> [ 0.698336] pci 0000:00:1b.0: reg 10: [mem 0xfe600000-0xfe603fff 64bit]
> [ 0.698456] pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold
> [ 0.698462] pci 0000:00:1b.0: PME# disabled
> [ 0.698503] pci 0000:00:1c.0: [8086:1c10] type 1 class 0x000604
> [ 0.698632] pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold
> [ 0.698639] pci 0000:00:1c.0: PME# disabled
> [ 0.698687] pci 0000:00:1c.2: [8086:1c14] type 1 class 0x000604
> [ 0.698816] pci 0000:00:1c.2: PME# supported from D0 D3hot D3cold
> [ 0.698822] pci 0000:00:1c.2: PME# disabled
> [ 0.698869] pci 0000:00:1c.3: [8086:1c16] type 1 class 0x000604
> [ 0.698999] pci 0000:00:1c.3: PME# supported from D0 D3hot D3cold
> [ 0.699005] pci 0000:00:1c.3: PME# disabled
> [ 0.699053] pci 0000:00:1c.5: [8086:1c1a] type 1 class 0x000604
> [ 0.699182] pci 0000:00:1c.5: PME# supported from D0 D3hot D3cold
> [ 0.699189] pci 0000:00:1c.5: PME# disabled
> [ 0.699234] pci 0000:00:1c.6: [8086:1c1c] type 1 class 0x000604
> [ 0.699364] pci 0000:00:1c.6: PME# supported from D0 D3hot D3cold
> [ 0.699371] pci 0000:00:1c.6: PME# disabled
> [ 0.699416] pci 0000:00:1c.7: [8086:244e] type 1 class 0x000604
> [ 0.699546] pci 0000:00:1c.7: PME# supported from D0 D3hot D3cold
> [ 0.699552] pci 0000:00:1c.7: PME# disabled
> [ 0.699604] pci 0000:00:1d.0: [8086:1c26] type 0 class 0x000c03
> [ 0.699645] pci 0000:00:1d.0: reg 10: [mem 0xfe606000-0xfe6063ff]
> [ 0.699793] pci 0000:00:1d.0: PME# supported from D0 D3hot D3cold
> [ 0.699800] pci 0000:00:1d.0: PME# disabled
> [ 0.699842] pci 0000:00:1f.0: [8086:1c4a] type 0 class 0x000601
> [ 0.700057] pci 0000:00:1f.2: [8086:1c02] type 0 class 0x000106
> [ 0.700098] pci 0000:00:1f.2: reg 10: [io 0xf0b0-0xf0b7]
> [ 0.700114] pci 0000:00:1f.2: reg 14: [io 0xf0a0-0xf0a3]
> [ 0.700130] pci 0000:00:1f.2: reg 18: [io 0xf090-0xf097]
> [ 0.700146] pci 0000:00:1f.2: reg 1c: [io 0xf080-0xf083]
> [ 0.700162] pci 0000:00:1f.2: reg 20: [io 0xf060-0xf07f]
> [ 0.700179] pci 0000:00:1f.2: reg 24: [mem 0xfe605000-0xfe6057ff]
> [ 0.700254] pci 0000:00:1f.2: PME# supported from D3hot
> [ 0.700260] pci 0000:00:1f.2: PME# disabled
> [ 0.700293] pci 0000:00:1f.3: [8086:1c22] type 0 class 0x000c05
> [ 0.700324] pci 0000:00:1f.3: reg 10: [mem 0xfe604000-0xfe6040ff 64bit]
> [ 0.700370] pci 0000:00:1f.3: reg 20: [io 0xf040-0xf05f]
> [ 0.700475] pci 0000:00:01.0: PCI bridge to [bus 01-01]
> [ 0.700668] pci 0000:00:1c.0: PCI bridge to [bus 02-02]
> [ 0.700878] pci 0000:00:1c.2: PCI bridge to [bus 03-03]
> [ 0.701080] pci 0000:00:1c.3: PCI bridge to [bus 04-04]
> [ 0.701323] pci 0000:05:00.0: [1b21:1042] type 0 class 0x000c03
> [ 0.701369] pci 0000:05:00.0: reg 10: [mem 0xfe500000-0xfe507fff 64bit]
> [ 0.701569] pci 0000:05:00.0: PME# supported from D3hot D3cold
> [ 0.701578] pci 0000:05:00.0: PME# disabled
> [ 0.701668] pci 0000:00:1c.5: PCI bridge to [bus 05-05]
> [ 0.701772] pci 0000:00:1c.5: bridge window [mem 0xfe500000-0xfe5fffff]
> [ 0.701908] pci 0000:06:00.0: [1969:1083] type 0 class 0x000200
> [ 0.701952] pci 0000:06:00.0: reg 10: [mem 0xfe400000-0xfe43ffff 64bit]
> [ 0.701975] pci 0000:06:00.0: reg 18: [io 0xe000-0xe07f]
> [ 0.702132] pci 0000:06:00.0: PME# supported from D0 D1 D2 D3hot D3cold
> [ 0.702141] pci 0000:06:00.0: PME# disabled
> [ 0.707396] pci 0000:00:1c.6: PCI bridge to [bus 06-06]
> [ 0.707505] pci 0000:00:1c.6: bridge window [io 0xe000-0xefff]
> [ 0.707512] pci 0000:00:1c.6: bridge window [mem 0xfe400000-0xfe4fffff]
> [ 0.707634] pci 0000:07:00.0: [1b21:1080] type 1 class 0x000604
> [ 0.707804] pci 0000:00:1c.7: PCI bridge to [bus 07-08] (subtractive decode)
> [ 0.707920] pci 0000:00:1c.7: bridge window [io 0x0000-0x0cf7] (subtractive decode)
> [ 0.707923] pci 0000:00:1c.7: bridge window [io 0x0d00-0xffff] (subtractive decode)
> [ 0.707925] pci 0000:00:1c.7: bridge window [mem 0x000a0000-0x000bffff] (subtractive decode)
> [ 0.707927] pci 0000:00:1c.7: bridge window [mem 0xcfa00000-0xffffffff] (subtractive decode)
> [ 0.708112] pci 0000:07:00.0: PCI bridge to [bus 08-08] (subtractive decode)
> [ 0.708243] pci 0000:07:00.0: bridge window [??? 0x00000000 flags 0x0] (subtractive decode)
> [ 0.708246] pci 0000:07:00.0: bridge window [??? 0x00000000 flags 0x0] (subtractive decode)
> [ 0.708248] pci 0000:07:00.0: bridge window [??? 0x00000000 flags 0x0] (subtractive decode)
> [ 0.708250] pci 0000:07:00.0: bridge window [??? 0x00000000 flags 0x0] (subtractive decode)
> [ 0.708253] pci 0000:07:00.0: bridge window [io 0x0000-0x0cf7] (subtractive decode)
> [ 0.708255] pci 0000:07:00.0: bridge window [io 0x0d00-0xffff] (subtractive decode)
> [ 0.708257] pci 0000:07:00.0: bridge window [mem 0x000a0000-0x000bffff] (subtractive decode)
> [ 0.708259] pci 0000:07:00.0: bridge window [mem 0xcfa00000-0xffffffff] (subtractive decode)
> [ 0.708337] pci_bus 0000:00: on NUMA node 0
> [ 0.708341] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
> [ 0.708530] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P1._PRT]
> [ 0.708581] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEX0._PRT]
> [ 0.708631] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEX2._PRT]
> [ 0.708674] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEX3._PRT]
> [ 0.708723] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEX5._PRT]
> [ 0.708766] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEX6._PRT]
> [ 0.708823] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEX7.BR19._PRT]
> [ 0.709010] pci0000:00: Requesting ACPI _OSC control (0x1d)
> [ 0.709346] pci0000:00: ACPI _OSC control (0x1c) granted
> [ 0.720056] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 10 *11 12 14 15)
> [ 0.720685] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 *7 10 11 12 14 15)
> [ 0.721304] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 10 *11 12 14 15)
> [ 0.721880] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 *10 11 12 14 15)
> [ 0.722455] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 *5 6 7 10 11 12 14 15)
> [ 0.723072] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 *10 11 12 14 15)
> [ 0.723686] ACPI: PCI Interrupt Link [LNKG] (IRQs *3 4 5 6 7 10 11 12 14 15)
> [ 0.724310] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 *5 6 7 10 11 12 14 15)
> [ 0.725199] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
> [ 0.725373] vgaarb: loaded
> [ 0.725463] vgaarb: bridge control possible 0000:00:02.0
> [ 0.725769] SCSI subsystem initialized
> [ 0.726053] libata version 3.00 loaded.
> [ 0.726191] usbcore: registered new interface driver usbfs
> [ 0.726355] usbcore: registered new interface driver hub
> [ 0.726531] usbcore: registered new device driver usb
> [ 0.726981] Advanced Linux Sound Architecture Driver Version 1.0.24.
> [ 0.727078] PCI: Using ACPI for IRQ routing
> [ 0.730105] PCI: pci_cache_line_size set to 64 bytes
> [ 0.730271] reserve RAM buffer: 000000000009d800 - 000000000009ffff
> [ 0.730274] reserve RAM buffer: 00000000cac19000 - 00000000cbffffff
> [ 0.730279] reserve RAM buffer: 00000000cadd2000 - 00000000cbffffff
> [ 0.730282] reserve RAM buffer: 00000000cb000000 - 00000000cbffffff
> [ 0.730285] reserve RAM buffer: 000000012fe00000 - 000000012fffffff
> [ 0.730651] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0
> [ 0.731102] hpet0: 8 comparators, 64-bit 14.318180 MHz counter
> [ 0.733239] Switching to clocksource hpet
> [ 0.760667] pnp: PnP ACPI init
> [ 0.760780] ACPI: bus type pnp registered
> [ 0.761034] pnp 00:00: [bus 00-ff]
> [ 0.761037] pnp 00:00: [io 0x0cf8-0x0cff]
> [ 0.761039] pnp 00:00: [io 0x0000-0x0cf7 window]
> [ 0.761041] pnp 00:00: [io 0x0d00-0xffff window]
> [ 0.761043] pnp 00:00: [mem 0x000a0000-0x000bffff window]
> [ 0.761045] pnp 00:00: [mem 0x000c8000-0x000dffff window]
> [ 0.761047] pnp 00:00: [mem 0xcfa00000-0xffffffff window]
> [ 0.761049] pnp 00:00: [mem 0x00000000 window]
> [ 0.761261] pnp 00:00: Plug and Play ACPI device, IDs PNP0a08 PNP0a03 (active)
> [ 0.761338] pnp 00:01: [mem 0xfed10000-0xfed19fff]
> [ 0.761340] pnp 00:01: [mem 0xe0000000-0xe3ffffff]
> [ 0.761342] pnp 00:01: [mem 0xfed90000-0xfed93fff]
> [ 0.761343] pnp 00:01: [mem 0xfed20000-0xfed3ffff]
> [ 0.761345] pnp 00:01: [mem 0xfee00000-0xfee0ffff]
> [ 0.761491] system 00:01: [mem 0xfed10000-0xfed19fff] has been reserved
> [ 0.761590] system 00:01: [mem 0xe0000000-0xe3ffffff] has been reserved
> [ 0.761689] system 00:01: [mem 0xfed90000-0xfed93fff] has been reserved
> [ 0.761786] system 00:01: [mem 0xfed20000-0xfed3ffff] has been reserved
> [ 0.761885] system 00:01: [mem 0xfee00000-0xfee0ffff] has been reserved
> [ 0.761985] system 00:01: Plug and Play ACPI device, IDs PNP0c01 (active)
> [ 0.762059] pnp 00:02: [io 0x0000-0xffffffffffffffff disabled]
> [ 0.762061] pnp 00:02: [io 0x0000-0xffffffffffffffff disabled]
> [ 0.762063] pnp 00:02: [io 0x0290-0x029f]
> [ 0.762065] pnp 00:02: [io 0x0000-0xffffffffffffffff disabled]
> [ 0.762205] system 00:02: [io 0x0290-0x029f] has been reserved
> [ 0.762303] system 00:02: Plug and Play ACPI device, IDs PNP0c02 (active)
> [ 0.762471] pnp 00:03: [dma 4]
> [ 0.762473] pnp 00:03: [io 0x0000-0x000f]
> [ 0.762475] pnp 00:03: [io 0x0081-0x0083]
> [ 0.762476] pnp 00:03: [io 0x0087]
> [ 0.762478] pnp 00:03: [io 0x0089-0x008b]
> [ 0.762480] pnp 00:03: [io 0x008f]
> [ 0.762481] pnp 00:03: [io 0x00c0-0x00df]
> [ 0.762582] pnp 00:03: Plug and Play ACPI device, IDs PNP0200 (active)
> [ 0.762601] pnp 00:04: [io 0x0070-0x0071]
> [ 0.762614] pnp 00:04: [irq 8]
> [ 0.762716] pnp 00:04: Plug and Play ACPI device, IDs PNP0b00 (active)
> [ 0.762730] pnp 00:05: [io 0x0061]
> [ 0.762831] pnp 00:05: Plug and Play ACPI device, IDs PNP0800 (active)
> [ 0.762856] pnp 00:06: [io 0x0010-0x001f]
> [ 0.762858] pnp 00:06: [io 0x0022-0x003f]
> [ 0.762861] pnp 00:06: [io 0x0044-0x005f]
> [ 0.762862] pnp 00:06: [io 0x0063]
> [ 0.762864] pnp 00:06: [io 0x0065]
> [ 0.762865] pnp 00:06: [io 0x0067-0x006f]
> [ 0.762867] pnp 00:06: [io 0x0072-0x007f]
> [ 0.762869] pnp 00:06: [io 0x0080]
> [ 0.762871] pnp 00:06: [io 0x0084-0x0086]
> [ 0.762872] pnp 00:06: [io 0x0088]
> [ 0.762874] pnp 00:06: [io 0x008c-0x008e]
> [ 0.762875] pnp 00:06: [io 0x0090-0x009f]
> [ 0.762877] pnp 00:06: [io 0x00a2-0x00bf]
> [ 0.762878] pnp 00:06: [io 0x00e0-0x00ef]
> [ 0.762880] pnp 00:06: [io 0x04d0-0x04d1]
> [ 0.763026] system 00:06: [io 0x04d0-0x04d1] has been reserved
> [ 0.763126] system 00:06: Plug and Play ACPI device, IDs PNP0c02 (active)
> [ 0.763141] pnp 00:07: [io 0x00f0-0x00ff]
> [ 0.763149] pnp 00:07: [irq 13]
> [ 0.763253] pnp 00:07: Plug and Play ACPI device, IDs PNP0c04 (active)
> [ 0.763548] pnp 00:08: [io 0x03f8-0x03ff]
> [ 0.763556] pnp 00:08: [irq 4]
> [ 0.763558] pnp 00:08: [dma 0 disabled]
> [ 0.763705] pnp 00:08: Plug and Play ACPI device, IDs PNP0501 (active)
> [ 0.763738] pnp 00:09: [io 0x0060]
> [ 0.763740] pnp 00:09: [io 0x0064]
> [ 0.763748] pnp 00:09: [irq 1]
> [ 0.763869] pnp 00:09: Plug and Play ACPI device, IDs PNP0303 PNP030b (active)
> [ 0.764028] pnp 00:0a: [io 0x0400-0x0453]
> [ 0.764030] pnp 00:0a: [io 0x0458-0x047f]
> [ 0.764032] pnp 00:0a: [io 0x0000-0xffffffffffffffff disabled]
> [ 0.764034] pnp 00:0a: [io 0x0500-0x057f]
> [ 0.764036] pnp 00:0a: [mem 0xfed1c000-0xfed1ffff]
> [ 0.764038] pnp 00:0a: [mem 0xfec00000-0xfecfffff]
> [ 0.764040] pnp 00:0a: [mem 0xfed08000-0xfed08fff]
> [ 0.764042] pnp 00:0a: [mem 0xff000000-0xffffffff]
> [ 0.764194] system 00:0a: [io 0x0400-0x0453] has been reserved
> [ 0.764292] system 00:0a: [io 0x0458-0x047f] has been reserved
> [ 0.764389] system 00:0a: [io 0x0500-0x057f] has been reserved
> [ 0.764486] system 00:0a: [mem 0xfed1c000-0xfed1ffff] has been reserved
> [ 0.764584] system 00:0a: [mem 0xfec00000-0xfecfffff] could not be reserved
> [ 0.764683] system 00:0a: [mem 0xfed08000-0xfed08fff] has been reserved
> [ 0.764781] system 00:0a: [mem 0xff000000-0xffffffff] has been reserved
> [ 0.764880] system 00:0a: Plug and Play ACPI device, IDs PNP0c01 (active)
> [ 0.764932] pnp 00:0b: [io 0x0454-0x0457]
> [ 0.765082] system 00:0b: [io 0x0454-0x0457] has been reserved
> [ 0.765180] system 00:0b: Plug and Play ACPI device, IDs INT3f0d PNP0c02 (active)
> [ 0.765492] pnp 00:0c: [mem 0xfed00000-0xfed003ff]
> [ 0.765657] pnp 00:0c: Plug and Play ACPI device, IDs PNP0103 (active)
> [ 0.765918] pnp: PnP ACPI: found 13 devices
> [ 0.766011] ACPI: ACPI bus type pnp unregistered
> [ 0.778953] PCI: max bus depth: 2 pci_try_num: 3
> [ 0.779066] pci 0000:00:01.0: PCI bridge to [bus 01-01]
> [ 0.779170] pci 0000:00:1c.0: PCI bridge to [bus 02-02]
> [ 0.779284] pci 0000:00:1c.2: PCI bridge to [bus 03-03]
> [ 0.779398] pci 0000:00:1c.3: PCI bridge to [bus 04-04]
> [ 0.779512] pci 0000:00:1c.5: PCI bridge to [bus 05-05]
> [ 0.779614] pci 0000:00:1c.5: bridge window [mem 0xfe500000-0xfe5fffff]
> [ 0.779725] pci 0000:00:1c.6: PCI bridge to [bus 06-06]
> [ 0.779821] pci 0000:00:1c.6: bridge window [io 0xe000-0xefff]
> [ 0.779924] pci 0000:00:1c.6: bridge window [mem 0xfe400000-0xfe4fffff]
> [ 0.780044] pci 0000:07:00.0: PCI bridge to [bus 08-08]
> [ 0.780168] pci 0000:00:1c.7: PCI bridge to [bus 07-08]
> [ 0.780297] pci 0000:00:01.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
> [ 0.780398] pci 0000:00:01.0: setting latency timer to 64
> [ 0.780415] pci 0000:00:1c.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
> [ 0.780517] pci 0000:00:1c.0: setting latency timer to 64
> [ 0.780534] pci 0000:00:1c.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
> [ 0.780635] pci 0000:00:1c.2: setting latency timer to 64
> [ 0.780653] pci 0000:00:1c.3: PCI INT D -> GSI 19 (level, low) -> IRQ 19
> [ 0.780755] pci 0000:00:1c.3: setting latency timer to 64
> [ 0.780766] pci 0000:00:1c.5: PCI INT B -> GSI 16 (level, low) -> IRQ 16
> [ 0.780867] pci 0000:00:1c.5: setting latency timer to 64
> [ 0.780878] pci 0000:00:1c.6: PCI INT C -> GSI 18 (level, low) -> IRQ 18
> [ 0.780980] pci 0000:00:1c.6: setting latency timer to 64
> [ 0.780991] pci 0000:00:1c.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19
> [ 0.781093] pci 0000:00:1c.7: setting latency timer to 64
> [ 0.781104] pci 0000:07:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
> [ 0.781207] pci 0000:07:00.0: setting latency timer to 64
> [ 0.781213] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7]
> [ 0.781215] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff]
> [ 0.781217] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff]
> [ 0.781219] pci_bus 0000:00: resource 7 [mem 0xcfa00000-0xffffffff]
> [ 0.781222] pci_bus 0000:05: resource 1 [mem 0xfe500000-0xfe5fffff]
> [ 0.781224] pci_bus 0000:06: resource 0 [io 0xe000-0xefff]
> [ 0.781226] pci_bus 0000:06: resource 1 [mem 0xfe400000-0xfe4fffff]
> [ 0.781228] pci_bus 0000:07: resource 4 [io 0x0000-0x0cf7]
> [ 0.781230] pci_bus 0000:07: resource 5 [io 0x0d00-0xffff]
> [ 0.781232] pci_bus 0000:07: resource 6 [mem 0x000a0000-0x000bffff]
> [ 0.781234] pci_bus 0000:07: resource 7 [mem 0xcfa00000-0xffffffff]
> [ 0.781236] pci_bus 0000:08: resource 8 [io 0x0000-0x0cf7]
> [ 0.781238] pci_bus 0000:08: resource 9 [io 0x0d00-0xffff]
> [ 0.781240] pci_bus 0000:08: resource 10 [mem 0x000a0000-0x000bffff]
> [ 0.781242] pci_bus 0000:08: resource 11 [mem 0xcfa00000-0xffffffff]
> [ 0.781306] NET: Registered protocol family 2
> [ 0.781585] IP route cache hash table entries: 131072 (order: 8, 1048576 bytes)
> [ 0.782322] TCP established hash table entries: 262144 (order: 10, 4194304 bytes)
> [ 0.783096] TCP bind hash table entries: 32768 (order: 9, 2621440 bytes)
> [ 0.784483] TCP: Hash tables configured (established 262144 bind 32768)
> [ 0.784596] TCP reno registered
> [ 0.784694] UDP hash table entries: 2048 (order: 6, 393216 bytes)
> [ 0.784963] UDP-Lite hash table entries: 2048 (order: 6, 393216 bytes)
> [ 0.785348] NET: Registered protocol family 1
> [ 0.785690] RPC: Registered named UNIX socket transport module.
> [ 0.785787] RPC: Registered udp transport module.
> [ 0.785881] RPC: Registered tcp transport module.
> [ 0.785974] RPC: Registered tcp NFSv4.1 backchannel transport module.
> [ 0.786086] pci 0000:00:02.0: Boot video device
> [ 1.033346] PCI: CLS 64 bytes, default 64
> [ 1.033453] Unpacking initramfs...
> [ 1.084553] Freeing initrd memory: 2600k freed
> [ 1.084917] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
> [ 1.085016] Placing 64MB software IO TLB between ffff8800c6c19000 - ffff8800cac19000
> [ 1.085162] software IO TLB at phys 0xc6c19000 - 0xcac19000
> [ 1.091666] alg: No test for __gcm-aes-aesni (__driver-gcm-aes-aesni)
> [ 1.094579] sha1_ssse3: Using AVX optimized SHA-1 implementation
> [ 1.109313] Installing knfsd (copyright (C) 1996 okir@xxxxxxxxxxxx).
> [ 1.109775] fuse init (API version 7.17)
> [ 1.110308] msgmni has been set to 7686
> [ 1.111335] alg: No test for stdrng (krng)
> [ 1.111450] io scheduler noop registered
> [ 1.111543] io scheduler deadline registered
> [ 1.111744] io scheduler cfq registered (default)
> [ 1.111844] start plist test
> [ 1.113315] end plist test
> [ 1.113634] pcieport 0000:00:01.0: setting latency timer to 64
> [ 1.113691] pcieport 0000:00:01.0: irq 40 for MSI/MSI-X
> [ 1.113894] pcieport 0000:00:1c.0: setting latency timer to 64
> [ 1.113979] pcieport 0000:00:1c.0: irq 41 for MSI/MSI-X
> [ 1.114152] pcieport 0000:00:1c.2: setting latency timer to 64
> [ 1.114236] pcieport 0000:00:1c.2: irq 42 for MSI/MSI-X
> [ 1.114408] pcieport 0000:00:1c.3: setting latency timer to 64
> [ 1.114492] pcieport 0000:00:1c.3: irq 43 for MSI/MSI-X
> [ 1.114666] pcieport 0000:00:1c.5: setting latency timer to 64
> [ 1.114750] pcieport 0000:00:1c.5: irq 44 for MSI/MSI-X
> [ 1.114922] pcieport 0000:00:1c.6: setting latency timer to 64
> [ 1.115006] pcieport 0000:00:1c.6: irq 45 for MSI/MSI-X
> [ 1.115850] intel_idle: MWAIT substates: 0x1120
> [ 1.115851] intel_idle: v0.4 model 0x2A
> [ 1.115853] intel_idle: lapic_timer_reliable_states 0xffffffff
> [ 1.116270] input: Power Button as /devices/LNXSYSTM:00/device:00/PNP0C0C:00/input/input0
> [ 1.116432] ACPI: Power Button [PWRB]
> [ 1.116751] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1
> [ 1.116904] ACPI: Power Button [PWRF]
> [ 1.170194] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
> [ 1.190948] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
> [ 1.424279] 00:08: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
> [ 1.460218] Real Time Clock Driver v1.12b
> [ 1.460716] Non-volatile memory driver v1.3
> [ 1.460847] Linux agpgart interface v0.103
> [ 1.461258] agpgart-intel 0000:00:00.0: Intel Sandybridge Chipset
> [ 1.461564] agpgart-intel 0000:00:00.0: detected gtt size: 2097152K total, 262144K mappable
> [ 1.463159] agpgart-intel 0000:00:00.0: detected 65536K stolen memory
> [ 1.463465] agpgart-intel 0000:00:00.0: AGP aperture is 256M @ 0xd0000000
> [ 1.463652] Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, margin is 60 seconds).
> [ 1.463800] Hangcheck: Using getrawmonotonic().
> [ 1.463983] [drm] Initialized drm 1.1.0 20060810
> [ 1.464101] i915 0000:00:02.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
> [ 1.464200] i915 0000:00:02.0: setting latency timer to 64
> [ 1.589530] mtrr: type mismatch for d0000000,10000000 old: write-back new: write-combining
> [ 1.589695] [drm] MTRR allocation failed. Graphics performance may suffer.
> [ 1.591049] i915 0000:00:02.0: irq 46 for MSI/MSI-X
> [ 1.591056] [drm] Supports vblank timestamp caching Rev 1 (10.10.2010).
> [ 1.591155] [drm] Driver supports precise vblank timestamp query.
> [ 1.591319] vgaarb: device changed decodes: PCI:0000:00:02.0,olddecodes=io+mem,decodes=io+mem:owns=io+mem
> [ 1.904432] fbcon: inteldrmfb (fb0) is primary device
> [ 2.079426] Console: switching to colour frame buffer device 240x75
> [ 2.085905] fb0: inteldrmfb frame buffer device
> [ 2.085931] drm: registered panic notifier
> [ 2.086015] Refined TSC clocksource calibration: 2508.443 MHz.
> [ 2.086050] Switching to clocksource tsc
> [ 2.087658] fixme: max PWM is zero.
> [ 2.088006] acpi device:33: registered as cooling_device4
> [ 2.088411] input: Video Bus as /devices/LNXSYSTM:00/device:00/PNP0A08:00/LNXVIDEO:00/input/input2
> [ 2.088465] ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no)
> [ 2.088590] [drm] Initialized i915 1.6.0 20080730 for 0000:00:02.0 on minor 0
> [ 2.090880] loop: module loaded
> [ 2.091276] ahci 0000:00:1f.2: version 3.0
> [ 2.091301] ahci 0000:00:1f.2: PCI INT B -> GSI 20 (level, low) -> IRQ 20
> [ 2.091405] ahci 0000:00:1f.2: irq 47 for MSI/MSI-X
> [ 2.102717] ahci 0000:00:1f.2: AHCI 0001.0300 32 slots 6 ports 6 Gbps 0x3f impl SATA mode
> [ 2.102763] ahci 0000:00:1f.2: flags: 64bit ncq sntf pm led clo pio slum part ems apst
> [ 2.102810] ahci 0000:00:1f.2: setting latency timer to 64
> [ 2.137648] scsi0 : ahci
> [ 2.137903] scsi1 : ahci
> [ 2.138113] scsi2 : ahci
> [ 2.138321] scsi3 : ahci
> [ 2.138529] scsi4 : ahci
> [ 2.138750] scsi5 : ahci
> [ 2.139185] ata1: SATA max UDMA/133 abar m2048@0xfe605000 port 0xfe605100 irq 47
> [ 2.139226] ata2: SATA max UDMA/133 abar m2048@0xfe605000 port 0xfe605180 irq 47
> [ 2.139266] ata3: FORCE: PHY spd limit set to 1.5Gbps
> [ 2.139301] ata3: SATA max UDMA/133 abar m2048@0xfe605000 port 0xfe605200 irq 47
> [ 2.139341] ata4: SATA max UDMA/133 abar m2048@0xfe605000 port 0xfe605280 irq 47
> [ 2.139381] ata5: SATA max UDMA/133 abar m2048@0xfe605000 port 0xfe605300 irq 47
> [ 2.139421] ata6: SATA max UDMA/133 abar m2048@0xfe605000 port 0xfe605380 irq 47
> [ 2.139889] tun: Universal TUN/TAP device driver, 1.6
> [ 2.139919] tun: (C) 1999-2004 Max Krasnyansky <maxk@xxxxxxxxxxxx>
> [ 2.140160] atl1c 0000:06:00.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18
> [ 2.140206] atl1c 0000:06:00.0: setting latency timer to 64
> [ 2.256845] atl1c 0000:06:00.0: version 1.0.1.0-NAPI
> [ 2.257271] usbcore: registered new interface driver uas
> [ 2.257301] Initializing USB Mass Storage driver...
> [ 2.257396] usbcore: registered new interface driver usb-storage
> [ 2.257429] USB Mass Storage support registered.
> [ 2.257612] i8042: PNP: PS/2 Controller [PNP0303:PS2K] at 0x60,0x64 irq 1
> [ 2.257648] i8042: PNP: PS/2 appears to have AUX port disabled, if this is incorrect please boot with i8042.nopnp
> [ 2.258428] serio: i8042 KBD port at 0x60,0x64 irq 1
> [ 2.258770] mousedev: PS/2 mouse device common for all mice
> [ 2.259393] i2c /dev entries driver
> [ 2.261536] w83627ehf: Found NCT6776F chip at 0x290
> [ 2.262697] device-mapper: uevent: version 1.0.3
> [ 2.262942] device-mapper: ioctl: 4.22.0-ioctl (2011-10-19) initialised: dm-devel@xxxxxxxxxx
> [ 2.264016] cpuidle: using governor ladder
> [ 2.265949] cpuidle: using governor menu
> [ 2.267050] EFI Variables Facility v0.08 2004-May-17
> [ 2.269907] usbcore: registered new interface driver usbhid
> [ 2.269940] usbhid: USB HID core driver
> [ 2.272249] snd_hda_intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
> [ 2.272378] snd_hda_intel 0000:00:1b.0: irq 48 for MSI/MSI-X
> [ 2.272428] snd_hda_intel 0000:00:1b.0: setting latency timer to 64
> [ 2.280315] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3
> [ 2.459173] ata5: SATA link down (SStatus 0 SControl 300)
> [ 2.460954] ata6: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> [ 2.462731] ata4: SATA link down (SStatus 0 SControl 300)
> [ 2.464500] ata3: SATA link down (SStatus 0 SControl 310)
> [ 2.466267] ata2: SATA link down (SStatus 0 SControl 300)
> [ 2.467999] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> [ 2.469756] ata6.00: ATAPI: HL-DT-STDVD-RAM GH22LS30, 1.00, max UDMA/100
> [ 2.471543] ata1.00: HPA detected: current 976771055, native 976773168
> [ 2.473310] ata1.00: ATA-8: WDC WD5000AACS-00G8B0, 05.04C05, max UDMA/133
> [ 2.474985] ata1.00: 976771055 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
> [ 2.476716] ata6.00: configured for UDMA/100
> [ 2.479182] ata1.00: configured for UDMA/133
> [ 2.481137] scsi 0:0:0:0: Direct-Access ATA WDC WD5000AACS-0 05.0 PQ: 0 ANSI: 5
> [ 2.483596] sd 0:0:0:0: [sda] 976771055 512-byte logical blocks: (500 GB/465 GiB)
> [ 2.484072] sd 0:0:0:0: Attached scsi generic sg0 type 0
> [ 2.486970] sd 0:0:0:0: [sda] Write Protect is off
> [ 2.488088] scsi 5:0:0:0: CD-ROM HL-DT-ST DVD-RAM GH22LS30 1.00 PQ: 0 ANSI: 5
> [ 2.490191] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
> [ 2.490230] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
> [ 2.490371] sr0: scsi3-mmc drive: 48x/48x writer dvd-ram cd/rw xa/form2 cdda tray
> [ 2.490373] cdrom: Uniform CD-ROM driver Revision: 3.20
> [ 2.490912] sr 5:0:0:0: Attached scsi CD-ROM sr0
> [ 2.491233] sr 5:0:0:0: Attached scsi generic sg1 type 5
> [ 2.518374] sda: sda1 sda2
> [ 2.521176] sd 0:0:0:0: [sda] Attached SCSI disk
> [ 2.805813] hda_codec: ALC887-VD: BIOS auto-probing.
> [ 2.817524] ALSA device list:
> [ 2.819101] #0: HDA Intel PCH at 0xfe600000 irq 48
> [ 2.820684] Netfilter messages via NETLINK v0.30.
> [ 2.822294] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
> [ 2.824484] ip_set: protocol 6
> [ 2.827712] ip_tables: (C) 2000-2006 Netfilter Core Team
> [ 2.829301] arp_tables: (C) 2002 David S. Miller
> [ 2.830852] TCP cubic registered
> [ 2.832367] NET: Registered protocol family 17
> [ 2.833993] Bridge firewalling registered
> [ 2.835497] Ebtables v2.0 registered
> [ 2.837997] registered taskstats version 1
> [ 2.846356] Freeing unused kernel memory: 2572k freed
> [ 2.848206] Write protecting the kernel read-only data: 10240k
> [ 2.851539] Freeing unused kernel memory: 628k freed
> [ 2.856302] Freeing unused kernel memory: 1300k freed
> [ 2.933381] udevd[1586]: starting version 175
> [ 9.470987] alg: No test for __cbc-aes-aesni (cryptd(__driver-cbc-aes-aesni))
> [ 10.280638] PM: Starting manual resume from disk
> [ 10.312511] kjournald starting. Commit interval 5 seconds
> [ 10.312634] EXT3-fs (dm-1): mounted filesystem with writeback data mode
> [ 11.889493] udevd[1808]: starting version 175
> [ 12.675574] xhci_hcd 0000:05:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
> [ 12.677181] xhci_hcd 0000:05:00.0: setting latency timer to 64
> [ 12.677197] xhci_hcd 0000:05:00.0: xHCI Host Controller
> [ 12.678857] xhci_hcd 0000:05:00.0: new USB bus registered, assigned bus number 1
> [ 12.690012] xhci_hcd 0000:05:00.0: irq 17, io mem 0xfe500000
> [ 12.691726] xhci_hcd 0000:05:00.0: irq 49 for MSI/MSI-X
> [ 12.691735] xhci_hcd 0000:05:00.0: irq 50 for MSI/MSI-X
> [ 12.691744] xhci_hcd 0000:05:00.0: irq 51 for MSI/MSI-X
> [ 12.691753] xhci_hcd 0000:05:00.0: irq 52 for MSI/MSI-X
> [ 12.691762] xhci_hcd 0000:05:00.0: irq 53 for MSI/MSI-X
> [ 12.692166] xHCI xhci_add_endpoint called for root hub
> [ 12.692168] xHCI xhci_check_bandwidth called for root hub
> [ 12.692238] hub 1-0:1.0: USB hub found
> [ 12.693901] hub 1-0:1.0: 2 ports detected
> [ 12.695650] xhci_hcd 0000:05:00.0: xHCI Host Controller
> [ 12.697399] xhci_hcd 0000:05:00.0: new USB bus registered, assigned bus number 2
> [ 12.699186] xHCI xhci_add_endpoint called for root hub
> [ 12.699189] xHCI xhci_check_bandwidth called for root hub
> [ 12.699248] hub 2-0:1.0: USB hub found
> [ 12.700754] hub 2-0:1.0: 2 ports detected
> [ 12.744226] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
> [ 12.745878] ehci_hcd 0000:00:1a.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23
> [ 12.747528] ehci_hcd 0000:00:1a.0: setting latency timer to 64
> [ 12.747533] ehci_hcd 0000:00:1a.0: EHCI Host Controller
> [ 12.749206] ehci_hcd 0000:00:1a.0: new USB bus registered, assigned bus number 3
> [ 12.750812] ehci_hcd 0000:00:1a.0: debug port 2
> [ 12.756241] ehci_hcd 0000:00:1a.0: cache line size of 64 is not supported
> [ 12.756272] ehci_hcd 0000:00:1a.0: irq 23, io mem 0xfe607000
> [ 12.770199] ehci_hcd 0000:00:1a.0: USB 2.0 started, EHCI 1.00
> [ 12.771979] hub 3-0:1.0: USB hub found
> [ 12.773540] hub 3-0:1.0: 2 ports detected
> [ 12.775208] ehci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23
> [ 12.776783] ehci_hcd 0000:00:1d.0: setting latency timer to 64
> [ 12.776787] ehci_hcd 0000:00:1d.0: EHCI Host Controller
> [ 12.778216] ehci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 4
> [ 12.779691] ehci_hcd 0000:00:1d.0: debug port 2
> [ 12.785126] ehci_hcd 0000:00:1d.0: cache line size of 64 is not supported
> [ 12.785134] ehci_hcd 0000:00:1d.0: irq 23, io mem 0xfe606000
> [ 12.800188] ehci_hcd 0000:00:1d.0: USB 2.0 started, EHCI 1.00
> [ 12.801945] hub 4-0:1.0: USB hub found
> [ 12.803475] hub 4-0:1.0: 2 ports detected
> [ 13.080056] usb 3-1: new high-speed USB device number 2 using ehci_hcd
> [ 13.204038] hub 3-1:1.0: USB hub found
> [ 13.205493] hub 3-1:1.0: 6 ports detected
> [ 13.313284] usb 4-1: new high-speed USB device number 2 using ehci_hcd
> [ 13.440822] hub 4-1:1.0: USB hub found
> [ 13.442327] hub 4-1:1.0: 8 ports detected
> [ 13.713135] usb 4-1.5: new low-speed USB device number 3 using ehci_hcd
> [ 13.820487] input: Logitech USB-PS/2 Optical Mouse as /devices/pci0000:00/0000:00:1d.0/usb4/4-1/4-1.5/4-1.5:1.0/input/input4
> [ 13.822399] generic-usb 0003:046D:C050.0001: input,hidraw0: USB HID v1.10 Mouse [Logitech USB-PS/2 Optical Mouse] on usb-0000:00:1d.0-1.5/input0
> [ 15.001424] EXT3-fs (dm-1): using internal journal
> [ 15.758443] Adding 3903484k swap on /dev/mapper/zzz-cswap. Priority:-1 extents:1 across:3903484k
> [ 16.489711] kjournald starting. Commit interval 5 seconds
> [ 16.495099] EXT3-fs (dm-3): using internal journal
> [ 16.497145] EXT3-fs (dm-3): mounted filesystem with writeback data mode
> [ 16.536416] kjournald starting. Commit interval 5 seconds
> [ 16.536846] EXT3-fs (dm-4): using internal journal
> [ 16.536856] EXT3-fs (dm-4): mounted filesystem with writeback data mode
> [ 17.866508] device eth0 entered promiscuous mode
> [ 17.869987] atl1c 0000:06:00.0: irq 54 for MSI/MSI-X
> [ 17.870110] atl1c 0000:06:00.0: atl1c: eth0 NIC Link is Up<1000 Mbps Full Duplex>
> [ 17.874758] device brtap0 entered promiscuous mode
> [ 17.882187] br0: port 1(eth0) entering forwarding state
> [ 17.884413] br0: port 1(eth0) entering forwarding state
> [ 152.502080] br0: port 2(brtap0) entering forwarding state
> [ 152.502093] br0: port 2(brtap0) entering forwarding state
> [ 3202.032203] usb 4-1.2: new high-speed USB device number 4 using ehci_hcd
> [16266.375704] usb 4-1.2: USB disconnect, device number 4
> [16351.507673] PM: Syncing filesystems ... done.
> [16351.630070] Freezing user space processes ... (elapsed 0.03 seconds) done.
> [16351.669142] PM: Preallocating image memory... done (allocated 340490 pages)
> [16351.794992] PM: Allocated 1361960 kbytes in 0.12 seconds (11349.66 MB/s)
> [16351.795020] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
> [16351.806781] Suspending console(s) (use no_console_suspend to debug)
> [16351.807779] sd 0:0:0:0: [sda] Synchronizing SCSI cache
> [16351.808729] i8042 kbd 00:09: wake-up capability enabled by ACPI
> [16351.809156] serial 00:08: disabled
> [16351.809166] serial 00:08: wake-up capability disabled by ACPI
> [16351.809551] snd_hda_intel 0000:00:1b.0: PCI INT A disabled
> [16351.809624] ACPI handle has no context!
> [16351.809650] br0: port 1(eth0) entering forwarding state
> [16351.829675] PM: freeze of devices complete after 22.874 msecs
> [16351.831060] PM: late freeze of devices complete after 1.381 msecs
> [16351.831062] Disabling non-boot CPUs ...
> [16351.834016] kvm: disabling virtualization on CPU1
> [16351.834041] CPU 1 is now offline
> [16351.837527] kvm: disabling virtualization on CPU2
> [16351.837555] CPU 2 is now offline
> [16351.839770] kvm: disabling virtualization on CPU3
> [16351.839815] CPU 3 is now offline
> [16351.839817] lockdep: fixing up alternatives.
> [16351.840469] Extended CMOS year: 2000
> [16351.840580] PM: Creating hibernation image:
> [16351.916977] PM: Need to copy 338911 pages
> [16351.840919] Extended CMOS year: 2000
> [16351.840956] Enabling non-boot CPUs ...
> [16351.847918] lockdep: fixing up alternatives.
> [16351.847925] Booting Node 0 Processor 1 APIC 0x2
> [16351.847927] smpboot cpu 1: start_ip = 98000
> [16351.859046] Calibrating delay loop (skipped) already calibrated this CPU
> [16351.859099] kvm: enabling virtualization on CPU1
> [16351.880239] NMI watchdog enabled, takes one hw-pmu counter.
> [16351.880576] ACPI Error: The DSDT has been corrupted or replaced - old, new headers below (20110623/tbutils-372)
> [16351.880581] ACPI: DSDT (null) 0979E (v02 ALASKA A M I 00000000 INTL 20051117)
> [16351.880587] ACPI: DSDT (null) 0979E (v02 ALASKA A M I 00000000 INTL 20051117)
> [16351.880591] ACPI Error: Please send DMI info to linux-acpi@xxxxxxxxxxxxxxx
> [16351.880593] If system does not work as expected, please boot with acpi=copy_dsdt (20110623/tbutils-378)
> [16351.880813] CPU1 is up
> [16351.881299] lockdep: fixing up alternatives.
> [16351.881306] Booting Node 0 Processor 2 APIC 0x4
> [16351.881308] smpboot cpu 2: start_ip = 98000
> [16351.892429] Calibrating delay loop (skipped) already calibrated this CPU
> [16351.892483] kvm: enabling virtualization on CPU2
> [16351.913836] NMI watchdog enabled, takes one hw-pmu counter.
> [16351.914377] CPU2 is up
> [16351.914879] lockdep: fixing up alternatives.
> [16351.914886] Booting Node 0 Processor 3 APIC 0x6
> [16351.914888] smpboot cpu 3: start_ip = 98000
> [16351.925906] Calibrating delay loop (skipped) already calibrated this CPU
> [16351.925960] kvm: enabling virtualization on CPU3
> [16351.947612] NMI watchdog enabled, takes one hw-pmu counter.
> [16351.948132] CPU3 is up
> [16351.962797] snd_hda_intel 0000:00:1b.0: BAR 0: set to [mem 0xfe600000-0xfe603fff 64bit] (PCI address [0xfe600000-0xfe603fff])
> [16351.962826] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0xf (was 0x100, writing 0x103)
> [16351.962859] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x10)
> [16351.962869] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100002)
> [16351.963770] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00403, writing 0x2b00407)
> [16351.963976] xhci_hcd 0000:05:00.0: restoring config space at offset 0x1 (was 0x100006, writing 0x100406)
> [16351.964885] PM: early restore of devices complete after 13.340 msecs
> [16352.028856] i915 0000:00:02.0: setting latency timer to 64
> [16352.028865] ehci_hcd 0000:00:1a.0: setting latency timer to 64
> [16352.028889] snd_hda_intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
> [16352.028899] snd_hda_intel 0000:00:1b.0: setting latency timer to 64
> [16352.028915] usb usb3: root hub lost power or was reset
> [16352.028976] snd_hda_intel 0000:00:1b.0: irq 48 for MSI/MSI-X
> [16352.029081] pci 0000:00:1c.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19
> [16352.029088] pci 0000:00:1c.7: setting latency timer to 64
> [16352.029103] ehci_hcd 0000:00:1d.0: setting latency timer to 64
> [16352.029144] usb usb4: root hub lost power or was reset
> [16352.029220] ahci 0000:00:1f.2: setting latency timer to 64
> [16352.029266] xhci_hcd 0000:05:00.0: setting latency timer to 64
> [16352.029272] usb usb1: root hub lost power or was reset
> [16352.029274] usb usb2: root hub lost power or was reset
> [16352.032801] ehci_hcd 0000:00:1a.0: cache line size of 64 is not supported
> [16352.032884] pci 0000:07:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
> [16352.032894] pci 0000:07:00.0: setting latency timer to 64
> [16352.032984] sd 0:0:0:0: [sda] Starting disk
> [16352.033034] ehci_hcd 0000:00:1d.0: cache line size of 64 is not supported
> [16352.039526] xhci_hcd 0000:05:00.0: irq 49 for MSI/MSI-X
> [16352.039535] xhci_hcd 0000:05:00.0: irq 50 for MSI/MSI-X
> [16352.039543] xhci_hcd 0000:05:00.0: irq 51 for MSI/MSI-X
> [16352.039551] xhci_hcd 0000:05:00.0: irq 52 for MSI/MSI-X
> [16352.039559] xhci_hcd 0000:05:00.0: irq 53 for MSI/MSI-X
> [16352.040200] serial 00:08: activated
> [16352.040233] i8042 kbd 00:09: wake-up capability disabled by ACPI
> [16352.136554] atl1c 0000:06:00.0: irq 54 for MSI/MSI-X
> [16352.352536] ata3: SATA link down (SStatus 0 SControl 310)
> [16352.359222] ata4: SATA link down (SStatus 0 SControl 300)
> [16352.365859] ata2: SATA link down (SStatus 0 SControl 300)
> [16352.372528] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
> [16352.374572] ata1.00: configured for UDMA/133
> [16352.379190] ata5: SATA link down (SStatus 0 SControl 300)
> [16352.379212] usb 4-1: reset high-speed USB device number 2 using ehci_hcd
> [16352.385884] ata6: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
> [16352.388473] ata6.00: configured for UDMA/100
> [16352.609124] usb 3-1: reset high-speed USB device number 2 using ehci_hcd
> [16352.989022] usb 4-1.5: reset low-speed USB device number 3 using ehci_hcd
> [16353.265737] PM: restore of devices complete after 1237.652 msecs
> [16353.270435] Restarting tasks ...
> [16353.270612] BUG: spinlock bad magic on CPU#2, dhclient3/2590
> [16353.274702] lock: ffff880127b9ce08, .magic: 00000000, .owner: dhclient3/2590, .owner_cpu: 2
> [16353.276594] Pid: 2590, comm: dhclient3 Not tainted 3.2.1 #2
> [16353.278294] done.
> [16353.278314] video LNXVIDEO:00: Restoring backlight state
> [16353.281627] Call Trace:
> [16353.281633] [<ffffffff81552515>] spin_dump+0x8a/0x8f
> [16353.281636] [<ffffffff81552540>] spin_bug+0x26/0x28
> [16353.281639] [<ffffffff81285af8>] do_raw_spin_unlock+0x1e/0x83
> [16353.281643] [<ffffffff8155912e>] _raw_spin_unlock_irq+0x28/0x5b
> [16353.281647] [<ffffffff8105e427>] get_signal_to_deliver+0x546/0x57c
> [16353.281651] [<ffffffff81001572>] do_signal+0x3e/0x62f
> [16353.281654] [<ffffffff815595b8>] ? retint_restore_args+0x13/0x13
> [16353.281657] [<ffffffff81280079>] ? copy_user_generic_string+0x19/0x40
> [16353.281661] [<ffffffff81142e8c>] ? poll_select_copy_remaining+0xc5/0xe9
> [16353.281664] [<ffffffff81001ba8>] do_notify_resume+0x2c/0x53
> [16353.281666] [<ffffffff8128137e>] ? trace_hardirqs_on_thunk+0x3a/0x3f
> [16353.281669] [<ffffffff8155a1d6>] int_signal+0x12/0x17
> [16353.472289] tsc = fffffd60248a9f39 last_guest_tsc = 229a823d4b88
> [ 0.006666] Marking TSC unstable due to KVM discovered backwards TSC
> [16362.155512] Switching to clocksource hpet
> [16363.617844] atl1c 0000:06:00.0: atl1c: eth0 NIC Link is Up<1000 Mbps Full Duplex>
> [16363.618145] br0: port 1(eth0) entering forwarding state
> [16363.618153] br0: port 1(eth0) entering forwarding state

Are you suspending your host while a VM is running? There is most
probably a long-pending bug in the KVM TSC check logic that declares
that clocksource unstable after resume. I'm seeing this here for ages
and complained about it from the beginning (but I never debugged it :-/).

Of course, this doesn't explain your corrupted spin lock. That might be
a different issue.

Jan

--
Siemens AG, Corporate Technology, CT T DE IT 1
Corporate Competence Center Embedded Linux
--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
Please read the FAQ at http://www.tux.org/lkml/

Next message: Kirill A. Shutemov: "Re: [67/90] intel idle: Make idle driver more robust"
Previous message: Sasha Levin: "Re: [PATCH] netns: fix net_alloc_generic()"
In reply to: Johannes Stezenbach: "Re: linux-3.2.1 + KVM: TSC unstable on Core i5-2400S / H67"
Next in thread: Johannes Stezenbach: "Re: linux-3.2.1 + KVM: TSC unstable on Core i5-2400S / H67"
Messages sorted by: [ date ] [ thread ] [ subject ] [ author ]