* linux-3.2.1 + KVM: TSC unstable on Core i5-2400S / H67 @ 2012-01-24 11:36 Johannes Stezenbach 2012-01-25 12:27 ` Marcelo Tosatti 0 siblings, 1 reply; 7+ messages in thread From: Johannes Stezenbach @ 2012-01-24 11:36 UTC (permalink / raw) To: kvm; +Cc: linux-kernel Hi, I just got the following in dmesg with linux-3.2.1: [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 The mainboard is an Asus P8H67-V, BIOS 0806 10/26/2011 with Core i5-2400S. The kvm instance was running for a day already, and there was an overnight suspend-to-disk cycle in between, but the above happened ~1 hour after resume. (I don't think the usb message has anything to do with it, it's just the last message before the TSC message.) Hm, checking /var/log/kern.* it seems this also happened with linux-3.1.* without me noticing. Should I be worried? I thought Core-i5 processors have a stable TSC? Johannes ^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: linux-3.2.1 + KVM: TSC unstable on Core i5-2400S / H67 2012-01-24 11:36 linux-3.2.1 + KVM: TSC unstable on Core i5-2400S / H67 Johannes Stezenbach @ 2012-01-25 12:27 ` Marcelo Tosatti 2012-01-25 13:25 ` Johannes Stezenbach 0 siblings, 1 reply; 7+ messages in thread From: Marcelo Tosatti @ 2012-01-25 12:27 UTC (permalink / raw) To: Johannes Stezenbach; +Cc: kvm, linux-kernel On Tue, Jan 24, 2012 at 12:36:08PM +0100, Johannes Stezenbach wrote: > Hi, > > I just got the following in dmesg with linux-3.2.1: > > [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 > > The mainboard is an Asus P8H67-V, BIOS 0806 10/26/2011 with Core i5-2400S. > The kvm instance was running for a day already, and there was an overnight > suspend-to-disk cycle in between, but the above happened ~1 hour after resume. > (I don't think the usb message has anything to do with it, it's just > the last message before the TSC message.) > > Hm, checking /var/log/kern.* it seems this also happened with > linux-3.1.* without me noticing. > > Should I be worried? I thought Core-i5 processors have a stable TSC? It does. Can you please apply the following patch, and report back when it reproduces again. diff --git a/arch/x86/kvm/x86.c b/arch/x86/kvm/x86.c index 9c912f0b..17d706d 100644 --- a/arch/x86/kvm/x86.c +++ b/arch/x86/kvm/x86.c @@ -2242,8 +2242,11 @@ void kvm_arch_vcpu_load(struct kvm_vcpu *vcpu, int cpu) tsc_delta = !vcpu->arch.last_guest_tsc ? 0 : tsc - vcpu->arch.last_guest_tsc; - if (tsc_delta < 0) + if (tsc_delta < 0) { + printk(KERN_ERR "tsc = %llx last_guest_tsc = %llx\n", + tsc, vcpu->arch.last_guest_tsc); mark_tsc_unstable("KVM discovered backwards TSC"); + } if (check_tsc_unstable()) { kvm_x86_ops->adjust_tsc_offset(vcpu, -tsc_delta); vcpu->arch.tsc_catchup = 1; ^ permalink raw reply related [flat|nested] 7+ messages in thread
* Re: linux-3.2.1 + KVM: TSC unstable on Core i5-2400S / H67 2012-01-25 12:27 ` Marcelo Tosatti @ 2012-01-25 13:25 ` Johannes Stezenbach 2012-01-26 9:46 ` Johannes Stezenbach 0 siblings, 1 reply; 7+ messages in thread From: Johannes Stezenbach @ 2012-01-25 13:25 UTC (permalink / raw) To: Marcelo Tosatti; +Cc: kvm, linux-kernel 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. Thanks Johannes ^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: linux-3.2.1 + KVM: TSC unstable on Core i5-2400S / H67 2012-01-25 13:25 ` Johannes Stezenbach @ 2012-01-26 9:46 ` Johannes Stezenbach 2012-01-26 14:43 ` Jan Kiszka 0 siblings, 1 reply; 7+ messages in thread From: Johannes Stezenbach @ 2012-01-26 9:46 UTC (permalink / raw) To: Marcelo Tosatti; +Cc: kvm, linux-kernel 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@monad.swb.de). [ 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@qualcomm.com> [ 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@redhat.com [ 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@vger.kernel.org [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 ^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: linux-3.2.1 + KVM: TSC unstable on Core i5-2400S / H67 2012-01-26 9:46 ` Johannes Stezenbach @ 2012-01-26 14:43 ` Jan Kiszka 2012-01-26 15:35 ` Johannes Stezenbach 0 siblings, 1 reply; 7+ messages in thread From: Jan Kiszka @ 2012-01-26 14:43 UTC (permalink / raw) To: Johannes Stezenbach; +Cc: Marcelo Tosatti, kvm, linux-kernel 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@monad.swb.de). > [ 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@qualcomm.com> > [ 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@redhat.com > [ 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@vger.kernel.org > [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 ^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: linux-3.2.1 + KVM: TSC unstable on Core i5-2400S / H67 2012-01-26 14:43 ` Jan Kiszka @ 2012-01-26 15:35 ` Johannes Stezenbach 2012-01-26 16:48 ` Marcelo Tosatti 0 siblings, 1 reply; 7+ messages in thread From: Johannes Stezenbach @ 2012-01-26 15:35 UTC (permalink / raw) To: Jan Kiszka; +Cc: Marcelo Tosatti, kvm, linux-kernel On Thu, Jan 26, 2012 at 03:43:27PM +0100, Jan Kiszka wrote: > On 2012-01-26 10:46, Johannes Stezenbach wrote: > > [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 mosto Yes, that's what I'm doing. > 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 :-/). Hm, OK. I hope someone has an idea how to fix it. I'm ready to test patches. > Of course, this doesn't explain your corrupted spin lock. That might be > a different issue. Yes, most probably it is something different since I haven't seen this before. However, I didn't use 3.2.1 long. Now I updated to 3.2.2 and enabled a few more debug options. We'll see if it triggers again. Thanks Johannes ^ permalink raw reply [flat|nested] 7+ messages in thread
* Re: linux-3.2.1 + KVM: TSC unstable on Core i5-2400S / H67 2012-01-26 15:35 ` Johannes Stezenbach @ 2012-01-26 16:48 ` Marcelo Tosatti 0 siblings, 0 replies; 7+ messages in thread From: Marcelo Tosatti @ 2012-01-26 16:48 UTC (permalink / raw) To: Johannes Stezenbach; +Cc: Jan Kiszka, kvm, linux-kernel On Thu, Jan 26, 2012 at 04:35:13PM +0100, Johannes Stezenbach wrote: > On Thu, Jan 26, 2012 at 03:43:27PM +0100, Jan Kiszka wrote: > > On 2012-01-26 10:46, Johannes Stezenbach wrote: > > > [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 mosto > > Yes, that's what I'm doing. > > > 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 :-/). > > Hm, OK. I hope someone has an idea how to fix it. I'm ready to test patches. > > > Of course, this doesn't explain your corrupted spin lock. That might be > > a different issue. > > Yes, most probably it is something different since I haven't seen this > before. However, I didn't use 3.2.1 long. Now I updated > to 3.2.2 and enabled a few more debug options. We'll see if it > triggers again. There is a fix available: http://lists.openwall.net/linux-kernel/2011/06/21/5 It should be integrated. ^ permalink raw reply [flat|nested] 7+ messages in thread
end of thread, other threads:[~2012-01-26 16:48 UTC | newest] Thread overview: 7+ messages (download: mbox.gz follow: Atom feed -- links below jump to the message on this page -- 2012-01-24 11:36 linux-3.2.1 + KVM: TSC unstable on Core i5-2400S / H67 Johannes Stezenbach 2012-01-25 12:27 ` Marcelo Tosatti 2012-01-25 13:25 ` Johannes Stezenbach 2012-01-26 9:46 ` Johannes Stezenbach 2012-01-26 14:43 ` Jan Kiszka 2012-01-26 15:35 ` Johannes Stezenbach 2012-01-26 16:48 ` Marcelo Tosatti
This is a public inbox, see mirroring instructions for how to clone and mirror all data and code used for this inbox